C:\XXX\spring-data-jpa-lazy-loading>mvn test
[INFO] Scanning for projects...
[INFO] ------------------------------------------------------------------------
[INFO] Building LazyLoading
[INFO]    task-segment: [test]
[INFO] ------------------------------------------------------------------------
[debug] execute contextualize
[INFO] [resources:resources {execution: default-resources}]
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 2 resources
[INFO] [compiler:compile {execution: default-compile}]
[INFO] Compiling 4 source files to C:\XXX\spring-data-jpa-lazy-loading\target\classes
[INFO] Preparing exec:java
[WARNING] Removing: java from forked lifecycle, to prevent recursive invocation.
[INFO] No goals needed for project - skipping
[INFO] [exec:java {execution: default}]
[ObjectDB 2.2.3_04 Enhancer]
2 persistable types have been enhanced:
    test.model.Bar
    test.model.Foo

[debug] execute contextualize
[INFO] [resources:testResources {execution: default-testResources}]
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 2 resources
[INFO] [compiler:testCompile {execution: default-testCompile}]
[INFO] Compiling 1 source file to C:\XXX\spring-data-jpa-lazy-loading\target\test-classes
[INFO] [surefire:test {execution: default-test}]
[INFO] Surefire report directory: C:\XXX\spring-data-jpa-lazy-loading\target\surefire-reports
Concurrency config is parallel='classes', perCoreThreadCount=true, threadCount=2, useUnlimitedThreads=false

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running test.LazyLoadingTests
11:31:51.909 [pool-1-thread-1] DEBUG o.s.t.c.s.DependencyInjectionTestExecutionListener - Performing dependency injection for test context [[TestContext@33aae94f testClass = LazyLoadingTests, locations = array<String>['classpath:/test/LazyLoadingTests-context.xml'], testInstance = test.LazyLoadingTests@19bb5c09, testMethod = [null], testException = [null]]].

11:31:51.912 [pool-1-thread-1] DEBUG o.s.t.c.s.AbstractGenericContextLoader - Loading ApplicationContext for locations [classpath:/test/LazyLoadingTests-context.xml].

11:31:51.974 [pool-1-thread-1] INFO  o.s.b.f.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [test/LazyLoadingTests-context.xml]

11:31:51.981 [pool-1-thread-1] DEBUG o.s.b.f.xml.DefaultDocumentLoader - Using JAXP provider [com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl]

11:31:52.005 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Loading schema mappings from [META-INF/spring.schemas]

11:31:52.010 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Loaded schema mappings: {http://www.springframework.org/schema/util/spring-util.xsd=org/springframework/beans/factory/xml/spring-util-3.0.xsd, http://www.springframework.org/schema/task/spring-task.xsd=org/springframework/scheduling/config/spring-task-3.0.xsd, http://www.springframework.org/schema/aop/spring-aop-3.0.xsd=org/springframework/aop/config/spring-aop-3.0.xsd, http://www.springframework.org/schema/aop/spring-aop-2.0.xsd=org/springframework/aop/config/spring-aop-2.0.xsd, http://www.springframework.org/schema/tool/spring-tool-2.5.xsd=org/springframework/beans/factory/xml/spring-tool-2.5.xsd, http://www.springframework.org/schema/beans/spring-beans.xsd=org/springframework/beans/factory/xml/spring-beans-3.0.xsd, http://www.springframework.org/schema/jee/spring-jee-2.5.xsd=org/springframework/ejb/config/spring-jee-2.5.xsd, http://www.springframework.org/schema/aop/spring-aop.xsd=org/springframework/aop/conf
ig/spring-aop-3.0.xsd, http://www.springframework.org/schema/beans/spring-beans-2.0.xsd=org/springframework/beans/factory/xml/spring-beans-2.0.xsd, http://www.springframework.org/schema/beans/spring-beans-3.0.xsd=org/springframework/beans/factory/xml/spring-beans-3.0.xsd, http://www.springframework.org/schema/task/spring-task-3.0.xsd=org/springframework/scheduling/config/spring-task-3.0.xsd, http://www.springframework.org/schema/tx/spring-tx-2.5.xsd=org/springframework/transaction/config/spring-tx-2.5.xsd, http://www.springframework.org/schema/context/spring-context-2.5.xsd=org/springframework/context/config/spring-context-2.5.xsd, http://www.springframework.org/schema/jdbc/spring-jdbc-3.0.xsd=org/springframework/jdbc/config/spring-jdbc-3.0.xsd, http://www.springframework.org/schema/tool/spring-tool-3.0.xsd=org/springframework/beans/factory/xml/spring-tool-3.0.xsd, http://www.springframework.org/schema/tx/spring-tx.xsd=org/springframework/transaction/config/spring-tx-3.0.xsd, http://ww
w.springframework.org/schema/tool/spring-tool-2.0.xsd=org/springframework/beans/factory/xml/spring-tool-2.0.xsd, http://www.springframework.org/schema/util/spring-util-2.5.xsd=org/springframework/beans/factory/xml/spring-util-2.5.xsd, http://www.springframework.org/schema/lang/spring-lang.xsd=org/springframework/scripting/config/spring-lang-3.0.xsd, http://www.springframework.org/schema/lang/spring-lang-2.5.xsd=org/springframework/scripting/config/spring-lang-2.5.xsd, http://www.springframework.org/schema/data/jpa/spring-jpa-1.0.xsd=org/springframework/data/jpa/repository/config/spring-jpa-1.0.xsd, http://www.springframework.org/schema/jee/spring-jee-3.0.xsd=org/springframework/ejb/config/spring-jee-3.0.xsd, http://www.springframework.org/schema/jee/spring-jee-2.0.xsd=org/springframework/ejb/config/spring-jee-2.0.xsd, http://www.springframework.org/schema/context/spring-context.xsd=org/springframework/context/config/spring-context-3.0.xsd, http://www.springframework.org/schema/jee/spri
ng-jee.xsd=org/springframework/ejb/config/spring-jee-3.0.xsd, http://www.springframework.org/schema/data/jpa/spring-jpa.xsd=org/springframework/data/jpa/repository/config/spring-jpa-1.0.xsd, http://www.springframework.org/schema/aop/spring-aop-2.5.xsd=org/springframework/aop/config/spring-aop-2.5.xsd, http://www.springframework.org/schema/data/repository/spring-repository.xsd=org/springframework/data/repository/config/spring-repository-1.0.xsd, http://www.springframework.org/schema/jdbc/spring-jdbc.xsd=org/springframework/jdbc/config/spring-jdbc-3.0.xsd, http://www.springframework.org/schema/data/repository/spring-repository-1.0.xsd=org/springframework/data/repository/config/spring-repository-1.0.xsd, http://www.springframework.org/schema/tx/spring-tx-2.0.xsd=org/springframework/transaction/config/spring-tx-2.0.xsd, http://www.springframework.org/schema/tx/spring-tx-3.0.xsd=org/springframework/transaction/config/spring-tx-3.0.xsd, http://www.springframework.org/schema/context/spring-co
ntext-3.0.xsd=org/springframework/context/config/spring-context-3.0.xsd, http://www.springframework.org/schema/tool/spring-tool.xsd=org/springframework/beans/factory/xml/spring-tool-3.0.xsd, http://www.springframework.org/schema/util/spring-util-3.0.xsd=org/springframework/beans/factory/xml/spring-util-3.0.xsd, http://www.springframework.org/schema/lang/spring-lang-3.0.xsd=org/springframework/scripting/config/spring-lang-3.0.xsd, http://www.springframework.org/schema/util/spring-util-2.0.xsd=org/springframework/beans/factory/xml/spring-util-2.0.xsd, http://www.springframework.org/schema/lang/spring-lang-2.0.xsd=org/springframework/scripting/config/spring-lang-2.0.xsd, http://www.springframework.org/schema/beans/spring-beans-2.5.xsd=org/springframework/beans/factory/xml/spring-beans-2.5.xsd}

11:31:52.013 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/beans/spring-beans.xsd] in classpath: org/springframework/beans/factory/xml/spring-beans-3.0.xsd

11:31:52.056 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/context/spring-context.xsd] in classpath: org/springframework/context/config/spring-context-3.0.xsd

11:31:52.066 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/tool/spring-tool-3.0.xsd] in classpath: org/springframework/beans/factory/xml/spring-tool-3.0.xsd

11:31:52.074 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/tx/spring-tx.xsd] in classpath: org/springframework/transaction/config/spring-tx-3.0.xsd

11:31:52.080 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/data/jpa/spring-jpa.xsd] in classpath: org/springframework/data/jpa/repository/config/spring-jpa-1.0.xsd

11:31:52.083 [pool-1-thread-1] DEBUG o.s.b.f.xml.PluggableSchemaResolver - Found XML schema [http://www.springframework.org/schema/data/repository/spring-repository-1.0.xsd] in classpath: org/springframework/data/repository/config/spring-repository-1.0.xsd

11:31:52.092 [pool-1-thread-1] DEBUG o.s.b.f.x.DefaultBeanDefinitionDocumentReader - Loading bean definitions

11:31:52.104 [pool-1-thread-1] DEBUG o.s.b.f.x.DefaultNamespaceHandlerResolver - Loaded NamespaceHandler mappings: {http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, http://www.springframework.org/schema/aop=org.springframework.aop.config.AopNamespaceHandler, http://www.springframework.org/schema/jdbc=org.springframework.jdbc.config.JdbcNamespaceHandler, http://www.springframework.org/schema/data/jpa=org.springframework.data.jpa.repository.config.JpaRepositoryNameSpaceHandler, http://www.springframework.org/schema/tx=org.springframework.transaction.config.TxNamespaceHandler, http://www.springframework.org/schema/task=org.springframework.scheduling.config.TaskNamespaceHandler, http://www.springframework.org/schema/lang=org.springframe
work.scripting.config.LangNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler}

11:31:52.162 [pool-1-thread-1] DEBUG o.s.d.r.c.AbstractRepositoryConfigDefinitionParser - Triggering auto repository detection

11:31:52.176 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Looking for matching resources in directory tree [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository]

11:31:52.177 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Searching directory [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository] for files matching pattern [C:/Users/chris/Documents/workspace-sts-2.5.2.RELEASE/projects/trunk/spring-data-jpa-lazy-loading/spring-data-jpa-lazy-loading/target/classes/test/repository/**/*.class]

11:31:52.181 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Resolved location pattern [classpath*:test/repository/**/*.class] to resources [file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\BarRepository.class], file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\FooRepository.class]]

11:31:52.192 [pool-1-thread-1] DEBUG o.s.d.r.c.AbstractRepositoryConfigDefinitionParser$RepositoryComponentProvider - Identified candidate component class: file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\BarRepository.class]

11:31:52.193 [pool-1-thread-1] DEBUG o.s.d.r.c.AbstractRepositoryConfigDefinitionParser$RepositoryComponentProvider - Identified candidate component class: file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\FooRepository.class]

11:31:52.196 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Looking for matching resources in directory tree [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository]

11:31:52.196 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Searching directory [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository] for files matching pattern [C:/Users/chris/Documents/workspace-sts-2.5.2.RELEASE/projects/trunk/spring-data-jpa-lazy-loading/spring-data-jpa-lazy-loading/target/classes/test/repository/**/*.class]

11:31:52.198 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Resolved location pattern [classpath*:test/repository/**/*.class] to resources [file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\BarRepository.class], file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\FooRepository.class]]

11:31:52.200 [pool-1-thread-1] DEBUG o.s.d.r.c.AbstractRepositoryConfigDefinitionParser - Registering repository: barRepository - Interface: test.repository.BarRepository - Factory: org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean, - Custom implementation: null

11:31:52.200 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Looking for matching resources in directory tree [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository]

11:31:52.201 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Searching directory [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository] for files matching pattern [C:/Users/chris/Documents/workspace-sts-2.5.2.RELEASE/projects/trunk/spring-data-jpa-lazy-loading/spring-data-jpa-lazy-loading/target/classes/test/repository/**/*.class]

11:31:52.202 [pool-1-thread-1] DEBUG o.s.c.i.s.PathMatchingResourcePatternResolver - Resolved location pattern [classpath*:test/repository/**/*.class] to resources [file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\BarRepository.class], file [C:\XXX\spring-data-jpa-lazy-loading\target\classes\test\repository\FooRepository.class]]

11:31:52.203 [pool-1-thread-1] DEBUG o.s.d.r.c.AbstractRepositoryConfigDefinitionParser - Registering repository: fooRepository - Interface: test.repository.FooRepository - Factory: org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean, - Custom implementation: null

11:31:52.207 [pool-1-thread-1] DEBUG o.s.b.f.x.BeanDefinitionParserDelegate - Neither XML 'id' nor 'name' specified - using generated bean name [org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver#4bf54c5f]

11:31:52.208 [pool-1-thread-1] DEBUG o.s.b.f.xml.XmlBeanDefinitionReader - Loaded 17 bean definitions from location pattern [classpath:/test/LazyLoadingTests-context.xml]

11:31:52.211 [pool-1-thread-1] INFO  o.s.c.s.GenericApplicationContext - Refreshing org.springframework.context.support.GenericApplicationContext@c2f48d2: startup date [Wed May 04 11:31:52 CEST 2011]; root of context hierarchy

11:31:52.212 [pool-1-thread-1] DEBUG o.s.c.s.GenericApplicationContext - Bean factory for org.springframework.context.support.GenericApplicationContext@c2f48d2: org.springframework.beans.factory.support.DefaultListableBeanFactory@6b9cd75a: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,barRepository,fo
oRepository,org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0,org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,entityManagerFactory,transactionManager]; root of factory hierarchy

11:31:52.234 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor'

11:31:52.234 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor'

11:31:52.256 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor' to allow for resolving potential circular references

11:31:52.257 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor'

11:31:52.296 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0'

11:31:52.296 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0'

11:31:52.297 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0' to allow for resolving potential circular references

11:31:52.323 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0'

11:31:52.323 [pool-1-thread-1] INFO  o.s.b.f.c.PropertyPlaceholderConfigurer - Loading properties file from class path resource [config.properties]

11:31:52.329 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor'

11:31:52.329 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor'

11:31:52.330 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor' to allow for resolving potential circular references

11:31:52.330 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor'

11:31:52.330 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.context.annotation.internalRequiredAnnotationProcessor'

11:31:52.330 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.context.annotation.internalRequiredAnnotationProcessor'

11:31:52.331 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.context.annotation.internalRequiredAnnotationProcessor' to allow for resolving potential circular references

11:31:52.331 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.context.annotation.internalRequiredAnnotationProcessor'

11:31:52.331 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.context.annotation.internalCommonAnnotationProcessor'

11:31:52.331 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.context.annotation.internalCommonAnnotationProcessor'

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.context.annotation.internalCommonAnnotationProcessor' to allow for resolving potential circular references

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.context.annotation.internalCommonAnnotationProcessor'

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.context.annotation.internalPersistenceAnnotationProcessor'

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.context.annotation.internalPersistenceAnnotationProcessor'

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.context.annotation.internalPersistenceAnnotationProcessor' to allow for resolving potential circular references

11:31:52.335 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.context.annotation.internalPersistenceAnnotationProcessor'

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0'

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0'

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0' to allow for resolving potential circular references

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0'

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.aop.config.internalAutoProxyCreator'

11:31:52.336 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.aop.config.internalAutoProxyCreator'

11:31:52.349 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.aop.config.internalAutoProxyCreator' to allow for resolving potential circular references

11:31:52.361 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.aop.config.internalAutoProxyCreator'

11:31:52.361 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0'

11:31:52.361 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0'

11:31:52.363 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0' to allow for resolving potential circular references

11:31:52.369 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'entityManagerFactory'

11:31:52.369 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'entityManagerFactory'

11:31:52.381 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'entityManagerFactory' to allow for resolving potential circular references

11:31:52.393 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver#4bf54c5f'

11:31:52.398 [pool-1-thread-1] INFO  o.s.c.s.GenericApplicationContext - Bean 'org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver#4bf54c5f' of type [class org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)

11:31:52.399 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver#4bf54c5f'

11:31:52.399 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'entityManagerFactory'

11:31:52.410 [pool-1-thread-1] INFO  o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'test'

11:31:52.495 [pool-1-thread-1] INFO  o.s.c.s.GenericApplicationContext - Bean 'entityManagerFactory' of type [class org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)

11:31:52.495 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'entityManagerFactory'

11:31:52.497 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0'

11:31:52.497 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0'

11:31:52.497 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0'

11:31:52.498 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0' to allow for resolving potential circular references

11:31:52.501 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.501 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.503 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.config.internalTransactionAdvisor' to allow for resolving potential circular references

11:31:52.507 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

11:31:52.507 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

11:31:52.508 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0' to allow for resolving potential circular references

11:31:52.511 [pool-1-thread-1] INFO  o.s.c.s.GenericApplicationContext - Bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0' of type [class org.springframework.transaction.annotation.AnnotationTransactionAttributeSource] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)

11:31:52.513 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

11:31:52.513 [pool-1-thread-1] INFO  o.s.c.s.GenericApplicationContext - Bean 'org.springframework.transaction.config.internalTransactionAdvisor' of type [class org.springframework.transaction.interceptor.BeanFactoryTransactionAttributeSourceAdvisor] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)

11:31:52.513 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.516 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0'

11:31:52.518 [pool-1-thread-1] DEBUG o.s.c.s.GenericApplicationContext - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@a21d23b]

11:31:52.521 [pool-1-thread-1] DEBUG o.s.c.s.GenericApplicationContext - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@1b0c6cfc]

11:31:52.521 [pool-1-thread-1] INFO  o.s.b.f.s.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6b9cd75a: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,barRepository,fooRepository,org.springframework.data.repository.suppo
rt.RepositoryInterfaceAwareBeanPostProcessor#0,org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,entityManagerFactory,transactionManager]; root of factory hierarchy

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.annotation.internalRequiredAnnotationProcessor'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.annotation.internalCommonAnnotationProcessor'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.annotation.internalPersistenceAnnotationProcessor'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.aop.config.internalAutoProxyCreator'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

11:31:52.522 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

11:31:52.524 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0' to allow for resolving potential circular references

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0'

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'barRepository'

11:31:52.528 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'barRepository'

11:31:52.540 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Found injected element on class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]: PersistenceElement for public void org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean.setEntityManager(javax.persistence.EntityManager)

11:31:52.541 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Found injected element on class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]: PersistenceElement for public void org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean.setEntityManager(javax.persistence.EntityManager)

11:31:52.542 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'barRepository' to allow for resolving potential circular references

11:31:52.542 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Processing injected method of bean 'barRepository': PersistenceElement for public void org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean.setEntityManager(javax.persistence.EntityManager)

11:31:52.543 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean '(inner bean)'

11:31:52.547 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityManagerFactory'

11:31:52.547 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.569 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.573 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)'

11:31:52.574 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityManagerFactory'

11:31:52.574 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'barRepository'

11:31:52.580 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.582 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'barRepository'

11:31:52.772 [pool-1-thread-1] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@19fac852]

11:31:52.780 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.788 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'count' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.789 [pool-1-thread-1] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'barRepository' with 0 common interceptors and 1 specific interceptors

11:31:52.789 [pool-1-thread-1] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [$Proxy17@3041876]

11:31:52.796 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'fooRepository'

11:31:52.796 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'fooRepository'

11:31:52.796 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'fooRepository' to allow for resolving potential circular references

11:31:52.796 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Processing injected method of bean 'fooRepository': PersistenceElement for public void org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean.setEntityManager(javax.persistence.EntityManager)

11:31:52.797 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#1'

11:31:52.797 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityManagerFactory'

11:31:52.797 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.798 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#1'

11:31:52.798 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityManagerFactory'

11:31:52.799 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'fooRepository'

11:31:52.799 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.800 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'fooRepository'

11:31:52.801 [pool-1-thread-1] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@689e8c34]

11:31:52.807 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.808 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'count' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.808 [pool-1-thread-1] DEBUG o.s.a.f.a.InfrastructureAdvisorAutoProxyCreator - Creating implicit proxy for bean 'fooRepository' with 0 common interceptors and 1 specific interceptors

11:31:52.808 [pool-1-thread-1] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [$Proxy20@31554233]

11:31:52.813 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.data.repository.support.RepositoryInterfaceAwareBeanPostProcessor#0'

11:31:52.814 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0'

11:31:52.814 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0'

11:31:52.815 [pool-1-thread-1] DEBUG o.s.c.s.GenericApplicationContext - Unable to locate LifecycleProcessor with name 'lifecycleProcessor': using default [org.springframework.context.support.DefaultLifecycleProcessor@69f94884]

11:31:52.816 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'

11:31:52.820 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Found injected element on class [test.LazyLoadingTests]: AutowiredFieldElement for private test.repository.FooRepository test.LazyLoadingTests.fooRepository

11:31:52.821 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Found injected element on class [test.LazyLoadingTests]: AutowiredFieldElement for private test.repository.BarRepository test.LazyLoadingTests.barRepository

11:31:52.821 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Processing injected method of bean 'test.LazyLoadingTests': AutowiredFieldElement for private test.repository.FooRepository test.LazyLoadingTests.fooRepository

11:31:52.822 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'fooRepository'

11:31:52.822 [pool-1-thread-1] DEBUG o.s.b.f.a.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'test.LazyLoadingTests' to bean named 'fooRepository'

11:31:52.822 [pool-1-thread-1] DEBUG o.s.b.f.annotation.InjectionMetadata - Processing injected method of bean 'test.LazyLoadingTests': AutowiredFieldElement for private test.repository.BarRepository test.LazyLoadingTests.barRepository

11:31:52.823 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'barRepository'

11:31:52.823 [pool-1-thread-1] DEBUG o.s.b.f.a.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'test.LazyLoadingTests' to bean named 'barRepository'

11:31:52.823 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.827 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.interceptor.TransactionInterceptor#0'

11:31:52.829 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'deleteAll' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.835 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'deleteAll' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.835 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating shared instance of singleton bean 'transactionManager'

11:31:52.836 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Creating instance of bean 'transactionManager'

11:31:52.843 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Eagerly caching bean 'transactionManager' to allow for resolving potential circular references

11:31:52.847 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityManagerFactory'

11:31:52.847 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'transactionManager'

11:31:52.847 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'

11:31:52.849 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Finished creating instance of bean 'transactionManager'

11:31:52.853 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.springframework.data.jpa.repository.support.SimpleJpaRepository.deleteAll]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.873 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Opened new EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 1] for JPA transaction

11:31:52.875 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'deleteAll' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.875 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.875 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 1] for JPA transaction

11:31:52.876 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Participating in existing transaction

11:31:52.957 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit

11:31:52.957 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 1]

11:31:52.959 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Closing JPA EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 1] after transaction

11:31:52.959 [pool-1-thread-1] DEBUG o.s.o.jpa.EntityManagerFactoryUtils - Closing JPA EntityManager

11:31:52.959 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'deleteAll' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.960 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.960 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.springframework.data.jpa.repository.support.SimpleJpaRepository.deleteAll]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.960 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Opened new EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 2] for JPA transaction

11:31:52.960 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'deleteAll' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.960 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.960 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 2] for JPA transaction

11:31:52.960 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Participating in existing transaction

11:31:52.961 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit

11:31:52.961 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 2]

11:31:52.961 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Closing JPA EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 2] after transaction

11:31:52.961 [pool-1-thread-1] DEBUG o.s.o.jpa.EntityManagerFactoryUtils - Closing JPA EntityManager

11:31:52.962 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'save' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.962 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'save' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.962 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.962 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.springframework.data.jpa.repository.support.SimpleJpaRepository.save]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.962 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Opened new EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 3] for JPA transaction

11:31:52.962 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'save' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''

11:31:52.963 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.963 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 3] for JPA transaction

11:31:52.963 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Participating in existing transaction

11:31:52.965 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit

11:31:52.965 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 3]

11:31:52.967 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Closing JPA EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 3] after transaction

11:31:52.967 [pool-1-thread-1] DEBUG o.s.o.jpa.EntityManagerFactoryUtils - Closing JPA EntityManager

11:31:52.968 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'findOne' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.969 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'findOne' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.969 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.969 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.springframework.data.jpa.repository.support.SimpleJpaRepository.findOne]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.969 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Opened new EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 4] for JPA transaction

11:31:52.969 [pool-1-thread-1] DEBUG o.s.t.a.AnnotationTransactionAttributeSource - Adding transactional method 'findOne' with attribute: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''

11:31:52.969 [pool-1-thread-1] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'transactionManager'

11:31:52.969 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 4] for JPA transaction

11:31:52.969 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Participating in existing transaction

11:31:52.973 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit

11:31:52.973 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 4]

11:31:52.973 [pool-1-thread-1] DEBUG o.s.orm.jpa.JpaTransactionManager - Closing JPA EntityManager [ObjectManager of com.objectdb.o.WSM@d71194d - 4] after transaction

11:31:52.973 [pool-1-thread-1] DEBUG o.s.o.jpa.EntityManagerFactoryUtils - Closing JPA EntityManager

11:31:52.974 [pool-1-thread-1] DEBUG o.s.t.c.s.DirtiesContextTestExecutionListener - After test method: context [[TestContext@33aae94f testClass = LazyLoadingTests, locations = array<String>['classpath:/test/LazyLoadingTests-context.xml'], testInstance = test.LazyLoadingTests@19bb5c09, testMethod = testAddBars@LazyLoadingTests, testException = java.lang.AssertionError: bar list is empty]], class dirties context [false], class mode [null], method dirties context [false].

11:31:52.977 [pool-1-thread-1] DEBUG o.s.t.c.s.DirtiesContextTestExecutionListener - After test class: context [[TestContext@33aae94f testClass = LazyLoadingTests, locations = array<String>['classpath:/test/LazyLoadingTests-context.xml'], testInstance = [null], testMethod = [null], testException = [null]]], dirtiesContext [false].

Tests run: 2, Failures: 1, Errors: 0, Skipped: 1, Time elapsed: 1,168,546.079 sec <<< FAILURE!
11:31:51.842 [main] DEBUG o.s.t.c.j.SpringJUnit4ClassRunner - SpringJUnit4ClassRunner constructor called with [class test.LazyLoadingTests].
11:31:51.874 [main] INFO  o.s.test.context.TestContextManager - @TestExecutionListeners is not present for class [class test.LazyLoadingTests]: using defaults.
11:31:51.893 [main] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [test.LazyLoadingTests]
11:31:51.893 [main] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [test.LazyLoadingTests]
11:31:51.899 [main] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [test.LazyLoadingTests]
11:31:51.899 [main] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [test.LazyLoadingTests]
11:31:51.902 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [test.LazyLoadingTests]
11:31:51.902 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [test.LazyLoadingTests]
11:31:51.902 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [test.LazyLoadingTests]
11:31:51.902 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [test.LazyLoadingTests]
11:31:51.903 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [test.LazyLoadingTests]
11:31:51.904 [pool-1-thread-1] DEBUG o.s.t.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [test.LazyLoadingTests]

Results :

Failed tests:
  testAddBars(test.LazyLoadingTests): bar list is empty

Tests run: 2, Failures: 1, Errors: 0, Skipped: 1

11:31:56.094 [Thread-2] INFO  o.s.c.s.GenericApplicationContext - Closing org.springframework.context.support.GenericApplicationContext@c2f48d2: startup date [Wed May 04 11:31:52 CEST 2011]; root of context hierarchy
11:31:56.095 [Thread-2] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
11:31:56.105 [Thread-2] INFO  o.s.b.f.s.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6b9cd75a: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,barRepository,fooRepository,org.springframework.data.repository.support.RepositoryI
nterfaceAwareBeanPostProcessor#0,org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,entityManagerFactory,transactionManager]; root of factory hierarchy
11:31:56.114 [Thread-2] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Retrieved dependent beans for bean 'fooRepository': [test.LazyLoadingTests]
11:31:56.114 [Thread-2] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#1': [fooRepository]
11:31:56.115 [Thread-2] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)': [barRepository]
11:31:56.115 [Thread-2] DEBUG o.s.b.f.s.DisposableBeanAdapter - Invoking destroy() on bean with name 'entityManagerFactory'
11:31:56.115 [Thread-2] INFO  o.s.o.j.LocalContainerEntityManagerFactoryBean - Closing JPA EntityManagerFactory for persistence unit 'test'
11:31:56.116 [Thread-2] DEBUG o.s.b.f.s.DefaultListableBeanFactory - Retrieved dependent beans for bean 'org.springframework.instrument.classloading.InstrumentationLoadTimeWeaver#4bf54c5f': [entityManagerFactory]
[INFO] ------------------------------------------------------------------------
[ERROR] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] There are test failures.

Please refer to C:\XXX\spring-data-jpa-lazy-loading\target\surefire-reports for the individual test results.
[INFO] ------------------------------------------------------------------------
[INFO] For more information, run Maven with the -e switch
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 8 seconds
[INFO] Finished at: Wed May 04 11:31:56 CEST 2011
[INFO] Final Memory: 28M/224M
[INFO] ------------------------------------------------------------------------