0 Replies Latest reply on Jul 18, 2011 8:46 AM by arogerio

Running application on web start

arogerio Jul 18, 2011 8:46 AM

Hi guys!!! I'm from Brazil.

I'm developing a web start desktop application with hibernate, spring and mysql.

when I run local (without jboss), everything works fine, I run the queries in the database normally.

when I deploy in jboss (jboss-5.1.0.GA) and run the webstart, the queries in database returns the following error:

org.hibernate.hql.ast.QuerySyntaxException: Empresa is not mapped [ select distinct e from Empresa e left join fetch e.ambientes a where 1=1 order by e.nome ]

I'm trying to figure out the problem to a long time, but without success. I do not know what to do.

Can someone help me, please ?

I thank everyone in advance

**********************************************************************************

<<server.log>>

2011-07-18 09:29:42,885 INFO [org.jboss.web.WebService] (main) Using RMI server codebase: http://mi162831:8083/

2011-07-18 09:29:47,135 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) JBoss Web Services - Stack Native Core

2011-07-18 09:29:47,135 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) 3.1.2.GA

2011-07-18 09:29:47,479 INFO [org.jboss.dependency.plugins.AttributeCallbackItem] (main) Owner callback not implemented.

2011-07-18 09:29:48,244 INFO [org.jboss.logbridge.LogNotificationListener] (main) Adding notification listener for logging mbean "jboss.system:service=Logging,type=Log4jService" to server org.jboss.mx.server.MBeanServerImpl@bd4e3c[ defaultDomain='jboss' ]

2011-07-18 09:30:11,417 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] (main) Encountered deployment AbstractVFSDeploymentContext@16957111{vfsfile:/C:/jboss-5.1.0.GA/server/default/deploy/profileservice-secured.jar/}

2011-07-18 09:30:13,448 INFO [org.jboss.mx.remoting.service.JMXConnectorServerService] (main) JMX Connector server: service:jmx:rmi://mi162831/jndi/rmi://mi162831:1090/jmxconnector

2011-07-18 09:30:13,557 INFO [org.jboss.mail.MailService] (main) Mail Service bound to java:/Mail

2011-07-18 09:30:15,917 WARN [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (main) 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.

2011-07-18 09:30:15,917 WARN [org.jboss.annotation.factory.AnnotationCreator] (main) No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent

2011-07-18 09:30:15,979 WARN [org.jboss.annotation.factory.AnnotationCreator] (main) No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent

2011-07-18 09:30:16,026 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) JBossTS Transaction Service (JTA version - tag:JBOSSTS_4_6_1_GA) - JBoss Inc.

2011-07-18 09:30:16,026 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Setting up property manager MBean and JMX layer

2011-07-18 09:30:16,198 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Initializing recovery manager

2011-07-18 09:30:16,292 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Recovery manager configured

2011-07-18 09:30:16,292 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Binding TransactionManager JNDI Reference

2011-07-18 09:30:16,308 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Starting transaction recovery manager

2011-07-18 09:30:16,589 INFO [org.apache.catalina.core.AprLifecycleListener] (main) The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: J:\jdk1.6.0_20\bin;.;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\oraclexe\app\oracle\product\10.2.0\server\bin;C:\Inprise\vbroker\bin;D:\oracle\product\10.2.0\client_1\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Arquivos de programas\Arquivos comuns\Autodesk Shared\;C:\Arquivos de programas\Autodesk\DWG TrueView\;C:\Arquivos de programas\Rational\common;C:\Arquivos de programas\Rational\ClearCase\bin;C:\ARQUIV~1\Borland\Delphi6\Bin;C:\ARQUIV~1\Borland\Delphi6\Projects\Bpl;C:\Arquivos de programas\Java\jdk1.5.0_06\bin;C:\apache-ant-1.7.1\bin;Z:\GUPTA2005;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\apache-maven-2.2.1\bin;C:\Arquivos de programas\Borland\Delphi6\Bin;C:\Arquivos de programas\Borland\Delphi6\Lib;C:\Arquivos de programas\Windows Imaging\;J:\apache-maven-2.2.1\bin;J:\jdk1.6.0_20\bin

2011-07-18 09:30:16,636 INFO [org.apache.coyote.http11.Http11Protocol] (main) Initializing Coyote HTTP/1.1 on http-0.0.0.0-8080

2011-07-18 09:30:16,636 INFO [org.apache.coyote.ajp.AjpProtocol] (main) Initializing Coyote AJP/1.3 on ajp-0.0.0.0-8009

2011-07-18 09:30:16,651 INFO [org.apache.catalina.core.StandardService] (main) Starting service jboss.web

2011-07-18 09:30:16,651 INFO [org.apache.catalina.core.StandardEngine] (main) Starting Servlet Engine: JBoss Web/2.1.3.GA

2011-07-18 09:30:16,683 INFO [org.apache.catalina.startup.Catalina] (main) Server startup in 50 ms

2011-07-18 09:30:16,698 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jbossws

2011-07-18 09:30:17,011 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/invoker

2011-07-18 09:30:17,058 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/web-console

2011-07-18 09:30:17,308 INFO [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml

2011-07-18 09:30:17,308 INFO [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml

2011-07-18 09:30:17,323 INFO [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/jms-ra.rar/META-INF/ra.xml

2011-07-18 09:30:17,339 INFO [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/mail-ra.rar/META-INF/ra.xml

2011-07-18 09:30:17,354 INFO [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/quartz-ra.rar/META-INF/ra.xml

2011-07-18 09:30:17,401 INFO [org.quartz.simpl.SimpleThreadPool] (main) Job execution threads will use class loader of thread: main

2011-07-18 09:30:17,479 INFO [org.quartz.core.QuartzScheduler] (main) Quartz Scheduler v.1.5.2 created.

2011-07-18 09:30:17,479 INFO [org.quartz.simpl.RAMJobStore] (main) RAMJobStore initialized.

2011-07-18 09:30:17,479 INFO [org.quartz.impl.StdSchedulerFactory] (main) Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'

2011-07-18 09:30:17,479 INFO [org.quartz.impl.StdSchedulerFactory] (main) Quartz scheduler version: 1.5.2

2011-07-18 09:30:17,479 INFO [org.quartz.core.QuartzScheduler] (main) Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.

2011-07-18 09:30:17,573 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=MySqlCADS' to JNDI name 'java:MySqlCADS'

2011-07-18 09:30:17,886 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'

2011-07-18 09:30:18,245 INFO [org.jboss.jms.server.ServerPeer] (main) JBoss Messaging 1.4.3.GA server [0] started

2011-07-18 09:30:18,339 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://mi162831:4457 has leasing enabled, lease period 10000 milliseconds

2011-07-18 09:30:18,339 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@1ef6570 started

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.destination.QueueService] (main) Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.destination.QueueService] (main) Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://mi162831:4457 has leasing enabled, lease period 10000 milliseconds

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@1f28c started

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactoryJNDIMapper] (main) 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

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactoryJNDIMapper] (main) 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

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://mi162831:4457 has leasing enabled, lease period 10000 milliseconds

2011-07-18 09:30:18,354 INFO [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@865ba9 started

2011-07-18 09:30:18,542 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'

2011-07-18 09:30:18,886 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Created KernelDeployment for: profileservice-secured.jar

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:org.jboss.profileservice.spi.ProfileService

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureProfileService/remote

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureProfileService/remote-org.jboss.profileservice.spi.ProfileService

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3) to KernelDeployment of: profileservice-secured.jar

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureDeploymentManager/remote-org.jboss.deployers.spi.management.deploy.DeploymentManager

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:org.jboss.deployers.spi.management.deploy.DeploymentManager

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureDeploymentManager/remote

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3) to KernelDeployment of: profileservice-secured.jar

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:org.jboss.deployers.spi.management.ManagementView

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:SecureManagementView/remote

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3) to KernelDeployment of: profileservice-secured.jar

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.endpoint.deployers.EJB3EndpointDeployer] (main) Deploy AbstractBeanMetaData@1c514a7{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.endpoint.deployers.EJB3EndpointDeployer] (main) Deploy AbstractBeanMetaData@19ec220{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}

2011-07-18 09:30:18,901 INFO [org.jboss.ejb3.endpoint.deployers.EJB3EndpointDeployer] (main) Deploy AbstractBeanMetaData@1fa3d95{name=jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3_endpoint bean=org.jboss.ejb3.endpoint.deployers.impl.EndpointImpl properties=[container] constructor=null autowireCandidate=true}

2011-07-18 09:30:18,964 INFO [org.jboss.ejb3.session.SessionSpecContainer] (main) Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureDeploymentManager,service=EJB3

2011-07-18 09:30:18,964 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.profileservice.ejb.SecureDeploymentManager ejbName: SecureDeploymentManager

2011-07-18 09:30:19,011 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] (main) Binding the following Entries in Global JNDI:

SecureDeploymentManager/remote - EJB3.x Default Remote Business Interface

SecureDeploymentManager/remote-org.jboss.deployers.spi.management.deploy.DeploymentManager - EJB3.x Remote Business Interface

2011-07-18 09:30:19,058 INFO [org.jboss.ejb3.session.SessionSpecContainer] (main) Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureManagementView,service=EJB3

2011-07-18 09:30:19,058 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.profileservice.ejb.SecureManagementView ejbName: SecureManagementView

2011-07-18 09:30:19,073 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] (main) Binding the following Entries in Global JNDI:

SecureManagementView/remote - EJB3.x Default Remote Business Interface

SecureManagementView/remote-org.jboss.deployers.spi.management.ManagementView - EJB3.x Remote Business Interface

2011-07-18 09:30:19,089 INFO [org.jboss.ejb3.session.SessionSpecContainer] (main) Starting jboss.j2ee:jar=profileservice-secured.jar,name=SecureProfileService,service=EJB3

2011-07-18 09:30:19,104 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.profileservice.ejb.SecureProfileServiceBean ejbName: SecureProfileService

2011-07-18 09:30:19,104 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] (main) Binding the following Entries in Global JNDI:

SecureProfileService/remote - EJB3.x Default Remote Business Interface

SecureProfileService/remote-org.jboss.profileservice.spi.ProfileService - EJB3.x Remote Business Interface

2011-07-18 09:30:19,198 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/admin-console

2011-07-18 09:30:19,261 INFO [javax.enterprise.resource.webcontainer.jsf.config] (main) Initializing Mojarra (1.2_12-b01-FCS) for context '/admin-console'

2011-07-18 09:30:21,198 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/

2011-07-18 09:30:21,308 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jmx-console

2011-07-18 09:30:41,902 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/kitca

2011-07-18 09:30:42,105 ERROR [STDERR] (main) SLF4J: Class path contains multiple SLF4J bindings.

2011-07-18 09:30:42,105 ERROR [STDERR] (main) SLF4J: Found binding in [vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/kitca.war/WEB-INF/lib/slf4j-log4j12-1.6.1.jar/org/slf4j/impl/StaticLoggerBinder.class]

2011-07-18 09:30:42,105 ERROR [STDERR] (main) SLF4J: Found binding in [vfszip:/C:/jboss-5.1.0.GA/common/lib/slf4j-jboss-logging.jar/org/slf4j/impl/StaticLoggerBinder.class]

2011-07-18 09:30:42,105 ERROR [STDERR] (main) SLF4J: Found binding in [vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/kitca.war/WEB-INF/lib/logback-classic-0.9.24.jar/org/slf4j/impl/StaticLoggerBinder.class]

2011-07-18 09:30:42,105 ERROR [STDERR] (main) SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.

2011-07-18 09:30:42,199 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/kitca]] (main) Initializing Spring root WebApplicationContext

2011-07-18 09:30:42,199 INFO [STDOUT] (main) 09:30:42.199 [main] INFO o.s.web.context.ContextLoader - Root WebApplicationContext: initialization started

2011-07-18 09:30:42,230 INFO [STDOUT] (main) 09:30:42.230 [main] INFO o.s.w.c.s.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@79e5e7: display name [Root WebApplicationContext]; startup date [Mon Jul 18 09:30:42 GMT-03:00 2011]; root of context hierarchy

2011-07-18 09:30:42,277 INFO [STDOUT] (main) 09:30:42.277 [main] INFO o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/resources/applicationContext.xml]

2011-07-18 09:30:42,277 INFO [STDOUT] (main) 09:30:42.277 [main] DEBUG o.s.b.f.xml.DefaultDocumentLoader - Using JAXP provider [org.apache.xerces.jaxp.DocumentBuilderFactoryImpl]

2011-07-18 09:30:42,277 INFO [STDOUT] (main) 09:30:42.277 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Loading schema mappings from [META-INF/spring.schemas]

2011-07-18 09:30:42,277 INFO [STDOUT] (main) 09:30:42.277 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/beans/spring-beans-2.0.xsd] in classpath: org/springframework/beans/factory/xml/spring-beans-2.0.xsd

2011-07-18 09:30:42,292 INFO [STDOUT] (main) 09:30:42.292 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/tx/spring-tx-2.0.xsd] in classpath: org/springframework/transaction/config/spring-tx-2.0.xsd

2011-07-18 09:30:42,292 INFO [STDOUT] (main) 09:30:42.292 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/aop/spring-aop-2.0.xsd] in classpath: org/springframework/aop/config/spring-aop-2.0.xsd

2011-07-18 09:30:42,292 INFO [STDOUT] (main) 09:30:42.292 [main] DEBUG o.s.b.f.x.DefaultBeanDefinitionDocumentReader - Loading bean definitions

2011-07-18 09:30:42,324 INFO [STDOUT] (main) 09:30:42.324 [main] DEBUG o.s.b.f.x.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, http://www.springframework.org/schema/aop=org.springframework.aop.config.AopNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/tx=org.springframework.transaction.config.TxNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler}]

2011-07-18 09:30:42,386 INFO [STDOUT] (main) 09:30:42.386 [main] DEBUG o.s.b.f.x.BeanDefinitionParserDelegate - Neither XML 'id' nor 'name' specified - using generated bean name [br.com.petrobras.framework.module.i18n.SpringLocaleStore#16352a5]

2011-07-18 09:30:42,386 INFO [STDOUT] (main) 09:30:42.386 [main] DEBUG o.s.b.f.xml.XmlBeanDefinitionReader - Loaded 25 bean definitions from location pattern [/WEB-INF/resources/applicationContext.xml]

2011-07-18 09:30:42,386 INFO [STDOUT] (main) 09:30:42.386 [main] INFO o.s.w.c.s.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@79e5e7]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1d3e355

2011-07-18 09:30:42,386 INFO [STDOUT] (main) 09:30:42.386 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - 25 beans defined in org.springframework.web.context.support.XmlWebApplicationContext@79e5e7: display name [Root WebApplicationContext]; startup date [Mon Jul 18 09:30:42 GMT-03:00 2011]; root of context hierarchy

2011-07-18 09:30:42,433 INFO [STDOUT] (main) 09:30:42.433 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'propertyConfigurer1'

2011-07-18 09:30:42,433 INFO [STDOUT] (main) 09:30:42.433 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'propertyConfigurer1'

2011-07-18 09:30:42,449 INFO [STDOUT] (main) 09:30:42.449 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'propertyConfigurer1' to allow for resolving potential circular references

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'propertyConfigurer1'

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'propertyConfigurer2'

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'propertyConfigurer2'

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'propertyConfigurer2' to allow for resolving potential circular references

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'propertyConfigurer2'

2011-07-18 09:30:42,464 INFO [STDOUT] (main) 09:30:42.464 [main] INFO o.s.b.f.c.PropertyPlaceholderConfigurer - Loading properties file from class path resource [kitca/config.properties]

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] INFO o.s.b.f.c.PropertyPlaceholderConfigurer - Loading properties file from class path resource [kitca/kitca.properties]

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] INFO o.s.b.f.c.PropertyPlaceholderConfigurer - Loading properties file from class path resource [kitca/acessocorp.properties]

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.aop.config.internalAutoProxyCreator'

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.aop.config.internalAutoProxyCreator'

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.aop.config.internalAutoProxyCreator' to allow for resolving potential circular references

2011-07-18 09:30:42,480 INFO [STDOUT] (main) 09:30:42.480 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.aop.config.internalAutoProxyCreator'

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@7cbb68]

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@1f42872]

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.u.c.s.UiApplicationContextUtils - Unable to locate ThemeSource with name 'themeSource': using default [org.springframework.ui.context.support.ResourceBundleThemeSource@ed489f]

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] INFO o.s.b.f.s.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1d3e355: defining beans [log4jInitialization,propertyConfigurer1,propertyConfigurer2,dataSource,sessionFactory,transactionManager,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,dao,securityServiceFactory,scopedTarget.dadosAutenticacao,dadosAutenticacao,i18nConfigurer,locale,baseService,acessoService,empresaService,ambienteService,sistemaService,securityInterceptor,exceptionInterceptor,delegationInterceptor,kitcaService]; root of factory hierarchy

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'log4jInitialization'

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'log4jInitialization'

2011-07-18 09:30:42,496 INFO [STDOUT] (main) 09:30:42.496 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'log4jInitialization' to allow for resolving potential circular references

2011-07-18 09:30:42,511 INFO [STDOUT] (main) 09:30:42.511 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'log4jInitialization'

2011-07-18 09:30:42,527 ERROR [STDERR] (main) log4j:ERROR A "org.jboss.logging.appender.FileAppender" object is not assignable to a "org.apache.log4j.Appender" variable.

2011-07-18 09:30:42,527 ERROR [STDERR] (main) log4j:ERROR The class "org.apache.log4j.Appender" was loaded by

2011-07-18 09:30:42,527 ERROR [STDERR] (main) log4j:ERROR [BaseClassLoader@1eac903{vfszip:/C:/jboss-5.1.0.GA/server/default/deploy/kitca.war/}] whereas object of type

2011-07-18 09:30:42,527 ERROR [STDERR] (main) log4j:ERROR "org.jboss.logging.appender.FileAppender" was loaded by [org.jboss.bootstrap.NoAnnotationURLClassLoader@b166b5].

2011-07-18 09:30:42,527 ERROR [STDERR] (main) log4j:ERROR Could not instantiate appender named "FILE".

2011-07-18 09:30:42,558 INFO [STDOUT] (main) 09:30:42.558 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:42,558 INFO [STDOUT] (main) 09:30:42.558 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:42,558 INFO [STDOUT] (main) 09:30:42.558 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.config.internalTransactionAdvisor' to allow for resolving potential circular references

2011-07-18 09:30:42,558 INFO [STDOUT] (main) 09:30:42.558 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0' to allow for resolving potential circular references

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'log4jInitialization'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'propertyConfigurer1'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'propertyConfigurer2'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'dataSource'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'dataSource'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'dataSource' to allow for resolving potential circular references

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'dataSource'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.springframework.jndi.JndiTemplate - Looking up JNDI object with name [java:/MySqlCADS]

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.jndi.JndiObjectFactoryBean - Located object with JNDI name [java:/MySqlCADS]

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'dataSource'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'sessionFactory'

2011-07-18 09:30:42,574 INFO [STDOUT] (main) 09:30:42.574 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'sessionFactory'

2011-07-18 09:30:42,605 INFO [STDOUT] (main) 09:30:42.605 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'sessionFactory' to allow for resolving potential circular references

2011-07-18 09:30:42,621 INFO [STDOUT] (main) 09:30:42.621 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dataSource'

2011-07-18 09:30:42,621 INFO [STDOUT] (main) 09:30:42.621 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:42,621 INFO [STDOUT] (main) 09:30:42.621 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'sessionFactory'

2011-07-18 09:30:42,636 INFO [org.hibernate.cfg.annotations.Version] (main) Hibernate Annotations 3.4.0.GA

2011-07-18 09:30:42,652 INFO [org.hibernate.cfg.Environment] (main) Hibernate 3.3.1.GA

2011-07-18 09:30:42,652 INFO [org.hibernate.cfg.Environment] (main) hibernate.properties not found

2011-07-18 09:30:42,652 INFO [org.hibernate.cfg.Environment] (main) Bytecode provider name : javassist

2011-07-18 09:30:42,652 INFO [org.hibernate.cfg.Environment] (main) using JDK 1.4 java.sql.Timestamp handling

2011-07-18 09:30:43,371 INFO [org.hibernate.annotations.common.Version] (main) Hibernate Commons Annotations 3.1.0.GA

2011-07-18 09:30:43,433 INFO [STDOUT] (main) 09:30:43.433 [main] INFO o.s.o.h.a.AnnotationSessionFactoryBean - Building new Hibernate SessionFactory

2011-07-18 09:30:43,433 INFO [org.hibernate.cfg.search.HibernateSearchEventListenerRegister] (main) Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.

2011-07-18 09:30:43,433 INFO [org.hibernate.connection.ConnectionProviderFactory] (main) Initializing connection provider: org.springframework.orm.hibernate3.LocalDataSourceConnectionProvider

2011-07-18 09:30:43,652 INFO [org.hibernate.cfg.SettingsFactory] (main) RDBMS: MySQL, version: 5.5.14

2011-07-18 09:30:43,652 INFO [org.hibernate.cfg.SettingsFactory] (main) JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.1.16 ( Revision: ${bzr.revision-id} )

2011-07-18 09:30:43,667 INFO [org.hibernate.dialect.Dialect] (main) Using dialect: org.hibernate.dialect.MySQLDialect

2011-07-18 09:30:43,714 INFO [org.hibernate.transaction.TransactionFactoryFactory] (main) Transaction strategy: org.springframework.orm.hibernate3.SpringTransactionFactory

2011-07-18 09:30:43,714 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] (main) No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Automatic flush during beforeCompletion(): disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Automatic session close at end of transaction: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) JDBC batch size: 15

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) JDBC batch updates for versioned data: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Scrollable result sets: enabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) JDBC3 getGeneratedKeys(): enabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Connection release mode: auto

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Maximum outer join fetch depth: 1

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Default batch fetch size: 1

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Generate SQL with comments: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Order SQL updates by primary key: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Order SQL inserts for batching: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory

2011-07-18 09:30:43,714 INFO [org.hibernate.hql.ast.ASTQueryTranslatorFactory] (main) Using ASTQueryTranslatorFactory

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Query language substitutions: {}

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) JPA-QL strict compliance: disabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Second-level cache: enabled

2011-07-18 09:30:43,714 INFO [org.hibernate.cfg.SettingsFactory] (main) Query cache: disabled

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge

2011-07-18 09:30:43,730 INFO [org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge] (main) Cache provider: org.hibernate.cache.NoCacheProvider

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Optimize cache for minimal puts: disabled

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Structured second-level cache entries: disabled

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Statistics: disabled

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Deleted entity synthetic identifier rollback: disabled

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Default entity-mode: pojo

2011-07-18 09:30:43,730 INFO [org.hibernate.cfg.SettingsFactory] (main) Named query checking : enabled

2011-07-18 09:30:43,777 INFO [org.hibernate.impl.SessionFactoryImpl] (main) building session factory

2011-07-18 09:30:43,777 INFO [org.hibernate.impl.SessionFactoryObjectFactory] (main) Not binding factory to JNDI, no JNDI name configured

2011-07-18 09:30:43,777 INFO [STDOUT] (main) 09:30:43.777 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'sessionFactory'

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'transactionManager'

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'transactionManager'

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'transactionManager' to allow for resolving potential circular references

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sessionFactory'

2011-07-18 09:30:43,792 INFO [STDOUT] (main) 09:30:43.792 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:43,808 INFO [STDOUT] (main) 09:30:43.808 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'transactionManager'

2011-07-18 09:30:43,808 INFO [STDOUT] (main) 09:30:43.808 [main] INFO o.s.o.h.HibernateTransactionManager - Using DataSource [org.jboss.resource.adapter.jdbc.WrapperDataSource@c71fda] of Hibernate SessionFactory for HibernateTransactionManager

2011-07-18 09:30:43,808 INFO [STDOUT] (main) 09:30:43.808 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:43,808 INFO [STDOUT] (main) 09:30:43.808 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'transactionManager'

2011-07-18 09:30:43,808 INFO [STDOUT] (main) 09:30:43.808 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.808 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0' to allow for resolving potential circular references

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'dao'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'dao'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'dao' to allow for resolving potential circular references

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sessionFactory'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'dao'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'securityServiceFactory'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'securityServiceFactory'

2011-07-18 09:30:43,824 INFO [STDOUT] (main) 09:30:43.824 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'securityServiceFactory' to allow for resolving potential circular references

2011-07-18 09:30:43,839 INFO [STDOUT] (main) 09:30:43.839 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'securityServiceFactory'

2011-07-18 09:30:43,839 INFO [STDOUT] (main) 09:30:43.839 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:43,839 INFO [STDOUT] (main) 09:30:43.839 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'dadosAutenticacao'

2011-07-18 09:30:43,839 INFO [STDOUT] (main) 09:30:43.839 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'dadosAutenticacao' to allow for resolving potential circular references

2011-07-18 09:30:43,855 INFO [STDOUT] (main) 09:30:43.855 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Creating CGLIB2 proxy: target source is SimpleBeanTargetSource for target bean 'scopedTarget.dadosAutenticacao' of type [br.com.petrobras.snarf.common.security.DefaultAuthenticationData]

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public java.lang.Object br.com.petrobras.snarf.common.security.DefaultAuthenticationData.get(java.lang.String)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public java.lang.String br.com.petrobras.snarf.common.security.DefaultAuthenticationData.toString()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public br.com.petrobras.snarf.common.security.AuthenticationData br.com.petrobras.snarf.common.security.DefaultAuthenticationData.clone()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public void br.com.petrobras.snarf.common.security.DefaultAuthenticationData.store(java.lang.String,java.lang.Object)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public java.lang.String br.com.petrobras.snarf.common.security.DefaultAuthenticationData.getUsername()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public boolean br.com.petrobras.snarf.common.security.DefaultAuthenticationData.isAuthenticated()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public java.util.Set br.com.petrobras.snarf.common.security.DefaultAuthenticationData.getSecurityObjects()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Found finalize() method - using NO_OVERRIDE

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Found 'equals' method: public boolean java.lang.Object.equals(java.lang.Object)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Found 'hashCode' method: public native int java.lang.Object.hashCode()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public abstract java.lang.Object org.springframework.aop.scope.ScopedObject.getTargetObject()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Unable to apply any optimisations to advised method: public abstract void org.springframework.aop.scope.ScopedObject.removeFromScope()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract int org.springframework.aop.framework.Advised.indexOf(org.aopalliance.aop.Advice)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract org.springframework.aop.Advisor[] org.springframework.aop.framework.Advised.getAdvisors()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.addAdvisor(org.springframework.aop.Advisor) throws org.springframework.aop.framework.AopConfigException

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.addAdvisor(int,org.springframework.aop.Advisor) throws org.springframework.aop.framework.AopConfigException

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract boolean org.springframework.aop.framework.Advised.removeAdvisor(org.springframework.aop.Advisor)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.removeAdvisor(int) throws org.springframework.aop.framework.AopConfigException

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract org.springframework.aop.TargetSource org.springframework.aop.framework.Advised.getTargetSource()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.setTargetSource(org.springframework.aop.TargetSource)

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.addAdvice(int,org.aopalliance.aop.Advice) throws org.springframework.aop.framework.AopConfigException

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract void org.springframework.aop.framework.Advised.addAdvice(org.aopalliance.aop.Advice) throws org.springframework.aop.framework.AopConfigException

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract java.lang.Class[] org.springframework.aop.framework.Advised.getProxiedInterfaces()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract java.lang.String org.springframework.aop.framework.Advised.toProxyConfigString()

2011-07-18 09:30:43,917 INFO [STDOUT] (main) 09:30:43.917 [main] DEBUG o.s.aop.framework.Cglib2AopProxy - Method is declared on Advised interface: public abstract java.lang.Class org.springframework.aop.TargetClassAware.getTargetClass()

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'dadosAutenticacao'

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'i18nConfigurer'

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'i18nConfigurer'

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'i18nConfigurer' to allow for resolving potential circular references

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'br.com.petrobras.framework.module.i18n.SpringLocaleStore#16352a5'

2011-07-18 09:30:43,980 INFO [STDOUT] (main) 09:30:43.980 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'br.com.petrobras.framework.module.i18n.SpringLocaleStore#16352a5'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'i18nConfigurer'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'acessoService'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'acessoService'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'acessoService' to allow for resolving potential circular references

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dao'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'securityServiceFactory'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [logon] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT]

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'acessoService' with 0 common interceptors and 1 specific interceptors

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [br.com.tito.kitca.service.business.AcessoServiceImpl@ccecbb]

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'acessoService'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'empresaService'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'empresaService'

2011-07-18 09:30:43,996 INFO [STDOUT] (main) 09:30:43.996 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'empresaService' to allow for resolving potential circular references

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dao'

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'securityServiceFactory'

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [salvarEmpresa] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT]

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'empresaService' with 0 common interceptors and 1 specific interceptors

2011-07-18 09:30:44,011 INFO [STDOUT] (main) 09:30:44.011 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [br.com.tito.kitca.service.business.EmpresaServiceImpl@d7606]

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'empresaService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'ambienteService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'ambienteService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'ambienteService' to allow for resolving potential circular references

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dao'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'securityServiceFactory'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [salvarAmbiente] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT]

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'ambienteService' with 0 common interceptors and 1 specific interceptors

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [br.com.tito.kitca.service.business.AmbienteServiceImpl@de38]

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'ambienteService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'sistemaService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'sistemaService'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'sistemaService' to allow for resolving potential circular references

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dao'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'securityServiceFactory'

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [salvarSistema] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT]

2011-07-18 09:30:44,027 INFO [STDOUT] (main) 09:30:44.027 [main] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'sistemaService' with 0 common interceptors and 1 specific interceptors

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'sistemaService'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'securityInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'securityInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'securityInterceptor' to allow for resolving potential circular references

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'dadosAutenticacao'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'securityInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'exceptionInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'exceptionInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'exceptionInterceptor' to allow for resolving potential circular references

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'exceptionInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'delegationInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'delegationInterceptor'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'delegationInterceptor' to allow for resolving potential circular references

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'kitcaService'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'kitcaService'

2011-07-18 09:30:44,042 INFO [STDOUT] (main) 09:30:44.042 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'kitcaService' to allow for resolving potential circular references

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.aop.framework.ProxyFactoryBean - Advice has changed; recaching singleton instance

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'kitcaService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'acessoService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'empresaService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'ambienteService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sistemaService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'delegationInterceptor'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'kitcaService'

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] DEBUG o.s.web.context.ContextLoader - Published root WebApplicationContext as ServletContext attribute with name [org.springframework.web.context.WebApplicationContext.ROOT]

2011-07-18 09:30:44,058 INFO [STDOUT] (main) 09:30:44.058 [main] INFO o.s.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 1859 ms

2011-07-18 09:30:44,074 INFO [STDOUT] (main) 09:30:44.074 [main] DEBUG o.s.web.servlet.DispatcherServlet - Initializing servlet 'remoting'

2011-07-18 09:30:44,089 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/kitca]] (main) Initializing Spring FrameworkServlet 'remoting'

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] INFO o.s.web.servlet.DispatcherServlet - FrameworkServlet 'remoting': initialization started

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] DEBUG o.s.web.servlet.DispatcherServlet - Servlet with name 'remoting' will try to create custom WebApplicationContext context of class 'org.springframework.web.context.support.XmlWebApplicationContext', using parent context [org.springframework.web.context.support.XmlWebApplicationContext@79e5e7: display name [Root WebApplicationContext]; startup date [Mon Jul 18 09:30:42 GMT-03:00 2011]; root of context hierarchy]

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] INFO o.s.w.c.s.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@17dec37: display name [WebApplicationContext for namespace 'remoting-servlet']; startup date [Mon Jul 18 09:30:44 GMT-03:00 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@79e5e7

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] INFO o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/resources/remoting-servlet.xml]

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] DEBUG o.s.b.f.xml.DefaultDocumentLoader - Using JAXP provider [org.apache.xerces.jaxp.DocumentBuilderFactoryImpl]

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Loading schema mappings from [META-INF/spring.schemas]

2011-07-18 09:30:44,089 INFO [STDOUT] (main) 09:30:44.089 [main] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/beans/spring-beans-2.0.xsd] in classpath: org/springframework/beans/factory/xml/spring-beans-2.0.xsd

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.b.f.x.DefaultBeanDefinitionDocumentReader - Loading bean definitions

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.b.f.x.BeanDefinitionParserDelegate - No XML 'id' specified - using '/KITCAService.svlt' as bean name and [] as aliases

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.b.f.xml.XmlBeanDefinitionReader - Loaded 1 bean definitions from location pattern [/WEB-INF/resources/remoting-servlet.xml]

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] INFO o.s.w.c.s.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@17dec37]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1eb7ecf

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - 1 beans defined in org.springframework.web.context.support.XmlWebApplicationContext@17dec37: display name [WebApplicationContext for namespace 'remoting-servlet']; startup date [Mon Jul 18 09:30:44 GMT-03:00 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@79e5e7

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@18eedc2]

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.w.c.s.XmlWebApplicationContext - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@9b780d]

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.u.c.s.UiApplicationContextUtils - Unable to locate ThemeSource with name 'themeSource': using default [org.springframework.ui.context.support.DelegatingThemeSource@e6a1e5]

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] INFO o.s.b.f.s.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1eb7ecf: defining beans [/KITCAService.svlt]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@1d3e355

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean '/KITCAService.svlt'

2011-07-18 09:30:44,121 INFO [STDOUT] (main) 09:30:44.121 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean '/KITCAService.svlt'

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean '/KITCAService.svlt' to allow for resolving potential circular references

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'kitcaService'

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'securityInterceptor'

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.aop.framework.ProxyFactoryBean - Advice has changed; recaching singleton instance

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'exceptionInterceptor'

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.aop.framework.ProxyFactoryBean - Advice has changed; recaching singleton instance

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'delegationInterceptor'

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.aop.framework.ProxyFactoryBean - Advice has changed; recaching singleton instance

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is EmptyTargetSource: no target class, static

2011-07-18 09:30:44,136 INFO [STDOUT] (main) 09:30:44.136 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '/KITCAService.svlt'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [$Proxy289@1662318]

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean '/KITCAService.svlt'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.web.servlet.DispatcherServlet - Unable to locate MultipartResolver with name 'multipartResolver': no multipart request handling provided

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.web.servlet.DispatcherServlet - Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@eb1198]

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'

2011-07-18 09:30:44,152 INFO [STDOUT] (main) 09:30:44.152 [main] DEBUG o.s.web.servlet.DispatcherServlet - Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@8f329f]

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Looking for URL mappings in application context: org.springframework.web.context.support.XmlWebApplicationContext@17dec37: display name [WebApplicationContext for namespace 'remoting-servlet']; startup date [Mon Jul 18 09:30:44 GMT-03:00 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@79e5e7

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean '/KITCAService.svlt'

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Mapped URL path [/KITCAService.svlt] onto handler [org.springframework.remoting.httpinvoker.HttpInvokerServiceExporter@7f7d2b]

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Rejected bean name 'messageSource': no URL paths identified

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Rejected bean name 'applicationEventMulticaster': no URL paths identified

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping'

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping'

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.m.a.DefaultAnnotationHandlerMapping - Looking for URL mappings in application context: org.springframework.web.context.support.XmlWebApplicationContext@17dec37: display name [WebApplicationContext for namespace 'remoting-servlet']; startup date [Mon Jul 18 09:30:44 GMT-03:00 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@79e5e7

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.m.a.DefaultAnnotationHandlerMapping - Rejected bean name '/KITCAService.svlt': no URL paths identified

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.m.a.DefaultAnnotationHandlerMapping - Rejected bean name 'messageSource': no URL paths identified

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.w.s.m.a.DefaultAnnotationHandlerMapping - Rejected bean name 'applicationEventMulticaster': no URL paths identified

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.web.servlet.DispatcherServlet - No HandlerMappings found in servlet 'remoting': using default

2011-07-18 09:30:44,167 INFO [STDOUT] (main) 09:30:44.167 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.mvc.HttpRequestHandlerAdapter'

2011-07-18 09:30:44,183 INFO [STDOUT] (main) 09:30:44.183 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.mvc.throwaway.ThrowawayControllerHandlerAdapter'

2011-07-18 09:30:44,183 INFO [STDOUT] (main) 09:30:44.183 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter'

2011-07-18 09:30:44,199 INFO [STDOUT] (main) 09:30:44.199 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter'

2011-07-18 09:30:44,199 INFO [STDOUT] (main) 09:30:44.199 [main] DEBUG o.s.web.servlet.DispatcherServlet - No HandlerAdapters found in servlet 'remoting': using default

2011-07-18 09:30:44,199 INFO [STDOUT] (main) 09:30:44.199 [main] DEBUG o.s.web.servlet.DispatcherServlet - No HandlerExceptionResolvers found in servlet 'remoting': using default

2011-07-18 09:30:44,199 INFO [STDOUT] (main) 09:30:44.199 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'

2011-07-18 09:30:44,199 INFO [STDOUT] (main) 09:30:44.199 [main] DEBUG o.s.web.servlet.DispatcherServlet - Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@16fdd71]

2011-07-18 09:30:44,214 INFO [STDOUT] (main) 09:30:44.214 [main] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.view.InternalResourceViewResolver'

2011-07-18 09:30:44,214 INFO [STDOUT] (main) 09:30:44.214 [main] DEBUG o.s.web.servlet.DispatcherServlet - No ViewResolvers found in servlet 'remoting': using default

2011-07-18 09:30:44,214 INFO [STDOUT] (main) 09:30:44.214 [main] DEBUG o.s.web.servlet.DispatcherServlet - Published WebApplicationContext of servlet 'remoting' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.remoting]

2011-07-18 09:30:44,214 INFO [STDOUT] (main) 09:30:44.214 [main] INFO o.s.web.servlet.DispatcherServlet - FrameworkServlet 'remoting': initialization completed in 125 ms

2011-07-18 09:30:44,214 INFO [STDOUT] (main) 09:30:44.214 [main] DEBUG o.s.web.servlet.DispatcherServlet - Servlet 'remoting' configured successfully

2011-07-18 09:30:44,246 WARN [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) Failed to setup clustering, clustering disabled. NoClassDefFoundError: org/jboss/cache/pojo/jmx/PojoCacheJmxWrapperMBean

2011-07-18 09:30:44,277 INFO [org.apache.coyote.http11.Http11Protocol] (main) Starting Coyote HTTP/1.1 on http-0.0.0.0-8080

2011-07-18 09:30:44,292 INFO [org.apache.coyote.ajp.AjpProtocol] (main) Starting Coyote AJP/1.3 on ajp-0.0.0.0-8009

2011-07-18 09:30:44,292 INFO [org.jboss.bootstrap.microcontainer.ServerImpl] (main) JBoss (Microcontainer) [5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)] Started in 1m:6s:313ms

2011-07-18 09:32:12,154 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.154 [http-0.0.0.0-8080-1] DEBUG o.s.web.servlet.DispatcherServlet - DispatcherServlet with name 'remoting' processing request for [/kitca/KITCAService.svlt]

2011-07-18 09:32:12,169 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.169 [http-0.0.0.0-8080-1] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Mapping [/KITCAService.svlt] to handler 'org.springframework.remoting.httpinvoker.HttpInvokerServiceExporter@7f7d2b'

2011-07-18 09:32:12,169 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.169 [http-0.0.0.0-8080-1] DEBUG o.s.r.s.RemoteInvocationTraceInterceptor - Incoming HttpInvokerServiceExporter remote call: br.com.tito.kitca.common.business.AcessoService.logon

2011-07-18 09:32:12,185 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.185 [http-0.0.0.0-8080-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'scopedTarget.dadosAutenticacao'

2011-07-18 09:32:12,185 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.185 [http-0.0.0.0-8080-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

2011-07-18 09:32:12,185 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.185 [http-0.0.0.0-8080-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'scopedTarget.dadosAutenticacao'

2011-07-18 09:32:12,185 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.185 [http-0.0.0.0-8080-1] INFO b.c.t.k.c.business.AcessoService - [null] executa 'logon'

2011-07-18 09:32:12,185 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.185 [http-0.0.0.0-8080-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [logon] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT]

2011-07-18 09:32:12,200 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.200 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Creating new transaction with name [br.com.tito.kitca.common.business.AcessoService.logon]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT

2011-07-18 09:32:12,279 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.279 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Opened new Session [org.hibernate.impl.SessionImpl@486d9f] for Hibernate transaction

2011-07-18 09:32:12,279 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.279 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@486d9f]

2011-07-18 09:32:12,294 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.294 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Exposing Hibernate transaction as JDBC transaction [org.jboss.resource.adapter.jdbc.jdk6.WrappedConnectionJDK6@90b907]

2011-07-18 09:32:12,294 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.294 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Initiating transaction commit

2011-07-18 09:32:12,294 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.294 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Committing Hibernate transaction on Session [org.hibernate.impl.SessionImpl@486d9f]

2011-07-18 09:32:12,310 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.310 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Closing Hibernate Session [org.hibernate.impl.SessionImpl@486d9f] after transaction

2011-07-18 09:32:12,310 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.310 [http-0.0.0.0-8080-1] DEBUG o.s.o.hibernate3.SessionFactoryUtils - Closing Hibernate Session

2011-07-18 09:32:12,310 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.310 [http-0.0.0.0-8080-1] DEBUG o.s.r.s.RemoteInvocationTraceInterceptor - Finished processing of HttpInvokerServiceExporter remote call: br.com.tito.kitca.common.business.AcessoService.logon

2011-07-18 09:32:12,310 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.310 [http-0.0.0.0-8080-1] DEBUG o.s.web.servlet.DispatcherServlet - Null ModelAndView returned to DispatcherServlet with name 'remoting': assuming HandlerAdapter completed request handling

2011-07-18 09:32:12,310 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:12.310 [http-0.0.0.0-8080-1] DEBUG o.s.web.servlet.DispatcherServlet - Successfully completed request

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.web.servlet.DispatcherServlet - DispatcherServlet with name 'remoting' processing request for [/kitca/KITCAService.svlt]

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Mapping [/KITCAService.svlt] to handler 'org.springframework.remoting.httpinvoker.HttpInvokerServiceExporter@7f7d2b'

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.r.s.RemoteInvocationTraceInterceptor - Incoming HttpInvokerServiceExporter remote call: br.com.tito.kitca.common.business.EmpresaService.buscarEmpresas

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] INFO b.c.t.k.c.business.EmpresaService - [x4ij] executa 'buscarEmpresas'

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method [buscarEmpresas] with attribute [PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly]

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Creating new transaction with name [br.com.tito.kitca.common.business.EmpresaService.buscarEmpresas]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Opened new Session [org.hibernate.impl.SessionImpl@1619950] for Hibernate transaction

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@1619950]

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.jdbc.datasource.DataSourceUtils - Setting JDBC Connection [org.jboss.resource.adapter.jdbc.jdk6.WrappedConnectionJDK6@988e5e] read-only

2011-07-18 09:32:15,513 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.513 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Exposing Hibernate transaction as JDBC transaction [org.jboss.resource.adapter.jdbc.jdk6.WrappedConnectionJDK6@988e5e]

2011-07-18 09:32:15,732 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Initiating transaction rollback

2011-07-18 09:32:15,732 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Rolling back Hibernate transaction on Session [org.hibernate.impl.SessionImpl@1619950]

2011-07-18 09:32:15,732 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] DEBUG o.s.jdbc.datasource.DataSourceUtils - Resetting read-only flag of JDBC Connection [org.jboss.resource.adapter.jdbc.jdk6.WrappedConnectionJDK6@988e5e]

2011-07-18 09:32:15,732 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] DEBUG o.s.o.h.HibernateTransactionManager - Closing Hibernate Session [org.hibernate.impl.SessionImpl@1619950] after transaction

2011-07-18 09:32:15,732 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] DEBUG o.s.o.hibernate3.SessionFactoryUtils - Closing Hibernate Session

2011-07-18 09:32:15,779 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.732 [http-0.0.0.0-8080-1] ERROR b.c.t.k.c.business.EmpresaService - Empresa is not mapped [ select distinct e from Empresa e left join fetch e.ambientes a where 1=1 order by e.nome ]

java.lang.reflect.InvocationTargetException: null

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_20]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_20]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_20]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_20]

at br.com.petrobras.snarf.service.interceptor.DelegationInterceptor.invoke(DelegationInterceptor.java:52) ~[snarf-service-1.4.jar:na]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at br.com.petrobras.snarf.service.interceptor.ExceptionInterceptor.invoke(ExceptionInterceptor.java:115) ~[snarf-service-1.4.jar:na]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at br.com.petrobras.snarf.service.interceptor.SecurityInterceptor.invoke(SecurityInterceptor.java:62) [snarf-service-1.4.jar:na]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at $Proxy289.buscarEmpresas(Unknown Source) [na:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_20]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_20]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_20]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_20]

at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.support.RemoteInvocationTraceInterceptor.invoke(RemoteInvocationTraceInterceptor.java:77) [spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at $Proxy289.buscarEmpresas(Unknown Source) [na:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_20]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_20]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_20]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_20]

at org.springframework.remoting.support.RemoteInvocation.invoke(RemoteInvocation.java:205) [spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.support.DefaultRemoteInvocationExecutor.invoke(DefaultRemoteInvocationExecutor.java:38) [spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.support.RemoteInvocationBasedExporter.invoke(RemoteInvocationBasedExporter.java:78) [spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.support.RemoteInvocationBasedExporter.invokeAndCreateResult(RemoteInvocationBasedExporter.java:114) [spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.httpinvoker.HttpInvokerServiceExporter.handleRequest(HttpInvokerServiceExporter.java:74) [spring-web-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.web.servlet.mvc.HttpRequestHandlerAdapter.handle(HttpRequestHandlerAdapter.java:49) [spring-webmvc-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:875) [spring-webmvc-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:807) [spring-webmvc-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:571) [spring-webmvc-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:511) [spring-webmvc-2.5.6.SEC02.jar:2.5.6.SEC02]

at javax.servlet.http.HttpServlet.service(HttpServlet.java:637) [servlet-api.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) [servlet-api.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:190) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:92) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158) [jboss-web-service.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:598) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447) [jbossweb.jar!/:5.1.0.GA (build: SVNTag=JBoss_5_1_0_GA date=200905221634)]

at java.lang.Thread.run(Thread.java:619) [na:1.6.0_20]

Caused by: org.hibernate.hql.ast.QuerySyntaxException: Empresa is not mapped [ select distinct e from Empresa e left join fetch e.ambientes a where 1=1 order by e.nome ]

at org.hibernate.hql.ast.util.SessionFactoryHelper.requireClassPersister(SessionFactoryHelper.java:181) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.tree.FromElementFactory.addFromElement(FromElementFactory.java:110) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.tree.FromClause.addFromElement(FromClause.java:93) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.HqlSqlWalker.createFromElement(HqlSqlWalker.java:277) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.fromElement(HqlSqlBaseWalker.java:3056) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.fromElementList(HqlSqlBaseWalker.java:2945) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.fromClause(HqlSqlBaseWalker.java:688) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.query(HqlSqlBaseWalker.java:544) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.selectStatement(HqlSqlBaseWalker.java:281) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.antlr.HqlSqlBaseWalker.statement(HqlSqlBaseWalker.java:229) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.QueryTranslatorImpl.analyze(QueryTranslatorImpl.java:251) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.QueryTranslatorImpl.doCompile(QueryTranslatorImpl.java:183) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.hql.ast.QueryTranslatorImpl.compile(QueryTranslatorImpl.java:134) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.engine.query.HQLQueryPlan.<init>(HQLQueryPlan.java:101) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.engine.query.HQLQueryPlan.<init>(HQLQueryPlan.java:80) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.engine.query.QueryPlanCache.getHQLQueryPlan(QueryPlanCache.java:94) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.impl.AbstractSessionImpl.getHQLQueryPlan(AbstractSessionImpl.java:156) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.impl.AbstractSessionImpl.createQuery(AbstractSessionImpl.java:135) ~[hibernate-core.jar!/:3.3.1.GA]

at org.hibernate.impl.SessionImpl.createQuery(SessionImpl.java:1650) ~[hibernate-core.jar!/:3.3.1.GA]

at br.com.petrobras.snarf.service.persistence.BusinessTextQuery.buildQuery(BusinessTextQuery.java:212) ~[snarf-service-1.4.jar:na]

at br.com.petrobras.snarf.service.persistence.BusinessTextQuery.setText(BusinessTextQuery.java:74) ~[snarf-service-1.4.jar:na]

at br.com.tito.kitca.service.query.ConsultaEmpresasPorNome.<init>(ConsultaEmpresasPorNome.java:42) ~[kitca-service-1.0.0.jar:na]

at br.com.tito.kitca.service.business.EmpresaServiceImpl.buscarEmpresas(EmpresaServiceImpl.java:29) ~[kitca-service-1.0.0.jar:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_20]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_20]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_20]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_20]

at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) ~[spring-tx-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at $Proxy286.buscarEmpresas(Unknown Source) ~[na:na]

... 59 common frames omitted

2011-07-18 09:32:15,794 INFO [STDOUT] (http-0.0.0.0-8080-1) 09:32:15.794 [http-0.0.0.0-8080-1] WARN o.s.r.s.RemoteInvocationTraceInterceptor - Processing of HttpInvokerServiceExporter remote call resulted in fatal exception: br.com.tito.kitca.common.business.EmpresaService.buscarEmpresas

br.com.petrobras.snarf.common.exception.SystemException: snarf.exception.dataBaseError

at br.com.petrobras.snarf.common.exception.SystemException.unknown(SystemException.java:22) ~[snarf-common-1.4.jar:na]

at br.com.petrobras.snarf.service.interceptor.ExceptionInterceptor.handleException(ExceptionInterceptor.java:93) ~[snarf-service-1.4.jar:na]

at br.com.petrobras.snarf.service.interceptor.ExceptionInterceptor.invoke(ExceptionInterceptor.java:117) ~[snarf-service-1.4.jar:na]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at br.com.petrobras.snarf.service.interceptor.SecurityInterceptor.invoke(SecurityInterceptor.java:62) ~[snarf-service-1.4.jar:na]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at $Proxy289.buscarEmpresas(Unknown Source) [na:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_20]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_20]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_20]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_20]

at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) ~[spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) [spring-aop-2.5.6.SEC02.jar:2.5.6.SEC02]

at org.springframework.remoting.support.RemoteInvocationTraceInterceptor.invoke(RemoteInvocationTraceInterceptor.java:77) ~[spring-context-2.5.6.SEC02.jar:2.5.6.SEC02]