2-Mar-2011 2:41:20 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/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64/server:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/lib/amd64:/usr/lib/jvm/java-6-sun-1.6.0.24/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib 2-Mar-2011 2:41:20 PM org.apache.coyote.http11.Http11Protocol init INFO: Initializing Coyote HTTP/1.1 on http-8081 2-Mar-2011 2:41:20 PM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 1462 ms 2-Mar-2011 2:41:20 PM org.apache.catalina.core.StandardService start INFO: Starting service Catalina 2-Mar-2011 2:41:20 PM org.apache.catalina.core.StandardEngine start INFO: Starting Servlet Engine: Apache Tomcat/6.0.32 2-Mar-2011 2:41:20 PM org.apache.catalina.startup.HostConfig deployDescriptor INFO: Deploying configuration descriptor manager.xml 2-Mar-2011 2:41:21 PM org.apache.catalina.startup.HostConfig deployDescriptor INFO: Deploying configuration descriptor host-manager.xml 2-Mar-2011 2:41:21 PM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive klaros-web.war 2-Mar-2011 2:41:23 PM org.apache.catalina.core.StandardContext addApplicationListener INFO: The listener "com.icesoft.faces.util.event.servlet.ContextEventRepeater" is already configured for this context. The duplicate definition has been ignored. 2-Mar-2011 2:41:23 PM org.apache.catalina.core.StandardContext addApplicationListener INFO: The listener "com.icesoft.faces.util.event.servlet.ContextEventRepeater" is already configured for this context. The duplicate definition has been ignored. 14:41:23,912 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy] 14:41:23,913 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 14:41:23,913 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/opt/klaros-testmanagement/webapps/klaros-web/WEB-INF/classes/logback.xml] 14:41:23,914 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath. 14:41:23,914 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/klaros-testmanagement/webapps/klaros-web/WEB-INF/classes/logback.xml] 14:41:23,914 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [jar:file:/opt/klaros-testmanagement/webapps/klaros-web/WEB-INF/lib/jackrabbit-webapp-2.2.4.jar!/logback.xml] 14:41:23,914 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [jar:file:/opt/klaros-testmanagement/webapps/klaros-web/WEB-INF/lib/mylyn-common-0.1.0.201102202050.jar!/logback.xml] 14:41:24,093 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 14:41:24,096 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 14:41:24,113 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [RootConsoleAppender] 14:41:24,329 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - ROOT level set to WARN 14:41:24,329 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [RootConsoleAppender] to Logger[ROOT] 14:41:24,331 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jboss.seam.jsf.SeamPhaseListener] to ERROR 14:41:24,331 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [de.verit.klaros.web.beans.base.BaseBean] to DEBUG 14:41:24,331 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.commons.httpclient.HttpMethodBase] to ERROR 2011-03-02 14:41:40: WARN, org.hibernate.util.JDBCExceptionReporter, main SQL Error: 0, SQLState: 42P01 2011-03-02 14:41:40: ERROR, org.hibernate.util.JDBCExceptionReporter, main ERROR: relation "k_cfg_prop_def" does not exist Position: 386 2011-03-02 14:41:44: ERROR, org.hibernate.event.def.AbstractFlushingEventListener, main Could not synchronize database state with session org.hibernate.cache.CacheException: net.sf.ehcache.transaction.TransactionException: transaction not started at net.sf.ehcache.hibernate.regions.EhcacheGeneralDataRegion.put(EhcacheGeneralDataRegion.java:95) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.hibernate.regions.EhcacheTimestampsRegion.put(EhcacheTimestampsRegion.java:31) ~[ehcache-core-2.4.0.jar:na] at org.hibernate.cache.UpdateTimestampsCache.preinvalidate(UpdateTimestampsCache.java:70) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.engine.ActionQueue.registerCleanupActions(ActionQueue.java:279) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:270) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:259) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:178) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) ~[hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:51) [hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1206) [hibernate-core-3.5.6-Final.jar:3.5.6-Final] at org.springframework.orm.hibernate3.HibernateAccessor.flushIfNecessary(HibernateAccessor.java:390) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:420) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.orm.hibernate3.HibernateTemplate.executeWithNativeSession(HibernateTemplate.java:374) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.orm.hibernate3.HibernateTemplate.save(HibernateTemplate.java:694) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at de.verit.klaros.core.dao.hibernate.HibernateDAO.save(HibernateDAO.java:172) [klaros-migration-3.3.0.jar:3.3.0] at de.verit.klaros.core.dao.hibernate.HibernateDAO.save(HibernateDAO.java:49) [klaros-migration-3.3.0.jar:3.3.0] at de.verit.klaros.web.handler.DatabaseSetup.createDefaultUsers(DatabaseSetup.java:90) [DatabaseSetup.class:na] at de.verit.klaros.web.handler.DatabaseSetup.initialize(DatabaseSetup.java:58) [DatabaseSetup.class:na] at de.verit.klaros.core.dao.hibernate.KlarosLocalSessionFactoryBean.postProcessMappings(KlarosLocalSessionFactoryBean.java:89) [klaros-migration-3.3.0.jar:3.3.0] at org.springframework.orm.hibernate3.LocalSessionFactoryBean.buildSessionFactory(LocalSessionFactoryBean.java:672) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.orm.hibernate3.AbstractSessionFactoryBean.afterPropertiesSet(AbstractSessionFactoryBean.java:211) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at de.verit.klaros.core.dao.hibernate.KlarosLocalSessionFactoryBean.afterPropertiesSet(KlarosLocalSessionFactoryBean.java:140) [klaros-migration-3.3.0.jar:3.3.0] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1369) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at java.security.AccessController.doPrivileged(Native Method) [na:1.6.0_24] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:269) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:104) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1245) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1010) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:472) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at java.security.AccessController.doPrivileged(Native Method) [na:1.6.0_24] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380) [spring-2.5.6.SEC01.jar:2.5.6.SEC01] at org.jboss.seam.ioc.spring.ContextLoader.startupContextLoader(ContextLoader.java:64) [jboss-seam-ioc-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.ioc.spring.ContextLoader.create(ContextLoader.java:43) [jboss-seam-ioc-2.2.1.Final.jar:2.2.1.Final] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_24] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_24] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_24] at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_24] at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.util.Reflections.invokeAndWrap(Reflections.java:144) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.Component.callComponentMethod(Component.java:2275) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.Component.callCreateMethod(Component.java:2198) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.Component.newInstance(Component.java:2158) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.contexts.Contexts.startup(Contexts.java:304) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.contexts.Contexts.startup(Contexts.java:278) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.contexts.ServletLifecycle.endInitialization(ServletLifecycle.java:143) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.init.Initialization.init(Initialization.java:744) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.jboss.seam.servlet.SeamListener.contextInitialized(SeamListener.java:36) [jboss-seam-2.2.1.Final.jar:2.2.1.Final] at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4205) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardContext.start(StandardContext.java:4704) [catalina.jar:6.0.32] at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:799) [catalina.jar:6.0.32] at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:779) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:601) [catalina.jar:6.0.32] at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:943) [catalina.jar:6.0.32] at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:778) [catalina.jar:6.0.32] at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:504) [catalina.jar:6.0.32] at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1315) [catalina.jar:6.0.32] at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:324) [catalina.jar:6.0.32] at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:142) [catalina.jar:6.0.32] at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1061) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardHost.start(StandardHost.java:840) [catalina.jar:6.0.32] at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:463) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardService.start(StandardService.java:525) [catalina.jar:6.0.32] at org.apache.catalina.core.StandardServer.start(StandardServer.java:754) [catalina.jar:6.0.32] at org.apache.catalina.startup.Catalina.start(Catalina.java:595) [catalina.jar:6.0.32] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_24] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_24] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_24] at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_24] at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289) [bootstrap.jar:6.0.32] at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414) [bootstrap.jar:6.0.32] Caused by: net.sf.ehcache.transaction.TransactionException: transaction not started at net.sf.ehcache.transaction.local.LocalTransactionStore.getCurrentTransactionContext(LocalTransactionStore.java:89) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.transaction.local.LocalTransactionStore.assertNotTimedOut(LocalTransactionStore.java:95) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.transaction.local.LocalTransactionStore.put(LocalTransactionStore.java:155) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.Cache.putInternal(Cache.java:1401) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.Cache.put(Cache.java:1334) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.Cache.put(Cache.java:1306) ~[ehcache-core-2.4.0.jar:na] at net.sf.ehcache.hibernate.regions.EhcacheGeneralDataRegion.put(EhcacheGeneralDataRegion.java:86) ~[ehcache-core-2.4.0.jar:na] ... 88 common frames omitted 2-Mar-2011 2:41:45 PM com.sun.faces.config.ConfigureListener contextInitialized INFO: Initializing Mojarra (1.2_14-b01-FCS) for context '/klaros-web' 2-Mar-2011 2:41:47 PM org.apache.catalina.core.StandardContext start SEVERE: Error listenerStart 2-Mar-2011 2:41:47 PM org.apache.catalina.core.StandardContext start SEVERE: Context [/klaros-web] startup failed due to previous errors 2-Mar-2011 2:41:47 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc SEVERE: The web application [/klaros-web] registered the JDBC driver [org.apache.derby.jdbc.AutoloadedDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered. 2-Mar-2011 2:41:47 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc SEVERE: The web application [/klaros-web] registered the JDBC 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. 2-Mar-2011 2:41:47 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc SEVERE: The web application [/klaros-web] registered the JDBC driver [oracle.jdbc.OracleDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered. 2-Mar-2011 2:41:47 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc SEVERE: The web application [/klaros-web] registered the JDBC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered. 2-Mar-2011 2:41:47 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads SEVERE: The web application [/klaros-web] appears to have started a thread named [Session Monitor] but has failed to stop it. This is very likely to create a memory leak. 2-Mar-2011 2:41:49 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory ROOT 2-Mar-2011 2:41:49 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory examples 2-Mar-2011 2:41:49 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory docs 2-Mar-2011 2:41:49 PM org.apache.coyote.http11.Http11Protocol start INFO: Starting Coyote HTTP/1.1 on http-8081 2-Mar-2011 2:41:49 PM org.apache.jk.common.ChannelSocket init INFO: JK: ajp13 listening on /0.0.0.0:8009 2-Mar-2011 2:41:49 PM org.apache.jk.server.JkMain start INFO: Jk running ID=0 time=0/51 config=null 2-Mar-2011 2:41:49 PM org.apache.catalina.startup.Catalina start INFO: Server startup in 29062 ms