Tomcat + Spring startup fails

[Joe San]

Guys,

I cannot get my context started.It is a Spring based application. Below is what the log files say. I cannot make out where the error is.

Mar 18, 2011 10:45:07 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not 
found on the java.library.path: /usr/java/jdk1.6.0_22/jre/lib/i386/server:/usr/java/j
dk1.6.0_22/jre/lib/i386:/usr/java/jdk1.6.0_22/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
Mar 18, 2011 10:45:07 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-1979
Mar 18, 2011 10:45:07 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 405 ms
Mar 18, 2011 10:45:07 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Mar 18, 2011 10:45:07 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.24
Mar 18, 2011 10:45:07 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
Mar 18, 2011 10:45:07 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor Quintilis31Web.xml
Mar 18, 2011 10:45:07 PM org.apache.catalina.startup.HostConfig deployDescriptor
WARNING: A docBase /opt/apache-tomcat-6.0.24/webapps/opt/apache-tomcat-6.0.24/webapps/Quintilis31Web inside the host appBase has been specified, and will be ignored
0    INFO  ContextLoader  - Root WebApplicationContext: initialization started
40   INFO  XmlWebApplicationContext  - Refreshing Root WebApplicationContext: startup date [Fri Mar 18 22:45:08 CET 2011]; root of context hierarchy
103  INFO  XmlBeanDefinitionReader  - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext.xml]
174  INFO  XmlBeanDefinitionReader  - Loading XML bean definitions from class path resource [config/common/applicationContext-common.xml]
255  INFO  XmlBeanDefinitionReader  - Loading XML bean definitions from class path resource [config/common/applicationContext-velocity-email.xml]
296  INFO  XmlBeanDefinitionReader  - Loading XML bean definitions from class path resource [config/core/applicationContext-core.xml]
357  INFO  XmlBeanDefinitionReader  - Loading XML bean definitions from class path resource [config/common/applicationContext-web.xml]
601  INFO  PropertyPlaceholderConfigurer  - Loading properties file from class path resource [mail.properties]
703  INFO  XmlWebApplicationContext  - Bean 'dataSource' of type [class org.apache.commons.dbcp.BasicDataSource] is 
not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
726  INFO  XmlWebApplicationContext  - Bean 'org.springframework.orm.jpa.vendor.HibernateJpaVendorAdapter#e90943' 
of type [class org.springframework.orm.jpa.vendor.HibernateJpaVendorAdapter] is not eligible for getting processed by all 
BeanPostProcessors (for example: not eligible for auto-proxying)
734  INFO  DefaultContextLoadTimeWeaver  - Using a reflective load-time weaver for class loader: 
org.springframework.instrument.classloading.tomcat.TomcatInstrumentableClassLoader
734  INFO  XmlWebApplicationContext  - Bean 'loadTimeWeaver' of type [class org.springframework.context.weaving.
DefaultContextLoadTimeWeaver] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
742  INFO  LocalContainerEntityManagerFactoryBean  - Building JPA container EntityManagerFactory for persistence unit 'jugc-jpa'
768  INFO  Version  - Hibernate Annotations 3.3.1.GA
779  INFO  Environment  - Hibernate 3.2.6
782  INFO  Environment  - hibernate.properties not found
785  INFO  Environment  - Bytecode provider name : cglib
788  INFO  Environment  - using JDK 1.4 java.sql.Timestamp handling
848  INFO  Version  - Hibernate EntityManager 3.3.2.GA
865  INFO  Ejb3Configuration  - Processing PersistenceUnitInfo [
	name: jugc-jpa
	...]
1053 INFO  Configuration  - Reading mappings from resource : META-INF/COMMON-Queries.xml
1069 INFO  Configuration  - Reading mappings from resource : META-INF/CONTENT-Queries.xml
1083 INFO  Configuration  - Reading mappings from resource : META-INF/USER-Queries.xml
1167 INFO  AnnotationBinder  - Binding entity from annotated class: com.quintilis31.home.common.repository.expense.DailyExpense
1201 INFO  EntityBinder  - Bind entity com.quintilis31.home.common.repository.expense.DailyExpense on table DailyExpense
1236 INFO  AnnotationBinder  - Binding entity from annotated class: com.quintilis31.home.common.repository.user.UserDetails
1236 INFO  EntityBinder  - Bind entity com.quintilis31.home.common.repository.user.UserDetails on table JUGC_USER
1249 INFO  Version  - Hibernate Validator 3.0.0.GA
1333 INFO  ConnectionProviderFactory  - Initializing connection provider: org.hibernate.ejb.connection.
InjectedDataSourceConnectionProvider
1335 INFO  InjectedDataSourceConnectionProvider  - Using provided datasource
1537 INFO  SettingsFactory  - RDBMS: MySQL, version: 5.1.49-1ubuntu8.1
1538 INFO  SettingsFactory  - JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.1.10 ( Revision: ${svn.Revision} )
1549 INFO  Dialect  - Using dialect: org.hibernate.dialect.MySQL5Dialect
1552 INFO  TransactionFactoryFactory  - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
1553 INFO  TransactionManagerLookupFactory  - No TransactionManagerLookup configured (in JTA environment, use of 
read-write or transactional second-level cache is not recommended)
1553 INFO  SettingsFactory  - Automatic flush during beforeCompletion(): disabled
1553 INFO  SettingsFactory  - Automatic session close at end of transaction: disabled
1553 INFO  SettingsFactory  - JDBC batch size: 20
1553 INFO  SettingsFactory  - JDBC batch updates for versioned data: disabled
1554 INFO  SettingsFactory  - Scrollable result sets: enabled
1554 INFO  SettingsFactory  - JDBC3 getGeneratedKeys(): enabled
1554 INFO  SettingsFactory  - Connection release mode: auto
1554 INFO  SettingsFactory  - Maximum outer join fetch depth: 3
1554 INFO  SettingsFactory  - Default batch fetch size: 25
1554 INFO  SettingsFactory  - Generate SQL with comments: disabled
1554 INFO  SettingsFactory  - Order SQL updates by primary key: disabled
1554 INFO  SettingsFactory  - Order SQL inserts for batching: disabled
1555 INFO  SettingsFactory  - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
1556 INFO  ASTQueryTranslatorFactory  - Using ASTQueryTranslatorFactory
1556 INFO  SettingsFactory  - Query language substitutions: {}
1556 INFO  SettingsFactory  - JPA-QL strict compliance: enabled
1556 INFO  SettingsFactory  - Second-level cache: enabled
1556 INFO  SettingsFactory  - Query cache: enabled
1556 INFO  SettingsFactory  - Cache provider: net.sf.ehcache.hibernate.SingletonEhCacheProvider
1559 INFO  SettingsFactory  - Optimize cache for minimal puts: disabled
1559 INFO  SettingsFactory  - Structured second-level cache entries: disabled
1559 INFO  SettingsFactory  - Query cache factory: org.hibernate.cache.StandardQueryCacheFactory
1563 INFO  SettingsFactory  - Echoing all SQL to stdout
1563 INFO  SettingsFactory  - Statistics: enabled
1563 INFO  SettingsFactory  - Deleted entity synthetic identifier rollback: disabled
1564 INFO  SettingsFactory  - Default entity-mode: pojo
1564 INFO  SettingsFactory  - Named query checking : enabled
1585 INFO  SessionFactoryImpl  - building session factory
1591 WARN  ConfigurationFactory  - No configuration found. Configuring ehcache from ehcache-failsafe.xml  found in the
 classpath: jar:file:/opt/apache-tomcat-6.0.24/webapps/Quintilis31Web/WEB-INF/lib/ehcache-1.2.3.jar!/ehcache-failsafe.xml
1796 INFO  SessionFactoryObjectFactory  - Not binding factory to JNDI, no JNDI name configured
1797 INFO  UpdateTimestampsCache  - starting update timestamps cache at region: org.hibernate.cache.UpdateTimestampsCache
1797 WARN  SingletonEhCacheProvider  - Could not find a specific ehcache configuration for cache named [org.hibernate.cache
.UpdateTimestampsCache]; using defaults.
1816 INFO  StandardQueryCache  - starting query cache at region: org.hibernate.cache.StandardQueryCache
1816 WARN  SingletonEhCacheProvider  - Could not find a specific ehcache configuration for cache named [org.hibernate.cache.
StandardQueryCache]; using defaults.
1823 INFO  XmlWebApplicationContext  - Bean 'entityManagerFactory' of type [class org.springframework.orm.jpa.LocalContainer
EntityManagerFactoryBean] 
is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
1869 INFO  DefaultListableBeanFactory  - Pre-instantiating singletons in org.springframework.beans.factory.support.
DefaultListableBeanFactory@159780d: defining beans [dataSource,org.springframework.orm.jpa.support.Persistenc
eAnnotationBeanPostProcessor#0,org.springframework.dao.annotation.
PersistenceExceptionTranslationPostProcessor#0,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,
org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotation
Processor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.
internalPersistenceAnnotationProcessor,entityManagerFactory,txManager,txTemplate,org.springframework.beans.factory.config.
PropertyPlaceholderConfigurer#0,velocityEngine,mailSender,jugcEmailSender,org.springframework.aop.config.internalAutoProxy
Creator,loadTimeWeaver,loggingAspect,userFacade,contentFacade,userService,contentService,userNotificationService,
welcomeAction,userAction,meetUpAction,contentAction]; root of factory hierarchy
2349 INFO  ContextLoader  - Root WebApplicationContext: initialization completed in 2348 ms
2365 INFO  BasicTilesContainer  - Initializing Tiles2 container. . .
2383 INFO  BasicTilesContainer  - Tiles2 container initialization complete.
2384 INFO  TilesAccess  - Publishing TilesContext for context: org.apache.catalina.core.ApplicationContextFacade
2475 INFO  XmlConfigurationProvider  - Parsing configuration file [struts-default.xml]
2556 INFO  XmlConfigurationProvider  - Parsing configuration file [struts-plugin.xml]
2578 INFO  XmlConfigurationProvider  - Parsing configuration file [struts.xml]
2582 INFO  DefaultConfiguration  - Overriding property struts.i18n.reload - old value: false new value: true
2582 INFO  DefaultConfiguration  - Overriding property struts.configuration.xml.reload - old value: false new value: true
2643 INFO  StrutsSpringObjectFactory  - Initializing Struts-Spring integration...
2643 INFO  SpringObjectFactory  - Setting autowire strategy to name
2643 INFO  StrutsSpringObjectFactory  - ... initialized Struts-Spring integration successfully
Mar 18, 2011 10:45:11 PM org.apache.catalina.core.StandardContext start
SEVERE: Error filterStart
Mar 18, 2011 10:45:11 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/Quintilis31Web] startup failed due to previous errors
3119 INFO  TilesAccess  - Removing TilesContext for context: org.apache.catalina.core.ApplicationContextFacade
3119 INFO  XmlWebApplicationContext  - Closing Root WebApplicationContext: startup date [Fri Mar 18 22:45:08 CET 2011]; root of context hierarchy
3120 INFO  DefaultListableBeanFactory  - Destroying singletons in org.springframework.beans.factory.
support.DefaultListableBeanFactory@159780d: defining beans [dataSource,org.springframework.orm.jpa
.support.PersistenceAnnotationBeanPostProcessor#0,org.springframework.dao.annotation.
PersistenceExceptionTranslationPostProcessor#0,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.
springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotation
Processor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.
internalPersistenceAnnotationProcessor,entityManagerFactory,txManager,txTemplate,org.springframework.beans.factory.config.
PropertyPlaceholderConfigurer#0,velocityEngine,mailSender,jugcEmailSender,org.springframework.aop.config.internalAutoProxyCreator,
loadTimeWeaver,loggingAspect,userFacade,contentFacade,userService,contentService,userNotificationService,welcomeAction,userAction,
meetUpAction,contentAction]; root of factory hierarchy
3135 INFO  LocalContainerEntityManagerFactoryBean  - Closing JPA EntityManagerFactory for persistence unit 'jugc-jpa'
3135 INFO  SessionFactoryImpl  - closing
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. 
To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [Timer-0] but has failed to stop it. 
This is very likely to create a memory leak.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [MySQL Statement Cancellation Timer] 
but has failed to stop it. This is very likely to create a memory leak.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [org.springframework.core.NamedThreadLocal]
 (value [Prototype beans currently in creation]) and a value of type [null] (value [null]) but failed to remove it when the
 web application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [org.springframework.core.NamedThreadLocal] 
(value [Current AOP method invocation]) and a value of type [null] (value [null]) but failed to remove it when the web 
application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [null] (value [com.opensymphony.xwork2.inject.
ContainerImpl$10@ec9441]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@1f95cce]) but failed to
 remove it when the web application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [null] (value [com.opensymphony.xwork2.inject.
ContainerImpl$10@3bead5]) and a value of type [java.lang.Object[]] (value [[Ljava.lang.Object;@1b66aff]) but failed to
 remove it when the web application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Mar 18, 2011 10:45:11 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [org.springframework.core.NamedThreadLocal] 
(value [Name of currently proxied bean]) and a value of type [null] (value [null]) but failed to remove it when the web
 application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Mar 18, 2011 10:45:11 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
Mar 18, 2011 10:45:11 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
Mar 18, 2011 10:45:11 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-1979
Mar 18, 2011 10:45:11 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:1980
Mar 18, 2011 10:45:11 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=1/18  config=null
Mar 18, 2011 10:45:11 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 4336 ms

[Joe San]

It was due to a simple error. I was missing a required file.

[Barry Silva]

What file were you missing. Do you remember it.