I am new to hibernate and spring and I hope I have not missed something obvious in the forums or FAQ's. I have a trivial object with a map of strings to strings. If I add 500 strings into the map with plain hibernate, following the examples, it takes 0.2 seconds. If I do it using the spring classes, it takes 13 seconds! I am not sure what could be causing this huge performance hit. Stepping through the debuger, it seems that the while-loop in
BasicCollectionPersister.recreate() is horribly slow when running in spring. It looks like the line
Code:
st = session.getBatcher().prepareBatchStatement( getSQLInsertRowString() );
is where things slow down.
Any ideas about what configuration setting could be causing this? Should I not be using spring? Any help would be appriciated.
Hibernate version: 3.1
Springversion: 1.2.6
Service code:Code:
Device theDevice = new Device();
theDevice.setSimServer(simServer);
theDevice.setSimServerPort(simPort);
deviceDao.save(theDevice);
theDevice.setVariableAttributes(theVariableAttributes); // list of 500 strings
deviceDao.save(theDevice);
Hibernate DAO code:Code:
public Object save(Object object) {
Session session = getSession();
session.beginTransaction();
session.saveOrUpdate(object);
session.getTransaction().commit();
closeSession();
return object;
}
Hibernate w/spring DAO code:Code:
public void save(Object o) { getHibernateTemplate().saveOrUpdate(o); }
POJO:Code:
public class Device {
private Long id;
private String simServer;
private int simServerPort;
private Map variableAttributes = new HashMap();
...
public void setVariableAttributes(Map variableAttributes) { this.variableAttributes = variableAttributes; }
}
Mapping file:Code:
<class name="model.Device" table="DEVICE">
<id name="id" column="DEVICE_ID">
<generator class="native"/>
</id>
<property name="simServerPort"/>
<property name="simServer"/>
<map name="variableAttributes" >
<key column="DEVICE_ID"/>
<map-key column="ATTRIBUTE_NAME" type="string"/>
<element column="ATTRIBUTE_VALUE" type="string"/>
</map>
<set name="supportedServices" table="SUPPORTED_SERVICES">
<key column="DEVICE_ID"/>
<element type="string" column="SUPPORTED_SERVICE"/>
</set>
</class>
Hibernate config:Code:
<property name="connection.driver_class">solid.jdbc.SolidDriver</property>
<property name="connection.url">jdbc:solid://localhost:16714</property>
<property name="connection.username">admin</property>
<property name="connection.password">admin</property>
<property name="c3p0.min_size">5</property>
<property name="c3p0.max_size">20</property>
<property name="c3p0.timeout">1800</property>
<property name="c3p0.max_statements">50</property>
<property name="dialect">dialect.SolidDialect</property>
<property name="current_session_context_class">thread</property>
<property name="cache.provider_class">org.hibernate.cache.EhCacheProvider</property>
<property name="hibernate.max_fetch_depth">3</property>
<property name="hibernate.hbm2ddl.auto">create</property>
<mapping resource="model/Device.hbm.xml"/>
Spring config:Code:
<bean id="c3p0DataSource" class="com.mchange.v2.c3p0.ComboPooledDataSource" destroy-method="close">
<property name="driverClass" value="solid.jdbc.SolidDriver"/>
<property name="jdbcUrl" value="jdbc:solid://localhost:16714"/>
<property name="user" value="admin"/>
<property name="password" value="admin"/>
<property name="minPoolSize" value="5"/>
<property name="maxPoolSize" value="20"/>
<property name="maxStatements" value="50"/>
</bean>
<bean id="sessionFactory" class="org.springframework.orm.hibernate3.LocalSessionFactoryBean" abstract="false" singleton="true" lazy-init="default" autowire="default" dependency-check="default">
<property name="configLocation"><value>hibernate.cfg.xml</value></property >
<property name="dataSource">
<ref local="c3p0DataSource" />
</property>
</bean>
<bean id="deviceDao" class="dao.spring.DeviceSpringDaoImpl">
<property name="sessionFactory">
<ref local="sessionFactory" />
</property>
</bean>
Debug level Hibernate and Spring log excerpt:
18:06:42,905 WARN Configurator:126 - No configuration found. Configuring ehcache from ehcache-failsafe.xml found in the classpath: jar:file:/C:/workspaces/play/persistence/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
18:06:42,905 DEBUG Configuration$DiskStore:185 - Disk Store Path: C:\DOCUME~1\julio\LOCALS~1\Temp\
18:06:42,937 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 4, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,046 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,296 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
18:06:43,296 INFO Configuration:1022 - processing extends queue
18:06:43,296 INFO Configuration:1026 - processing collection mappings
18:06:43,296 INFO Configuration:1035 - processing association property references
18:06:43,296 INFO Configuration:1057 - processing foreign key constraints
18:06:43,296 INFO Configuration:1022 - processing extends queue
18:06:43,296 INFO Configuration:1026 - processing collection mappings
18:06:43,296 INFO Configuration:1035 - processing association property references
18:06:43,296 INFO Configuration:1057 - processing foreign key constraints
18:06:43,296 INFO SchemaExport:155 - Running hbm2ddl schema export
18:06:43,296 DEBUG SchemaExport:173 - import file not found: /import.sql
18:06:43,312 INFO SchemaExport:182 - exporting generated schema to database
18:06:43,312 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,327 DEBUG SchemaExport:296 - drop table DEVICE CASCADE
18:06:43,359 DEBUG SchemaExport:296 - drop table GUI_DATA CASCADE
18:06:43,405 DEBUG SchemaExport:296 - drop table SUPPORTED_SERVICES CASCADE
18:06:43,421 DEBUG SchemaExport:296 - drop table variableAttributes CASCADE
18:06:43,452 DEBUG SchemaExport:296 - drop table hibernate_unique_key CASCADE
18:06:43,484 DEBUG SchemaExport:296 - create table DEVICE (DEVICE_ID BIGINT not null, simServerPort INT, simServer VARCHAR(255), primary key (DEVICE_ID))
18:06:43,499 DEBUG SchemaExport:296 - create table GUI_DATA (GUI_DATA_ID BIGINT not null, label VARCHAR(255), defaultValue VARCHAR(255), DEVICE_ID BIGINT not null, primary key (GUI_DATA_ID))
18:06:43,530 DEBUG SchemaExport:296 - create table SUPPORTED_SERVICES (DEVICE_ID BIGINT not null, SUPPORTED_SERVICE VARCHAR(255))
18:06:43,546 DEBUG SchemaExport:296 - create table variableAttributes (DEVICE_ID BIGINT not null, ATTRIBUTE_VALUE VARCHAR(255), ATTRIBUTE_NAME VARCHAR(255) not null, primary key (DEVICE_ID, ATTRIBUTE_NAME))
18:06:43,577 DEBUG SchemaExport:296 - alter table GUI_DATA add constraint FKE1D1844E912B307F foreign key (DEVICE_ID) references DEVICE
18:06:43,593 DEBUG SchemaExport:296 - alter table SUPPORTED_SERVICES add constraint FK40F38CEF912B307F foreign key (DEVICE_ID) references DEVICE
18:06:43,624 DEBUG SchemaExport:296 - alter table variableAttributes add constraint FKE8EC6CF3912B307F foreign key (DEVICE_ID) references DEVICE
18:06:43,655 DEBUG SchemaExport:296 - create table hibernate_unique_key ( next_hi INT )
18:06:43,671 DEBUG SchemaExport:296 - insert into hibernate_unique_key values ( 0 )
18:06:43,702 INFO SchemaExport:202 - schema export complete
18:06:43,702 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
18:06:43,702 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,702 INFO SessionFactoryImpl:353 - Checking 0 named HQL queries
18:06:43,702 INFO SessionFactoryImpl:373 - Checking 0 named SQL queries
18:06:43,734 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'sessionFactory'
18:06:43,734 DEBUG DefaultListableBeanFactory:827 - Calling code asked for FactoryBean instance for name 'sessionFactory'
18:06:43,734 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'sessionFactory'
18:06:43,734 DEBUG DefaultListableBeanFactory:810 - Bean with name 'sessionFactory' is a factory bean
18:06:43,734 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'guiDataDao'
18:06:43,734 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'guiDataDao' with merged definition [Root bean: class [dao.spring.GuiDataSpringDaoImpl]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [applicationContext.xml]]
18:06:43,734 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'guiDataDao'
18:06:43,734 DEBUG CachedIntrospectionResults:141 - Getting BeanInfo for class [dao.spring.GuiDataSpringDaoImpl]
18:06:43,734 DEBUG CachedIntrospectionResults:157 - Caching PropertyDescriptors for class [dao.spring.GuiDataSpringDaoImpl]
18:06:43,734 DEBUG CachedIntrospectionResults:165 - Found property 'all' of type [java.util.Collection]
18:06:43,734 DEBUG CachedIntrospectionResults:165 - Found property 'class' of type [java.lang.Class]
18:06:43,734 DEBUG CachedIntrospectionResults:165 - Found property 'hibernateTemplate' of type [org.springframework.orm.hibernate3.HibernateTemplate]
18:06:43,749 DEBUG CachedIntrospectionResults:165 - Found property 'objectClass' of type [java.lang.Class]
18:06:43,749 DEBUG CachedIntrospectionResults:165 - Found property 'sessionFactory' of type [org.hibernate.SessionFactory]
18:06:43,749 DEBUG CachedIntrospectionResults:87 - Class [dao.spring.GuiDataSpringDaoImpl] is cache-safe
18:06:43,749 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'sessionFactory' in bean 'guiDataDao' to bean 'sessionFactory'
18:06:43,749 DEBUG CollectionFactory:90 - Creating [java.util.LinkedHashSet]
18:06:43,749 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'sessionFactory'
18:06:43,749 DEBUG DefaultListableBeanFactory:810 - Bean with name 'sessionFactory' is a factory bean
18:06:43,749 DEBUG BeanWrapperImpl:593 - About to invoke write method [public final void org.springframework.orm.hibernate3.support.HibernateDaoSupport.setSessionFactory(org.hibernate.SessionFactory)] on object of class [dao.spring.GuiDataSpringDaoImpl]
18:06:43,749 DEBUG BeanWrapperImpl:598 - Invoked write method [public final void org.springframework.orm.hibernate3.support.HibernateDaoSupport.setSessionFactory(org.hibernate.SessionFactory)] with value of type [org.hibernate.SessionFactory]
18:06:43,749 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'guiDataDao'
18:06:43,749 DEBUG DefaultListableBeanFactory:1057 - Invoking afterPropertiesSet() on bean with name 'guiDataDao'
18:06:43,749 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'guiDataDao'
18:06:43,749 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'deviceDao'
18:06:43,749 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'deviceDao' with merged definition [Root bean: class [dao.spring.DeviceSpringDaoImpl]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [applicationContext.xml]]
18:06:43,749 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'deviceDao'
18:06:43,749 DEBUG CachedIntrospectionResults:141 - Getting BeanInfo for class [dao.spring.DeviceSpringDaoImpl]
18:06:43,749 DEBUG CachedIntrospectionResults:157 - Caching PropertyDescriptors for class [dao.spring.DeviceSpringDaoImpl]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'all' of type [java.util.Collection]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'class' of type [java.lang.Class]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'hibernateTemplate' of type [org.springframework.orm.hibernate3.HibernateTemplate]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'objectClass' of type [java.lang.Class]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'sessionFactory' of type [org.hibernate.SessionFactory]
18:06:43,765 DEBUG CachedIntrospectionResults:87 - Class [dao.spring.DeviceSpringDaoImpl] is cache-safe
18:06:43,765 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'sessionFactory' in bean 'deviceDao' to bean 'sessionFactory'
18:06:43,765 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'sessionFactory'
18:06:43,765 DEBUG DefaultListableBeanFactory:810 - Bean with name 'sessionFactory' is a factory bean
18:06:43,765 DEBUG BeanWrapperImpl:593 - About to invoke write method [public final void org.springframework.orm.hibernate3.support.HibernateDaoSupport.setSessionFactory(org.hibernate.SessionFactory)] on object of class [dao.spring.DeviceSpringDaoImpl]
18:06:43,765 DEBUG BeanWrapperImpl:598 - Invoked write method [public final void org.springframework.orm.hibernate3.support.HibernateDaoSupport.setSessionFactory(org.hibernate.SessionFactory)] with value of type [org.hibernate.SessionFactory]
18:06:43,765 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'deviceDao'
18:06:43,765 DEBUG DefaultListableBeanFactory:1057 - Invoking afterPropertiesSet() on bean with name 'deviceDao'
18:06:43,765 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'deviceDao'
18:06:43,765 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'deviceOpsTarget'
18:06:43,765 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'deviceOpsTarget' with merged definition [Root bean: class [dao.spring.DeviceOpsSpringImpl]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [applicationContext.xml]]
18:06:43,765 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'deviceOpsTarget'
18:06:43,765 DEBUG CachedIntrospectionResults:141 - Getting BeanInfo for class [dao.spring.DeviceOpsSpringImpl]
18:06:43,765 DEBUG CachedIntrospectionResults:157 - Caching PropertyDescriptors for class [dao.spring.DeviceOpsSpringImpl]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'class' of type [java.lang.Class]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'deviceDao' of type [dao.DeviceDao]
18:06:43,765 DEBUG CachedIntrospectionResults:165 - Found property 'guiDataDao' of type [dao.GuiDataDao]
18:06:43,765 DEBUG CachedIntrospectionResults:87 - Class [dao.spring.DeviceOpsSpringImpl] is cache-safe
18:06:43,765 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'deviceDao' in bean 'deviceOpsTarget' to bean 'deviceDao'
18:06:43,765 DEBUG CollectionFactory:90 - Creating [java.util.LinkedHashSet]
18:06:43,765 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'deviceDao'
18:06:43,780 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'guiDataDao' in bean 'deviceOpsTarget' to bean 'guiDataDao'
18:06:43,780 DEBUG CollectionFactory:90 - Creating [java.util.LinkedHashSet]
18:06:43,780 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'guiDataDao'
18:06:43,780 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void dao.DeviceOpsImpl.setDeviceDao(dao.DeviceDao)] on object of class [dao.spring.DeviceOpsSpringImpl]
18:06:43,780 DEBUG BeanWrapperImpl:598 - Invoked write method [public void dao.DeviceOpsImpl.setDeviceDao(dao.DeviceDao)] with value of type [dao.DeviceDao]
18:06:43,780 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void dao.DeviceOpsImpl.setGuiDataDao(dao.GuiDataDao)] on object of class [dao.spring.DeviceOpsSpringImpl]
18:06:43,780 DEBUG BeanWrapperImpl:598 - Invoked write method [public void dao.DeviceOpsImpl.setGuiDataDao(dao.GuiDataDao)] with value of type [dao.GuiDataDao]
18:06:43,780 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'deviceOpsTarget'
18:06:43,780 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'deviceOpsTarget'
18:06:43,780 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'manager'
18:06:43,780 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'manager' with merged definition [Root bean: class [business.ManagerImpl]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [applicationContext.xml]]
18:06:43,780 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'manager'
18:06:43,780 DEBUG CachedIntrospectionResults:141 - Getting BeanInfo for class [business.ManagerImpl]
18:06:43,780 DEBUG CachedIntrospectionResults:157 - Caching PropertyDescriptors for class [business.ManagerImpl]
18:06:43,780 DEBUG CachedIntrospectionResults:165 - Found property 'class' of type [java.lang.Class]
18:06:43,780 DEBUG CachedIntrospectionResults:165 - Found property 'deviceDao' of type [dao.DeviceDao]
18:06:43,780 DEBUG CachedIntrospectionResults:165 - Found property 'deviceOps' of type [dao.DeviceOps]
18:06:43,780 DEBUG CachedIntrospectionResults:165 - Found property 'guiDataDao' of type [dao.GuiDataDao]
18:06:43,780 DEBUG CachedIntrospectionResults:87 - Class [business.ManagerImpl] is cache-safe
18:06:43,780 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'deviceDao' in bean 'manager' to bean 'deviceDao'
18:06:43,780 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'deviceDao'
18:06:43,780 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'guiDataDao' in bean 'manager' to bean 'guiDataDao'
18:06:43,780 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'guiDataDao'
18:06:43,780 DEBUG BeanDefinitionValueResolver:159 - Resolving reference from property 'deviceOps' in bean 'manager' to bean 'deviceOpsTarget'
18:06:43,780 DEBUG CollectionFactory:90 - Creating [java.util.LinkedHashSet]
18:06:43,780 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'deviceOpsTarget'
18:06:43,780 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void business.ManagerImpl.setDeviceDao(dao.DeviceDao)] on object of class [business.ManagerImpl]
18:06:43,780 DEBUG BeanWrapperImpl:598 - Invoked write method [public void business.ManagerImpl.setDeviceDao(dao.DeviceDao)] with value of type [dao.DeviceDao]
18:06:43,780 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void business.ManagerImpl.setGuiDataDao(dao.GuiDataDao)] on object of class [business.ManagerImpl]
18:06:43,780 DEBUG BeanWrapperImpl:598 - Invoked write method [public void business.ManagerImpl.setGuiDataDao(dao.GuiDataDao)] with value of type [dao.GuiDataDao]
18:06:43,780 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void business.ManagerImpl.setDeviceOps(dao.DeviceOps)] on object of class [business.ManagerImpl]
18:06:43,780 DEBUG BeanWrapperImpl:598 - Invoked write method [public void business.ManagerImpl.setDeviceOps(dao.DeviceOps)] with value of type [dao.DeviceOps]
18:06:43,780 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'manager'
18:06:43,796 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'manager'
18:06:43,796 DEBUG ClassPathXmlApplicationContext:223 - Publishing event in context [org.springframework.context.support.ClassPathXmlApplicationContext;hashCode=20181463]: org.springframework.context.event.ContextRefreshedEvent[source=org.springframework.context.support.ClassPathXmlApplicationContext: display name [org.springframework.context.support.ClassPathXmlApplicationContext;hashCode=20181463]; startup date [Mon Jan 02 18:06:41 MST 2006]; root of context hierarchy]
18:06:43,796 DEBUG DefaultListableBeanFactory:189 - Returning cached instance of singleton bean 'manager'
18:06:43,812 INFO XmlBeanDefinitionReader:163 - Loading XML bean definitions from class path resource [org/springframework/jdbc/support/sql-error-codes.xml]
18:06:43,812 DEBUG XmlBeanDefinitionReader:219 - Using JAXP implementation [com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl@ba5c7a]
18:06:43,812 DEBUG ResourceEntityResolver:50 - Trying to resolve XML entity with public ID [-//SPRING//DTD BEAN//EN] and system ID [http://www.springframework.org/dtd/spring-beans.dtd]
18:06:43,827 DEBUG ResourceEntityResolver:56 - Trying to locate [spring-beans.dtd] in Spring jar
18:06:43,827 DEBUG ResourceEntityResolver:64 - Found beans DTD [http://www.springframework.org/dtd/spring-beans.dtd] in classpath
18:06:43,859 DEBUG DefaultXmlBeanDefinitionParser:180 - Loading bean definitions
18:06:43,859 DEBUG DefaultXmlBeanDefinitionParser:185 - Default lazy init 'false'
18:06:43,859 DEBUG DefaultXmlBeanDefinitionParser:186 - Default autowire 'no'
18:06:43,859 DEBUG DefaultXmlBeanDefinitionParser:187 - Default dependency check 'none'
18:06:43,859 DEBUG DefaultXmlBeanDefinitionParser:193 - Found 8 <bean> elements in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]
18:06:43,859 DEBUG CollectionFactory:114 - Creating [java.util.LinkedHashMap]
18:06:43,859 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'DB2'
18:06:43,859 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'DB2' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,859 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'DB2'
18:06:43,859 DEBUG CachedIntrospectionResults:141 - Getting BeanInfo for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,859 DEBUG CachedIntrospectionResults:157 - Caching PropertyDescriptors for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,859 DEBUG CachedIntrospectionResults:165 - Found property 'badSqlGrammarCodes' of type [[Ljava.lang.String;]
18:06:43,859 DEBUG CachedIntrospectionResults:165 - Found property 'cannotAcquireLockCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'cannotSerializeTransactionCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'class' of type [java.lang.Class]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'customTranslations' of type [[Lorg.springframework.jdbc.support.CustomSQLErrorCodesTranslation;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'dataAccessResourceFailureCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'dataIntegrityViolationCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'databaseProductName' of type [java.lang.String]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'databaseProductNames' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'deadlockLoserCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'invalidResultSetAccessCodes' of type [[Ljava.lang.String;]
18:06:43,874 DEBUG CachedIntrospectionResults:165 - Found property 'useSqlStateForTranslation' of type [boolean]
18:06:43,874 DEBUG CachedIntrospectionResults:87 - Class [org.springframework.jdbc.support.SQLErrorCodes] is cache-safe
18:06:43,874 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,874 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] with value of type [java.lang.String]
18:06:43,890 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@340101]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,890 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@340101]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,890 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'DB2'
18:06:43,890 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'DB2'
18:06:43,890 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'HSQL'
18:06:43,890 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'HSQL' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,890 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'HSQL'
18:06:43,890 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] with value of type [java.lang.String]
18:06:43,890 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@2e1f1f]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,890 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@2e1f1f]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,890 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'HSQL'
18:06:43,890 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'HSQL'
18:06:43,890 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'MS-SQL'
18:06:43,890 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'MS-SQL' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,890 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'MS-SQL'
18:06:43,890 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,890 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] with value of type [java.lang.String]
18:06:43,890 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@fefe3f]
18:06:43,890 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@fefe3f]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'MS-SQL'
18:06:43,905 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'MS-SQL'
18:06:43,905 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'MySQL'
18:06:43,905 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'MySQL' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,905 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'MySQL'
18:06:43,905 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@e61a35]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@e61a35]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@e61a35]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDeadlockLoserCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDeadlockLoserCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'MySQL'
18:06:43,905 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'MySQL'
18:06:43,905 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'Oracle'
18:06:43,905 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'Oracle' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,905 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'Oracle'
18:06:43,905 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@c2b2f6]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@c2b2f6]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setInvalidResultSetAccessCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,905 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setInvalidResultSetAccessCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,905 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@c2b2f6]
18:06:43,905 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataAccessResourceFailureCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataAccessResourceFailureCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@c2b2f6]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@c2b2f6]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setCannotAcquireLockCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setCannotAcquireLockCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'Oracle'
18:06:43,921 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'Oracle'
18:06:43,921 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'Informix'
18:06:43,921 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'Informix' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,921 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'Informix'
18:06:43,921 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductName(java.lang.String)] with value of type [java.lang.String]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@737371]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@737371]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'Informix'
18:06:43,921 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'Informix'
18:06:43,921 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'PostgreSQL'
18:06:43,921 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'PostgreSQL' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,921 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'PostgreSQL'
18:06:43,921 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [boolean] using property editor [org.springframework.beans.propertyeditors.CustomBooleanEditor@1a001ff]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setUseSqlStateForTranslation(boolean)] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setUseSqlStateForTranslation(boolean)] with value of type [boolean]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@1e328e0]
18:06:43,921 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,921 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,921 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@1e328e0]
18:06:43,937 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,937 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,937 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'PostgreSQL'
18:06:43,937 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'PostgreSQL'
18:06:43,937 DEBUG DefaultListableBeanFactory:222 - Creating shared instance of singleton bean 'Sybase'
18:06:43,937 DEBUG DefaultListableBeanFactory:295 - Creating instance of bean 'Sybase' with merged definition [Root bean: class [org.springframework.jdbc.support.SQLErrorCodes]; abstract=false; singleton=true; lazyInit=false; autowire=0; dependencyCheck=0; initMethodName=null; destroyMethodName=null; factoryMethodName=null; factoryBeanName=null; defined in class path resource [org/springframework/jdbc/support/sql-error-codes.xml]]
18:06:43,937 DEBUG DefaultListableBeanFactory:224 - Invoking BeanPostProcessors before instantiation of bean 'Sybase'
18:06:43,937 DEBUG CachedIntrospectionResults:98 - Using cached introspection results for class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,937 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductNames(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,952 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDatabaseProductNames(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,952 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@90c06f]
18:06:43,952 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,952 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setBadSqlGrammarCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,952 DEBUG BeanWrapperImpl:767 - Converting String to [class [Ljava.lang.String;] using property editor [org.springframework.beans.propertyeditors.StringArrayPropertyEditor@90c06f]
18:06:43,952 DEBUG BeanWrapperImpl:593 - About to invoke write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] on object of class [org.springframework.jdbc.support.SQLErrorCodes]
18:06:43,952 DEBUG BeanWrapperImpl:598 - Invoked write method [public void org.springframework.jdbc.support.SQLErrorCodes.setDataIntegrityViolationCodes(java.lang.String[])] with value of type [[Ljava.lang.String;]
18:06:43,952 DEBUG DefaultListableBeanFactory:243 - Invoking BeanPostProcessors before initialization of bean 'Sybase'
18:06:43,952 DEBUG DefaultListableBeanFactory:262 - Invoking BeanPostProcessors after initialization of bean 'Sybase'
18:06:43,952 INFO SQLErrorCodesFactory:120 - SQLErrorCodes loaded: [DB2, HSQL, MS-SQL, MySQL, Oracle, Informix, PostgreSQL, Sybase]
18:06:43,952 DEBUG SQLErrorCodesFactory:170 - Looking up default SQLErrorCodes for DataSource [com.mchange.v2.c3p0.ComboPooledDataSource@1f934ad[ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, description -> null, driverClass -> solid.jdbc.SolidDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1f934ad, idleConnectionTestPeriod -> -1, initialPoolSize -> 3, jdbcUrl -> jdbc:solid://localhost:16714, loginTimeout -> 0, maxIdleTime -> 0, maxPoolSize -> 20, maxStatements -> 50, maxStatementsPerConnection -> 0, minPoolSize -> 5, numHelperThreads -> 3, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ]]
18:06:43,968 DEBUG DataSourceUtils:110 - Fetching JDBC Connection from DataSource
18:06:43,968 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,968 DEBUG DataSourceUtils:276 - Returning JDBC Connection to DataSource
18:06:43,968 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
18:06:43,968 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:43,968 DEBUG SQLErrorCodesFactory:225 - Database product name cached for DataSource [com.mchange.v2.c3p0.ComboPooledDataSource@1f934ad[ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, description -> null, driverClass -> solid.jdbc.SolidDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1f934ad, idleConnectionTestPeriod -> -1, initialPoolSize -> 3, jdbcUrl -> jdbc:solid://localhost:16714, loginTimeout -> 0, maxIdleTime -> 0, maxPoolSize -> 20, maxStatements -> 50, maxStatementsPerConnection -> 0, minPoolSize -> 5, numHelperThreads -> 3, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ]]: name is 'Solid Database Engine'
18:06:43,968 DEBUG SQLErrorCodesFactory:254 - SQL error codes for 'Solid Database Engine' not found
18:06:43,984 DEBUG SessionFactoryUtils:329 - Opening Hibernate Session
18:06:44,015 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,030 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@1ad98ef ).size(): 1
18:06:44,030 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 1; num connections: 1; num keys: 1
18:06:44,062 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 0; num connections: 1; num keys: 1
18:06:44,077 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@1ad98ef ).size(): 2
18:06:44,077 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 1; num connections: 1; num keys: 2
18:06:44,077 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 0; num connections: 1; num keys: 2
18:06:44,109 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 0; num connections: 1; num keys: 2
18:06:44,124 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,140 DEBUG HibernateTemplate:393 - Eagerly flushing Hibernate session
18:06:44,140 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,155 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@1815bfb ).size(): 1
18:06:44,155 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 3; checked out: 1; num connections: 2; num keys: 3
18:06:44,187 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 3; checked out: 0; num connections: 2; num keys: 3
18:06:44,187 DEBUG SessionFactoryUtils:785 - Closing Hibernate Session
18:06:44,187 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 3; checked out: 0; num connections: 2; num keys: 3
18:06:44,187 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,187 ERROR SpringDaoImpl:44 - Saved class model.Device: 0.391
18:06:44,187 DEBUG SQLErrorCodesFactory:170 - Looking up default SQLErrorCodes for DataSource [com.mchange.v2.c3p0.ComboPooledDataSource@1f934ad[ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, description -> null, driverClass -> solid.jdbc.SolidDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1f934ad, idleConnectionTestPeriod -> -1, initialPoolSize -> 3, jdbcUrl -> jdbc:solid://localhost:16714, loginTimeout -> 0, maxIdleTime -> 0, maxPoolSize -> 20, maxStatements -> 50, maxStatementsPerConnection -> 0, minPoolSize -> 5, numHelperThreads -> 3, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ]]
18:06:44,202 DEBUG SQLErrorCodesFactory:177 - Database product name found in cache for DataSource [com.mchange.v2.c3p0.ComboPooledDataSource@1f934ad[ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, description -> null, driverClass -> solid.jdbc.SolidDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1f934ad, idleConnectionTestPeriod -> -1, initialPoolSize -> 3, jdbcUrl -> jdbc:solid://localhost:16714, loginTimeout -> 0, maxIdleTime -> 0, maxPoolSize -> 20, maxStatements -> 50, maxStatementsPerConnection -> 0, minPoolSize -> 5, numHelperThreads -> 3, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ]]: name is 'Solid Database Engine'
18:06:44,202 DEBUG SQLErrorCodesFactory:254 - SQL error codes for 'Solid Database Engine' not found
18:06:44,202 DEBUG SessionFactoryUtils:329 - Opening Hibernate Session
18:06:44,202 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,202 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@401369 ).size(): 1
18:06:44,202 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 4; checked out: 1; num connections: 3; num keys: 4
18:06:44,202 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 4; checked out: 0; num connections: 3; num keys: 4
18:06:44,202 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@401369 ).size(): 2
18:06:44,202 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 5; checked out: 1; num connections: 3; num keys: 5
18:06:44,202 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 5; checked out: 0; num connections: 3; num keys: 5
18:06:44,218 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 5; checked out: 0; num connections: 3; num keys: 5
18:06:44,218 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,218 DEBUG HibernateTemplate:393 - Eagerly flushing Hibernate session
18:06:44,234 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 3, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,249 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@e6ff0d ).size(): 1
18:06:44,249 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 6; checked out: 1; num connections: 4; num keys: 6
18:06:44,280 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 6; checked out: 0; num connections: 4; num keys: 6
18:06:44,280 DEBUG SessionFactoryUtils:785 - Closing Hibernate Session
18:06:44,280 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 6; checked out: 0; num connections: 4; num keys: 6
18:06:44,280 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,280 ERROR SpringDaoImpl:44 - Saved class model.GuiData: 0.093
18:06:44,280 DEBUG SessionFactoryUtils:329 - Opening Hibernate Session
18:06:44,280 DEBUG HibernateTemplate:393 - Eagerly flushing Hibernate session
18:06:44,280 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 3, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,280 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@178460d ).size(): 1
18:06:44,280 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 7; checked out: 1; num connections: 5; num keys: 7
18:06:44,312 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 7; checked out: 0; num connections: 5; num keys: 7
18:06:44,312 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@178460d ).size(): 2
18:06:44,312 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 8; checked out: 1; num connections: 5; num keys: 8
18:06:44,327 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 8; checked out: 0; num connections: 5; num keys: 8
18:06:44,343 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@178460d ).size(): 3
18:06:44,343 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 9; checked out: 1; num connections: 5; num keys: 9
18:06:44,374 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 9; checked out: 0; num connections: 5; num keys: 9
18:06:44,374 DEBUG GooGooStatementCache:381 - cxnStmtMgr.statementSet( solid.jdbc.SolidConnection@178460d ).size(): 4
18:06:44,374 DEBUG GooGooStatementCache:117 - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 10; checked out: 1; num connections: 5; num keys: 10
18:06:44,421 DEBUG GooGooStatementCache:171 - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 10; checked out: 0; num connections: 5; num keys: 10
18:06:44,421 DEBUG SessionFactoryUtils:785 - Closing Hibernate Session
18:06:44,421 DEBUG GooGooStatementCache:197 - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 10; checked out: 0; num connections: 5; num keys: 10
18:06:44,421 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,421 ERROR SpringDaoImpl:44 - Saved class model.Device: 0.141
18:06:44,421 DEBUG SessionFactoryUtils:329 - Opening Hibernate Session
18:06:44,421 DEBUG HibernateTemplate:393 - Eagerly flushing Hibernate session
18:06:44,437 DEBUG BasicResourcePool:1161 - trace com.mchange.v2.resourcepool.BasicResourcePool@104faf8 [managed: 5, unused: 4, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@15ac3c9)
18:06:44,437 DEBUG GooGooStatemen