ibatis-user-java mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "M. Goodell" <mggl...@comcast.net>
Subject RE: SQL-Map / CDATA Performance Issue
Date Sun, 01 Feb 2009 01:00:25 GMT

Here is a log from a 10 record insertion. (My log4j set to ALL)

log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,093 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,125 main
org.springframework.beans.factory.xml.XmlBeanDefinitionReader INFO - Loading
XML bean definitions from class path resource [spring.xml]
log4j2009-01-31 17:57:30,140 main
org.springframework.beans.factory.xml.DefaultDocumentLoader DEBUG - Using
JAXP provider
[com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl]
log4j2009-01-31 17:57:30,203 main
org.springframework.beans.factory.xml.PluggableSchemaResolver TRACE - Trying
to resolve XML entity with public id [null] and system id
[http://www.springframework.org/schema/beans/spring-beans-2.5.xsd]
log4j2009-01-31 17:57:30,203 main
org.springframework.beans.factory.xml.PluggableSchemaResolver DEBUG -
Loading schema mappings from [META-INF/spring.schemas]
log4j2009-01-31 17:57:30,203 main
org.springframework.beans.factory.xml.PluggableSchemaResolver DEBUG - Loaded
schema mappings:
{http://www.springframework.org/schema/lang/spring-lang-2.5.xsd=org/springfr
amework/scripting/config/spring-lang-2.5.xsd,
http://www.springframework.org/schema/lang/spring-lang.xsd=org/springframewo
rk/scripting/config/spring-lang-2.5.xsd,
http://www.springframework.org/schema/context/spring-context-2.5.xsd=org/spr
ingframework/context/config/spring-context-2.5.xsd,
http://www.springframework.org/schema/jms/spring-jms-2.5.xsd=org/springframe
work/jms/config/spring-jms-2.5.xsd,
http://www.springframework.org/schema/context/spring-context.xsd=org/springf
ramework/context/config/spring-context-2.5.xsd,
http://www.springframework.org/schema/aop/spring-aop.xsd=org/springframework
/aop/config/spring-aop-2.5.xsd,
http://www.springframework.org/schema/util/spring-util-2.0.xsd=org/springfra
mework/beans/factory/xml/spring-util-2.0.xsd,
http://www.springframework.org/schema/util/spring-util-2.5.xsd=org/springfra
mework/beans/factory/xml/spring-util-2.5.xsd,
http://www.springframework.org/schema/tool/spring-tool-2.0.xsd=org/springfra
mework/beans/factory/xml/spring-tool-2.0.xsd,
http://www.springframework.org/schema/tx/spring-tx-2.0.xsd=org/springframewo
rk/transaction/config/spring-tx-2.0.xsd,
http://www.springframework.org/schema/tool/spring-tool-2.5.xsd=org/springfra
mework/beans/factory/xml/spring-tool-2.5.xsd,
http://www.springframework.org/schema/tx/spring-tx-2.5.xsd=org/springframewo
rk/transaction/config/spring-tx-2.5.xsd,
http://www.springframework.org/schema/beans/spring-beans-2.0.xsd=org/springf
ramework/beans/factory/xml/spring-beans-2.0.xsd,
http://www.springframework.org/schema/jms/spring-jms.xsd=org/springframework
/jms/config/spring-jms-2.5.xsd,
http://www.springframework.org/schema/beans/spring-beans-2.5.xsd=org/springf
ramework/beans/factory/xml/spring-beans-2.5.xsd,
http://www.springframework.org/schema/beans/spring-beans.xsd=org/springframe
work/beans/factory/xml/spring-beans-2.5.xsd,
http://www.springframework.org/schema/jee/spring-jee.xsd=org/springframework
/ejb/config/spring-jee-2.5.xsd,
http://www.springframework.org/schema/tool/spring-tool.xsd=org/springframewo
rk/beans/factory/xml/spring-tool-2.5.xsd,
http://www.springframework.org/schema/tx/spring-tx.xsd=org/springframework/t
ransaction/config/spring-tx-2.5.xsd,
http://www.springframework.org/schema/jee/spring-jee-2.0.xsd=org/springframe
work/ejb/config/spring-jee-2.0.xsd,
http://www.springframework.org/schema/aop/spring-aop-2.0.xsd=org/springframe
work/aop/config/spring-aop-2.0.xsd,
http://www.springframework.org/schema/aop/spring-aop-2.5.xsd=org/springframe
work/aop/config/spring-aop-2.5.xsd,
http://www.springframework.org/schema/jee/spring-jee-2.5.xsd=org/springframe
work/ejb/config/spring-jee-2.5.xsd,
http://www.springframework.org/schema/lang/spring-lang-2.0.xsd=org/springfra
mework/scripting/config/spring-lang-2.0.xsd,
http://www.springframework.org/schema/util/spring-util.xsd=org/springframewo
rk/beans/factory/xml/spring-util-2.5.xsd}
log4j2009-01-31 17:57:30,203 main
org.springframework.beans.factory.xml.PluggableSchemaResolver DEBUG - Found
XML schema
[http://www.springframework.org/schema/beans/spring-beans-2.5.xsd] in
classpath: org/springframework/beans/factory/xml/spring-beans-2.5.xsd
log4j2009-01-31 17:57:30,250 main
org.springframework.beans.factory.xml.PluggableSchemaResolver TRACE - Trying
to resolve XML entity with public id [null] and system id
[http://www.springframework.org/schema/tx/spring-tx-2.5.xsd]
log4j2009-01-31 17:57:30,250 main
org.springframework.beans.factory.xml.PluggableSchemaResolver DEBUG - Found
XML schema [http://www.springframework.org/schema/tx/spring-tx-2.5.xsd] in
classpath: org/springframework/transaction/config/spring-tx-2.5.xsd
log4j2009-01-31 17:57:30,265 main
org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader
DEBUG - Loading bean definitions
log4j2009-01-31 17:57:30,296 main
org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver
DEBUG - Loaded mappings
[{http://www.springframework.org/schema/p=org.springframework.beans.factory.
xml.SimplePropertyNamespaceHandler,
http://www.springframework.org/schema/lang=org.springframework.scripting.con
fig.LangNamespaceHandler,
http://www.springframework.org/schema/jee=org.springframework.ejb.config.Jee
NamespaceHandler,
http://www.springframework.org/schema/aop=org.springframework.aop.config.Aop
NamespaceHandler,
http://www.springframework.org/schema/util=org.springframework.beans.factory
.xml.UtilNamespaceHandler,
http://www.springframework.org/schema/jms=org.springframework.jms.config.Jms
NamespaceHandler,
http://www.springframework.org/schema/tx=org.springframework.transaction.con
fig.TxNamespaceHandler,
http://www.springframework.org/schema/context=org.springframework.context.co
nfig.ContextNamespaceHandler}]
log4j2009-01-31 17:57:30,343 main
org.springframework.beans.factory.xml.XmlBeanDefinitionReader DEBUG - Loaded
8 bean definitions from location pattern [spring.xml]
log4j2009-01-31 17:57:30,343 main
org.springframework.context.support.ClassPathXmlApplicationContext INFO -
Bean factory for application context
[org.springframework.context.support.ClassPathXmlApplicationContext@1f7d134]
:
org.springframework.beans.factory.support.DefaultListableBeanFactory@d08633
log4j2009-01-31 17:57:30,343 main
org.springframework.context.support.ClassPathXmlApplicationContext DEBUG - 8
beans defined in
org.springframework.context.support.ClassPathXmlApplicationContext@1f7d134:
display name
[org.springframework.context.support.ClassPathXmlApplicationContext@1f7d134]
; startup date [Sat Jan 31 17:57:30 MST 2009]; root of context hierarchy
log4j2009-01-31 17:57:30,359 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean
'org.springframework.aop.config.internalAutoProxyCreator'
log4j2009-01-31 17:57:30,359 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean
'org.springframework.aop.config.internalAutoProxyCreator'
log4j2009-01-31 17:57:30,359 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,390 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean
'org.springframework.aop.config.internalAutoProxyCreator' to allow for
resolving potential circular references
log4j2009-01-31 17:57:30,390 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class
[org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyC
reator]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyC
reator]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'advisorAdapterRegistry' of type
[org.springframework.aop.framework.adapter.AdvisorAdapterRegistry]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'applyCommonInterceptorsFirst' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'beanClassLoader' of type [java.lang.ClassLoader]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'beanFactory' of type
[org.springframework.beans.factory.BeanFactory]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'customTargetSourceCreators' of type
[[Lorg.springframework.aop.framework.autoproxy.TargetSourceCreator;]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'exposeProxy' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'frozen' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'interceptorNames' of type [[Ljava.lang.String;]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'opaque' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'optimize' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'order' of type [int]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'proxyClassLoader' of type [java.lang.ClassLoader]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'proxyTargetClass' of type [boolean]
log4j2009-01-31 17:57:30,406 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean
'org.springframework.aop.config.internalAutoProxyCreator'
log4j2009-01-31 17:57:30,406 main
org.springframework.context.support.ClassPathXmlApplicationContext DEBUG -
Unable to locate MessageSource with name 'messageSource': using default
[org.springframework.context.support.DelegatingMessageSource@1431340]
log4j2009-01-31 17:57:30,421 main
org.springframework.context.support.ClassPathXmlApplicationContext DEBUG -
Unable to locate ApplicationEventMulticaster with name
'applicationEventMulticaster': using default
[org.springframework.context.event.SimpleApplicationEventMulticaster@fd68b1]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory INFO -
Pre-instantiating singletons in
org.springframework.beans.factory.support.DefaultListableBeanFactory@d08633:
defining beans
[org.springframework.aop.config.internalAutoProxyCreator,org.springframework
.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springfra
mework.transaction.interceptor.TransactionInterceptor#0,org.springframework.
transaction.config.internalTransactionAdvisor,dataSource,transactionManager,
sqlMapClient,personServiceDAO]; root of factory hierarchy
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.aop.config.internalAutoProxyCreator'
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0'
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0'
log4j2009-01-31 17:57:30,421 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0' to allow for resolving potential circular references
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class
[org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,421 main
org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyCr
eator TRACE - Did not attempt to auto-proxy infrastructure class
[org.springframework.transaction.interceptor.BeanFactoryTransactionAttribute
SourceAdvisor]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean
'org.springframework.transaction.config.internalTransactionAdvisor' to allow
for resolving potential circular references
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class
[org.springframework.transaction.interceptor.BeanFactoryTransactionAttribute
SourceAdvisor]
log4j2009-01-31 17:57:30,421 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.transaction.interceptor.BeanFactoryTransactionAttribute
SourceAdvisor]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'advice' of type [org.aopalliance.aop.Advice]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'adviceBeanName' of type [java.lang.String]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'beanFactory' of type
[org.springframework.beans.factory.BeanFactory]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'classFilter' of type [org.springframework.aop.ClassFilter]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'order' of type [int]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'perInstance' of type [boolean]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'pointcut' of type [org.springframework.aop.Pointcut]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionAttributeSource' of type
[org.springframework.transaction.interceptor.TransactionAttributeSource]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning eagerly cached instance of singleton bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0' that is not fully initialized yet - a consequence of a circular
reference
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0'
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean
'org.springframework.transaction.interceptor.TransactionInterceptor#0'
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean
'org.springframework.transaction.interceptor.TransactionInterceptor#0'
log4j2009-01-31 17:57:30,437 main
org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyCr
eator TRACE - Did not attempt to auto-proxy infrastructure class
[org.springframework.transaction.interceptor.TransactionInterceptor]
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean
'org.springframework.transaction.interceptor.TransactionInterceptor#0' to
allow for resolving potential circular references
log4j2009-01-31 17:57:30,437 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class
[org.springframework.transaction.interceptor.TransactionInterceptor]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.transaction.interceptor.TransactionInterceptor]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionAttributeSource' of type
[org.springframework.transaction.interceptor.TransactionAttributeSource]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionAttributeSources' of type
[[Lorg.springframework.transaction.interceptor.TransactionAttributeSource;]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionAttributes' of type [java.util.Properties]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionManager' of type
[org.springframework.transaction.PlatformTransactionManager]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean 'transactionManager'
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean 'transactionManager'
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean 'transactionManager' to allow for resolving potential
circular references
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class
[org.springframework.jdbc.datasource.DataSourceTransactionManager]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.jdbc.datasource.DataSourceTransactionManager]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'dataSource' of type [javax.sql.DataSource]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'defaultTimeout' of type [int]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'failEarlyOnGlobalRollbackOnly' of type [boolean]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'globalRollbackOnParticipationFailure' of type [boolean]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'nestedTransactionAllowed' of type [boolean]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'resourceFactory' of type [java.lang.Object]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'rollbackOnCommitFailure' of type [boolean]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionSynchronization' of type [int]
log4j2009-01-31 17:57:30,453 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionSynchronizationName' of type [java.lang.String]
log4j2009-01-31 17:57:30,468 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'validateExistingTransaction' of type [boolean]
log4j2009-01-31 17:57:30,468 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean 'dataSource'
log4j2009-01-31 17:57:30,468 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean 'dataSource'
log4j2009-01-31 17:57:30,468 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean 'dataSource' to allow for resolving potential circular
references
log4j2009-01-31 17:57:30,468 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class [org.apache.commons.dbcp.BasicDataSource]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class [org.apache.commons.dbcp.BasicDataSource]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'accessToUnderlyingConnectionAllowed' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'connection' of type [java.sql.Connection]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'defaultAutoCommit' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'defaultCatalog' of type [java.lang.String]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'defaultReadOnly' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'defaultTransactionIsolation' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'driverClassName' of type [java.lang.String]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'initialSize' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'logAbandoned' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'logWriter' of type [java.io.PrintWriter]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'loginTimeout' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'maxActive' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'maxIdle' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'maxOpenPreparedStatements' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'maxWait' of type [long]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'minEvictableIdleTimeMillis' of type [long]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'minIdle' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'numActive' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'numIdle' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'numTestsPerEvictionRun' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'password' of type [java.lang.String]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'poolPreparedStatements' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'removeAbandoned' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'removeAbandonedTimeout' of type [int]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'testOnBorrow' of type [boolean]
log4j2009-01-31 17:57:30,484 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'testOnReturn' of type [boolean]
log4j2009-01-31 17:57:30,500 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'testWhileIdle' of type [boolean]
log4j2009-01-31 17:57:30,500 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'timeBetweenEvictionRunsMillis' of type [long]
log4j2009-01-31 17:57:30,500 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'url' of type [java.lang.String]
log4j2009-01-31 17:57:30,500 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'username' of type [java.lang.String]
log4j2009-01-31 17:57:30,500 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'validationQuery' of type [java.lang.String]
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.TypeConverterDelegate TRACE - Converting String to
[int] using property editor
[org.springframework.beans.propertyeditors.CustomNumberEditor@fbb7cb]
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.TypeConverterDelegate TRACE - Converting String to
[int] using property editor
[org.springframework.beans.propertyeditors.CustomNumberEditor@fbb7cb]
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean 'dataSource'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Invoking afterPropertiesSet() on bean with name 'transactionManager'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean 'transactionManager'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.annotation.AnnotationTransactionAttributeSo
urce#0'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Invoking afterPropertiesSet() on bean with name
'org.springframework.transaction.interceptor.TransactionInterceptor#0'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean
'org.springframework.transaction.interceptor.TransactionInterceptor#0'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean 'dataSource'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean 'transactionManager'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean 'sqlMapClient'
log4j2009-01-31 17:57:30,515 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean 'sqlMapClient'
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean 'sqlMapClient' to allow for resolving potential
circular references
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class [org.springframework.orm.ibatis.SqlMapClientFactoryBean]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class
[org.springframework.orm.ibatis.SqlMapClientFactoryBean]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'configLocation' of type [org.springframework.core.io.Resource]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'configLocations' of type [[Lorg.springframework.core.io.Resource;]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'dataSource' of type [javax.sql.DataSource]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'lobHandler' of type
[org.springframework.jdbc.support.lob.LobHandler]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'mappingLocations' of type
[[Lorg.springframework.core.io.Resource;]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'object' of type [java.lang.Object]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'objectType' of type [java.lang.Class]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'singleton' of type [boolean]
log4j2009-01-31 17:57:30,531 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'sqlMapClientProperties' of type [java.util.Properties]
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionConfigClass' of type [java.lang.Class]
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'transactionConfigProperties' of type [java.util.Properties]
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'useTransactionAwareDataSource' of type [boolean]
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean 'dataSource'
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.TypeConverterDelegate TRACE - Converting String to
[interface org.springframework.core.io.Resource] using property editor
[org.springframework.core.io.ResourceEditor@25d2b2]
log4j2009-01-31 17:57:30,562 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Invoking afterPropertiesSet() on bean with name 'sqlMapClient'
log4j2009-01-31 17:57:30,687 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,687 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean 'sqlMapClient'
log4j2009-01-31 17:57:30,687 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating shared instance of singleton bean 'personServiceDAO'
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Creating instance of bean 'personServiceDAO'
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Eagerly caching bean 'personServiceDAO' to allow for resolving potential
circular references
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Getting
BeanInfo for class [springibatis.PersonServiceDAO]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Caching
PropertyDescriptors for class [springibatis.PersonServiceDAO]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'class' of type [java.lang.Class]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'dataSource' of type [javax.sql.DataSource]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'person'
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'sqlMapClient' of type [com.ibatis.sqlmap.client.SqlMapClient]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.CachedIntrospectionResults TRACE - Found bean
property 'sqlMapClientTemplate' of type
[org.springframework.orm.ibatis.SqlMapClientTemplate]
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean 'sqlMapClient'
log4j2009-01-31 17:57:30,703 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,718 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Invoking afterPropertiesSet() on bean with name 'personServiceDAO'
log4j2009-01-31 17:57:30,718 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.config.internalTransactionAdvisor'
log4j2009-01-31 17:57:30,750 main
org.springframework.transaction.annotation.AnnotationTransactionAttributeSou
rce DEBUG - Adding transactional method [insertBulkTest] with attribute
[PROPAGATION_REQUIRED,ISOLATION_DEFAULT]
log4j2009-01-31 17:57:30,750 main org.springframework.core.CollectionFactory
TRACE - Creating [java.util.concurrent.ConcurrentHashMap]
log4j2009-01-31 17:57:30,750 main
org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyCr
eator DEBUG - Creating implicit proxy for bean 'personServiceDAO' with 0
common interceptors and 1 specific interceptors
log4j2009-01-31 17:57:30,750 main
org.springframework.aop.framework.JdkDynamicAopProxy DEBUG - Creating JDK
dynamic proxy: target source is SingletonTargetSource for target object
[springibatis.PersonServiceDAO@3411a]
log4j2009-01-31 17:57:30,765 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Finished creating instance of bean 'personServiceDAO'
log4j2009-01-31 17:57:30,765 main
org.springframework.context.support.ClassPathXmlApplicationContext TRACE -
Publishing event in context
[org.springframework.context.support.ClassPathXmlApplicationContext@1f7d134]
:
org.springframework.context.event.ContextRefreshedEvent[source=org.springfra
mework.context.support.ClassPathXmlApplicationContext@1f7d134: display name
[org.springframework.context.support.ClassPathXmlApplicationContext@1f7d134]
; startup date [Sat Jan 31 17:57:30 MST 2009]; root of context hierarchy]
log4j2009-01-31 17:57:30,765 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean 'personServiceDAO'
log4j2009-01-31 17:57:30,765 main
org.springframework.beans.factory.support.DefaultListableBeanFactory DEBUG -
Returning cached instance of singleton bean
'org.springframework.transaction.interceptor.TransactionInterceptor#0'
log4j2009-01-31 17:57:30,781 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Creating new transaction with name
[springibatis.PersonService.insertBulkTest]:
PROPAGATION_REQUIRED,ISOLATION_DEFAULT
log4j2009-01-31 17:57:31,250 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Acquired Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost,
MySQL-AB JDBC Driver] for JDBC transaction
log4j2009-01-31 17:57:31,265 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Switching JDBC Connection [jdbc:mysql://localhost/mgg,
UserName=michael@localhost, MySQL-AB JDBC Driver] to manual commit
log4j2009-01-31 17:57:31,265 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Bound value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] to thread [main]
log4j2009-01-31 17:57:31,265 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Initializing transaction synchronization
log4j2009-01-31 17:57:31,265 main
org.springframework.transaction.interceptor.TransactionInterceptor TRACE -
Getting transaction for [springibatis.PersonService.insertBulkTest]
log4j2009-01-31 17:57:31,265 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@1f48262] for
iBATIS operation
log4j2009-01-31 17:57:31,281 main java.sql.Connection DEBUG - {conn-100000}
Connection
log4j2009-01-31 17:57:31,296 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,296 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,296 main java.sql.Connection DEBUG - {conn-100000}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,312 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100001} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100001} Parameters: [Load, Bulk, 0]
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100001} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,343 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@d09ad3] for
iBATIS operation
log4j2009-01-31 17:57:31,343 main java.sql.Connection DEBUG - {conn-100002}
Connection
log4j2009-01-31 17:57:31,343 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,343 main java.sql.Connection DEBUG - {conn-100002}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,343 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100003} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100003} Parameters: [Load, Bulk, 1]
log4j2009-01-31 17:57:31,343 main java.sql.PreparedStatement DEBUG -
{pstm-100003} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,343 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@6295eb] for
iBATIS operation
log4j2009-01-31 17:57:31,343 main java.sql.Connection DEBUG - {conn-100004}
Connection
log4j2009-01-31 17:57:31,343 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,343 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,343 main java.sql.Connection DEBUG - {conn-100004}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100005} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100005} Parameters: [Load, Bulk, 2]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100005} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@1712b3a] for
iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100006}
Connection
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100006}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100007} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100007} Parameters: [Load, Bulk, 3]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100007} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@ba5c7a] for
iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100008}
Connection
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100008}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100009} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100009} Parameters: [Load, Bulk, 4]
log4j2009-01-31 17:57:31,359 main java.sql.PreparedStatement DEBUG -
{pstm-100009} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@7cbde6] for
iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100010}
Connection
log4j2009-01-31 17:57:31,359 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,359 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,359 main java.sql.Connection DEBUG - {conn-100010}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100011} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100011} Parameters: [Load, Bulk, 5]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100011} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@b7ec5d] for
iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100012}
Connection
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100012}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100013} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100013} Parameters: [Load, Bulk, 6]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100013} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@c2b2f6] for
iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100014}
Connection
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100014}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100015} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100015} Parameters: [Load, Bulk, 7]
log4j2009-01-31 17:57:31,375 main java.sql.PreparedStatement DEBUG -
{pstm-100015} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@b60b93] for
iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100016}
Connection
log4j2009-01-31 17:57:31,375 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,375 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,375 main java.sql.Connection DEBUG - {conn-100016}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100017} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100017} Parameters: [Load, Bulk, 8]
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100017} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,390 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Opened
SqlMapSession [com.ibatis.sqlmap.engine.impl.SqlMapSessionImpl@178460d] for
iBATIS operation
log4j2009-01-31 17:57:31,390 main java.sql.Connection DEBUG - {conn-100018}
Connection
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,390 main
org.springframework.orm.ibatis.SqlMapClientTemplate DEBUG - Obtained JDBC
Connection [jdbc:mysql://localhost/mgg, UserName=michael@localhost, MySQL-AB
JDBC Driver] for iBATIS operation
log4j2009-01-31 17:57:31,390 main java.sql.Connection DEBUG - {conn-100018}
Preparing Statement:          INSERT INTO people (
last_name,                 first_name,                 age             )
VALUES (                 ?,                 ?,?             );
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100019} Executing Statement:          INSERT INTO people
(                 last_name,                 first_name,
    age             ) VALUES (                 ?,
    ?,?             );
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100019} Parameters: [Load, Bulk, 9]
log4j2009-01-31 17:57:31,390 main java.sql.PreparedStatement DEBUG -
{pstm-100019} Types: [java.lang.String, java.lang.String, java.lang.Integer]
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Retrieved value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] bound to thread [main]
log4j2009-01-31 17:57:31,390 main
org.springframework.transaction.interceptor.TransactionInterceptor TRACE -
Completing transaction for [springibatis.PersonService.insertBulkTest]
log4j2009-01-31 17:57:31,390 main
org.springframework.jdbc.datasource.DataSourceTransactionManager TRACE -
Triggering beforeCommit synchronization
log4j2009-01-31 17:57:31,390 main
org.springframework.jdbc.datasource.DataSourceTransactionManager TRACE -
Triggering beforeCompletion synchronization
log4j2009-01-31 17:57:31,390 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Initiating transaction commit
log4j2009-01-31 17:57:31,406 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Committing JDBC transaction on Connection [jdbc:mysql://localhost/mgg,
UserName=michael@localhost, MySQL-AB JDBC Driver]
log4j2009-01-31 17:57:31,406 main
org.springframework.jdbc.datasource.DataSourceTransactionManager TRACE -
Triggering afterCommit synchronization
log4j2009-01-31 17:57:31,406 main
org.springframework.jdbc.datasource.DataSourceTransactionManager TRACE -
Triggering afterCompletion synchronization
log4j2009-01-31 17:57:31,406 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Clearing transaction synchronization
log4j2009-01-31 17:57:31,406 main
org.springframework.transaction.support.TransactionSynchronizationManager
TRACE - Removed value
[org.springframework.jdbc.datasource.ConnectionHolder@15db314] for key
[org.apache.commons.dbcp.BasicDataSource@137d090] from thread [main]
log4j2009-01-31 17:57:31,406 main
org.springframework.jdbc.datasource.DataSourceTransactionManager DEBUG -
Releasing JDBC Connection [jdbc:mysql://localhost/mgg,
UserName=michael@localhost, MySQL-AB JDBC Driver] after transaction
log4j2009-01-31 17:57:31,406 main
org.springframework.jdbc.datasource.DataSourceUtils DEBUG - Returning JDBC
Connection to DataSource
log4j2009-01-31 17:57:31,406 main springibatis.Main INFO - StopWatch '':
running time (millis) = 641
-----------------------------------------
ms     %     Task name
-----------------------------------------
00641  100%

-----Original Message-----
From: Clinton Begin [mailto:clinton.begin@gmail.com]
Sent: Saturday, January 31, 2009 3:39 PM
To: user-java@ibatis.apache.org; mgglist@comcast.net
Subject: Re: SQL-Map / CDATA Performance Issue


Do you have statememnt logging enabled?

On 1/31/09, MGG List Subscription <mgglist@comcast.net> wrote:
> When I format the SQL within my insert element like Example 1.0 and time
> several application calls the performance is degraded *substantially*.
> However, if I format the SQL as shown in Example 1.1 the performance
> improves dramatically.
>
> It seems the larger the SQL statement and the more "pretty" formatting
that
> is applied to the embedded SQL statement causes the performance to
degrade.
> I have read the manual sections on applying the CDATA XML tag to the SQL
but
> it seems to not make a difference unlsess the SQL is compacted on to one
> line.
>
> Thank you in advance for any help!
>
> Example 1.0:
>
> <insert id="insertPerson" parameterClass="springibatis.Person">
> 	<![CDATA[
>       	INSERT INTO people (
>             	last_name,
>                   first_name,
>                   age
>                 ) VALUES (
>                   #lastName#,
>                   #firstName#,
>                   #age#
>                 );
>         ]]>
>     </insert>
>
>
> Example 1.1:
>
> <insert id="insertPerson" parameterClass="springibatis.Person">
> 	<![CDATA[
>       	INSERT INTO people (last_name, first_name, age) VALUES (#lastName#,
> #firstName#, #age#);
> 	]]>
> </insert>
> No virus found in this outgoing message.
> Checked by AVG - www.avg.com
> Version: 8.0.233 / Virus Database: 270.10.16/1926 - Release Date: 01/30/09
> 17:31:00
>
>

--
Sent from my mobile device
No virus found in this incoming message.
Checked by AVG - www.avg.com
Version: 8.0.233 / Virus Database: 270.10.16/1926 - Release Date: 01/30/09
17:31:00

No virus found in this outgoing message.
Checked by AVG - www.avg.com
Version: 8.0.233 / Virus Database: 270.10.16/1926 - Release Date: 01/30/09
17:31:00


Mime
View raw message