Feb 13, 2013 10:11:40 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.23.
Feb 13, 2013 10:11:40 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Feb 13, 2013 10:11:40 AM org.apache.tomcat.util.digester.SetPropertiesRule begin
WARNING: [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'source' to 'org.eclipse.jst.jee.server:spring' did not find a matching property.
Feb 13, 2013 10:11:40 AM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Feb 13, 2013 10:11:40 AM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8009
Feb 13, 2013 10:11:40 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 847 ms
Feb 13, 2013 10:11:42 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 13, 2013 10:11:42 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.35
Feb 13, 2013 10:11:42 AM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
Feb 13, 2013 10:11:42 AM org.springframework.web.context.ContextLoader initWebApplicationContext
INFO: Root WebApplicationContext: initialization started
Feb 13, 2013 10:11:42 AM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing Root WebApplicationContext: startup date [Wed Feb 13 10:11:42 CET 2013]; root of context hierarchy
Feb 13, 2013 10:11:42 AM org.springframework.beans.factory.xml.XmlBeanDefinitionReader loadBeanDefinitions
INFO: Loading XML bean definitions from URL [file:/home/d6831/workspace-juno/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/spring/WEB-INF/classes/applicationContext.xml]
Feb 13, 2013 10:11:43 AM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1f47e668: defining beans [dvdService,videoManager,factoryMethodExample,factoryBean,exampleBean,genericService,constructorBean,constructorBean2,constructorBean3,setterBean,nullBean1,nullBean2,collectionBean,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,serviceAW1,serviceAW2,autowiredBean1,autowiredBean2,autowiredBean3,autowiredBean4,resourceBean,org.springframework.aop.config.internalAutoProxyCreator,myInterfaceImpl,myAspect,myDataSource,jpaVendorAdapter,entityManagerFactory,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,transactionManager,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,carDaoImpl]; root of factory hierarchy
Feb 13, 2013 10:11:43 AM org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean createNativeEntityManagerFactory
INFO: Building JPA container EntityManagerFactory for persistence unit 'pu'
10:11:44,098 INFO Version:43 - Hibernate Annotations 3.5.3-Final
10:11:44,115 INFO Environment:570 - Hibernate 3.5.3-Final
10:11:44,117 INFO Environment:603 - hibernate.properties not found
10:11:44,121 INFO Environment:781 - Bytecode provider name : javassist
10:11:44,128 INFO Environment:662 - using JDK 1.4 java.sql.Timestamp handling
10:11:44,206 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [uuid] -> [class org.hibernate.id.UUIDHexGenerator]
10:11:44,214 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [hilo] -> [class org.hibernate.id.TableHiLoGenerator]
10:11:44,215 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [assigned] -> [class org.hibernate.id.Assigned]
10:11:44,218 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [identity] -> [class org.hibernate.id.IdentityGenerator]
10:11:44,219 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [select] -> [class org.hibernate.id.SelectGenerator]
10:11:44,221 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [sequence] -> [class org.hibernate.id.SequenceGenerator]
10:11:44,223 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [seqhilo] -> [class org.hibernate.id.SequenceHiLoGenerator]
10:11:44,224 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [increment] -> [class org.hibernate.id.IncrementGenerator]
10:11:44,226 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [foreign] -> [class org.hibernate.id.ForeignGenerator]
10:11:44,227 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [guid] -> [class org.hibernate.id.GUIDGenerator]
10:11:44,227 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [uuid.hex] -> [class org.hibernate.id.UUIDHexGenerator]
10:11:44,230 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [sequence-identity] -> [class org.hibernate.id.SequenceIdentityGenerator]
10:11:44,232 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [enhanced-sequence] -> [class org.hibernate.id.enhanced.SequenceStyleGenerator]
10:11:44,234 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [enhanced-table] -> [class org.hibernate.id.enhanced.TableGenerator]
10:11:44,242 INFO Version:37 - Hibernate Commons Annotations 3.2.0.Final
10:11:44,250 INFO Version:42 - Hibernate EntityManager 3.5.3-Final
10:11:44,251 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [uuid] -> [class org.hibernate.id.UUIDHexGenerator]
10:11:44,251 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [hilo] -> [class org.hibernate.id.TableHiLoGenerator]
10:11:44,252 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [assigned] -> [class org.hibernate.id.Assigned]
10:11:44,252 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [identity] -> [class org.hibernate.id.IdentityGenerator]
10:11:44,252 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [select] -> [class org.hibernate.id.SelectGenerator]
10:11:44,252 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [sequence] -> [class org.hibernate.id.SequenceGenerator]
10:11:44,252 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [seqhilo] -> [class org.hibernate.id.SequenceHiLoGenerator]
10:11:44,253 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [increment] -> [class org.hibernate.id.IncrementGenerator]
10:11:44,253 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [foreign] -> [class org.hibernate.id.ForeignGenerator]
10:11:44,253 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [guid] -> [class org.hibernate.id.GUIDGenerator]
10:11:44,253 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [uuid.hex] -> [class org.hibernate.id.UUIDHexGenerator]
10:11:44,254 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [sequence-identity] -> [class org.hibernate.id.SequenceIdentityGenerator]
10:11:44,254 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [enhanced-sequence] -> [class org.hibernate.id.enhanced.SequenceStyleGenerator]
10:11:44,254 DEBUG DefaultIdentifierGeneratorFactory:99 - Registering IdentifierGenerator strategy [enhanced-table] -> [class org.hibernate.id.enhanced.TableGenerator]
10:11:44,293 DEBUG Ejb3Configuration:517 - Processing PersistenceUnitInfo [
name: pu
persistence provider classname: null
classloader: WebappClassLoader
context: /spring
delegate: false
repositories:
/WEB-INF/classes/
----------> Parent Classloader:
org.apache.catalina.loader.StandardClassLoader@28366573
Temporary classloader: org.springframework.instrument.classloading.SimpleThrowawayClassLoader@7acdba53
excludeUnlistedClasses: false
JTA datasource: null
Non JTA datasource: org.apache.commons.dbcp.BasicDataSource@5883de21
Transaction type: RESOURCE_LOCAL
PU root URL: file:/home/d6831/workspace-juno/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/spring/WEB-INF/classes/
Shared Cache Mode: UNSPECIFIED
Validation Mode: AUTO
Jar files URLs []
Managed classes names []
Mapping files names []
Properties []
10:11:44,296 DEBUG Ejb3Configuration:826 - Detect class: true; detect hbm: true
10:11:44,296 DEBUG Ejb3Configuration:826 - Detect class: true; detect hbm: true
10:11:44,306 DEBUG AbstractJarVisitor:123 - Searching mapped entities in jar/par: file:/home/d6831/workspace-juno/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/spring/WEB-INF/classes/
10:11:44,307 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.FormBean
10:11:44,348 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.FullController
10:11:44,370 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.FormController
10:11:44,372 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.BookController
10:11:44,378 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.OptionForm
10:11:44,379 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.mvc.Book
10:11:44,380 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.Dvd
10:11:44,381 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.factory.FactoryBean
10:11:44,382 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.factory.ExampleBeanImpl
10:11:44,382 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.factory.ExampleBean
10:11:44,383 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.Assembler
10:11:44,384 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.resources.ResourceBean
10:11:44,384 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.proxy.SimpleProxy
10:11:44,385 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.proxy.SimplePojo
10:11:44,386 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.proxy.SimpleInterface
10:11:44,386 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.proxy.Main
10:11:44,387 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.hibernate.dao.CarDaoImpl
10:11:44,388 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.hibernate.dao.Test
10:11:44,389 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.hibernate.dao.CarDao
10:11:44,389 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.hibernate.Car
10:11:44,390 DEBUG AbstractJarVisitor:220 - Java element filter matched for it.mrwebmaster.hibernate.Car
10:11:44,392 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.hibernate.Main
10:11:44,393 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.DvdService
10:11:44,393 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.AutowiredBean1
10:11:44,394 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.ServiceAW2
10:11:44,395 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.AutowiredBean4
10:11:44,396 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.ServiceAW1
10:11:44,396 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.AutowiredBean3
10:11:44,397 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.ServiceAW2Impl
10:11:44,397 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.AutowiredBean2
10:11:44,398 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.autowired.ServiceAW1Impl
10:11:44,399 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.collection.CollectionBean
10:11:44,400 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.constructor.GenericServiceImpl
10:11:44,401 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.constructor.GenericService
10:11:44,401 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.constructor.Bean
10:11:44,402 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.di.setter.Bean2
10:11:44,403 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.DvdServiceImpl
10:11:44,404 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.singleton.FactoryMethodBean
10:11:44,405 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.Main
10:11:44,406 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.VideoManager
10:11:44,407 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.aop.MyAspect
10:11:44,409 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.aop.MyInterfaceImpl
10:11:44,411 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.aop.MyInterface
10:11:44,411 DEBUG AbstractJarVisitor:169 - Filtering: it.mrwebmaster.aop.Main
10:11:44,436 DEBUG AnnotationConfiguration:318 - Execute first pass mapping processing
10:11:44,486 DEBUG AnnotationConfiguration:617 - Process hbm files
10:11:44,486 DEBUG AnnotationConfiguration:625 - Process annotated classes
10:11:44,493 INFO AnnotationBinder:529 - Binding entity from annotated class: it.mrwebmaster.hibernate.Car
10:11:44,519 DEBUG Ejb3Column:187 - Binding column: Ejb3DiscriminatorColumn{logicalColumnName'DTYPE', discriminatorTypeName='string'}
10:11:44,540 DEBUG EntityBinder:338 - Import with entity name Car
10:11:44,556 INFO EntityBinder:501 - Bind entity it.mrwebmaster.hibernate.Car on table Car
10:11:44,600 DEBUG Ejb3Column:187 - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Car), mappingColumn=id, insertable=true, updatable=true, unique=false}
10:11:44,609 DEBUG PropertyBinder:175 - binding property id with lazy=false
10:11:44,615 DEBUG SimpleValueBinder:294 - building SimpleValue for id
10:11:44,618 DEBUG PropertyBinder:254 - Building property id
10:11:44,623 DEBUG Ejb3Column:187 - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Car), mappingColumn=name, insertable=true, updatable=true, unique=false}
10:11:44,623 DEBUG PropertyBinder:175 - binding property name with lazy=false
10:11:44,623 DEBUG SimpleValueBinder:294 - building SimpleValue for name
10:11:44,624 DEBUG PropertyBinder:254 - Building property name
10:11:44,624 DEBUG Ejb3Column:187 - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Car), mappingColumn=year, insertable=true, updatable=true, unique=false}
10:11:44,624 DEBUG PropertyBinder:175 - binding property year with lazy=false
10:11:44,625 DEBUG SimpleValueBinder:294 - building SimpleValue for year
10:11:44,625 DEBUG PropertyBinder:254 - Building property year
10:11:44,628 DEBUG SimpleValueBinder:330 - setting SimpleValue typeName for id
10:11:44,628 DEBUG SimpleValueBinder:330 - setting SimpleValue typeName for name
10:11:44,629 DEBUG SimpleValueBinder:330 - setting SimpleValue typeName for year
10:11:44,630 DEBUG AnnotationConfiguration:486 - processing fk mappings (*ToOne and JoinedSubclass)
10:11:44,632 DEBUG Configuration:1211 - processing extends queue
10:11:44,633 DEBUG Configuration:1215 - processing collection mappings
10:11:44,633 DEBUG Configuration:1226 - processing native query and ResultSetMapping mappings
10:11:44,633 DEBUG Configuration:1234 - processing association property references
10:11:44,634 DEBUG Configuration:1256 - processing foreign key constraints
10:11:44,635 INFO AnnotationConfiguration:450 - Hibernate Validator not found: ignoring
10:11:44,654 INFO Version:56 - Hibernate Validator 4.0.1.GA
10:11:44,659 DEBUG ResourceBundleMessageInterpolator:193 - ValidationMessages not found. Delegating to org.hibernate.validator.ValidationMessages
10:11:44,663 DEBUG DefaultTraversableResolver:68 - Found javax.persistence.PersistenceUtil on classpath.
10:11:44,665 INFO DefaultTraversableResolver:89 - Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.
10:11:44,669 DEBUG ValidationXmlParser:218 - No META-INF/validation.xml found. Using annotation based configuration only
10:11:44,742 DEBUG NamingHelper:53 - No JNDI name configured for binding Ejb3Configuration
10:11:44,747 DEBUG AnnotationConfiguration:973 - Legacy Validator not present in classpath, ignoring event listener registration
10:11:44,748 DEBUG ResourceBundleMessageInterpolator:193 - ValidationMessages not found. Delegating to org.hibernate.validator.ValidationMessages
10:11:44,748 DEBUG DefaultTraversableResolver:68 - Found javax.persistence.PersistenceUtil on classpath.
10:11:44,749 INFO DefaultTraversableResolver:89 - Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.
10:11:44,750 DEBUG ValidationXmlParser:218 - No META-INF/validation.xml found. Using annotation based configuration only
10:11:44,758 DEBUG HibernateSearchEventListenerRegister:231 - Search not present in classpath, ignoring event listener registration.
10:11:44,758 INFO HibernateSearchEventListenerRegister:75 - Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
10:11:44,759 DEBUG Configuration:1376 - Preparing to build session factory with filters : {}
10:11:44,759 DEBUG AnnotationConfiguration:318 - Execute first pass mapping processing
10:11:44,759 DEBUG AnnotationConfiguration:617 - Process hbm files
10:11:44,759 DEBUG AnnotationConfiguration:625 - Process annotated classes
10:11:44,760 DEBUG AnnotationConfiguration:486 - processing fk mappings (*ToOne and JoinedSubclass)
10:11:44,760 DEBUG Configuration:1211 - processing extends queue
10:11:44,760 DEBUG Configuration:1215 - processing collection mappings
10:11:44,760 DEBUG Configuration:1226 - processing native query and ResultSetMapping mappings
10:11:44,761 DEBUG Configuration:1234 - processing association property references
10:11:44,761 DEBUG Configuration:1256 - processing foreign key constraints
10:11:44,762 DEBUG ResourceBundleMessageInterpolator:193 - ValidationMessages not found. Delegating to org.hibernate.validator.ValidationMessages
10:11:44,763 DEBUG DefaultTraversableResolver:68 - Found javax.persistence.PersistenceUtil on classpath.
10:11:44,763 INFO DefaultTraversableResolver:89 - Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.
10:11:44,764 DEBUG ValidationXmlParser:218 - No META-INF/validation.xml found. Using annotation based configuration only
10:11:44,835 INFO ConnectionProviderFactory:95 - Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
10:11:44,839 INFO InjectedDataSourceConnectionProvider:62 - Using provided datasource
10:11:45,134 INFO SettingsFactory:117 - RDBMS: MySQL, version: 5.5.29-0ubuntu0.12.10.1
10:11:45,134 INFO SettingsFactory:118 - JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.1.12 ( Revision: ${bzr.revision-id} )
10:11:45,171 INFO Dialect:206 - Using dialect: org.hibernate.dialect.MySQLDialect
10:11:45,180 INFO JdbcSupportLoader:79 - Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4
10:11:45,182 INFO TransactionFactoryFactory:62 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
10:11:45,183 INFO TransactionManagerLookupFactory:80 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
10:11:45,184 INFO SettingsFactory:169 - Automatic flush during beforeCompletion(): disabled
10:11:45,184 INFO SettingsFactory:173 - Automatic session close at end of transaction: disabled
10:11:45,184 INFO SettingsFactory:180 - JDBC batch size: 15
10:11:45,184 INFO SettingsFactory:183 - JDBC batch updates for versioned data: disabled
10:11:45,185 INFO SettingsFactory:188 - Scrollable result sets: enabled
10:11:45,185 DEBUG SettingsFactory:192 - Wrap result sets: disabled
10:11:45,186 INFO SettingsFactory:196 - JDBC3 getGeneratedKeys(): enabled
10:11:45,186 INFO SettingsFactory:204 - Connection release mode: auto
10:11:45,187 INFO SettingsFactory:228 - Maximum outer join fetch depth: 2
10:11:45,187 INFO SettingsFactory:231 - Default batch fetch size: 1
10:11:45,187 INFO SettingsFactory:235 - Generate SQL with comments: disabled
10:11:45,188 INFO SettingsFactory:239 - Order SQL updates by primary key: disabled
10:11:45,188 INFO SettingsFactory:243 - Order SQL inserts for batching: disabled
10:11:45,188 INFO SettingsFactory:410 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
10:11:45,191 INFO ASTQueryTranslatorFactory:47 - Using ASTQueryTranslatorFactory
10:11:45,191 INFO SettingsFactory:251 - Query language substitutions: {}
10:11:45,191 INFO SettingsFactory:256 - JPA-QL strict compliance: enabled
10:11:45,191 INFO SettingsFactory:261 - Second-level cache: enabled
10:11:45,192 INFO SettingsFactory:265 - Query cache: disabled
10:11:45,192 INFO SettingsFactory:395 - Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
10:11:45,192 INFO SettingsFactory:275 - Optimize cache for minimal puts: disabled
10:11:45,192 INFO SettingsFactory:284 - Structured second-level cache entries: disabled
10:11:45,197 INFO SettingsFactory:304 - Echoing all SQL to stdout
10:11:45,198 INFO SettingsFactory:313 - Statistics: disabled
10:11:45,198 INFO SettingsFactory:317 - Deleted entity synthetic identifier rollback: disabled
10:11:45,199 INFO SettingsFactory:332 - Default entity-mode: pojo
10:11:45,199 INFO SettingsFactory:336 - Named query checking : enabled
10:11:45,199 INFO SettingsFactory:340 - Check Nullability in Core (should be disabled when Bean Validation is on): disabled
10:11:45,228 INFO SessionFactoryImpl:199 - building session factory
10:11:45,234 DEBUG SessionFactoryImpl:238 - Session factory constructed with filter configurations : {}
10:11:45,234 DEBUG SessionFactoryImpl:242 - instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/home/d6831/workspace-juno/.metadata/.plugins/org.eclipse.wst.server.core/tmp0, sun.management.compiler=HotSpot 64-Bit Tiered Compilers, hibernate.ejb.discard_pc_on_close=false, catalina.useNaming=true, hibernate.transaction.flush_before_completion=false, os.name=Linux, sun.boot.class.path=/usr/lib/jvm/jdk1.6.0/jre/lib/resources.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/rt.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/sunrsasign.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/jsse.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/jce.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/charsets.jar:/usr/lib/jvm/jdk1.6.0/jre/lib/modules/jdk.boot.jar:/usr/lib/jvm/jdk1.6.0/jre/classes, sun.desktop=gnome, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_32-b05, hibernate.connection.autocommit=true, user.name=d6831, shared.loader=${catalina.home}/shared/classes, tomcat.util.buf.StringCache.byte.enabled=true, javax.persistence.validation.mode=AUTO, hibernate.connection.release_mode=auto, user.language=en, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, sun.boot.library.path=/usr/lib/jvm/jdk1.6.0/jre/lib/amd64, java.version=1.6.0_32, user.timezone=Europe/Rome, sun.arch.data.model=64, java.endorsed.dirs=/home/d6831/as/tomcat/endorsed, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/usr/lib/jvm/jdk1.6.0/jre, java.vm.info=mixed mode, os.version=3.5.0-23-generic, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, hibernate.query.jpaql_strict_compliance=true, path.separator=:, java.vm.version=20.7-b02, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., hibernate.check_nullability=false, java.naming.factory.url.pkgs=org.apache.naming, user.home=/home/d6831, java.specification.vendor=Sun Microsystems Inc., hibernate.hbm2ddl.auto=update, java.library.path=/usr/lib/jvm/jdk1.6.0/jre/lib/amd64/server:/usr/lib/jvm/jdk1.6.0/jre/lib/amd64:/usr/lib/jvm/jdk1.6.0/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.MySQLDialect, common.loader=${catalina.base}/lib,${catalina.base}/lib/*.jar,${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, sun.java.command=org.apache.catalina.startup.Bootstrap start, java.class.path=/home/d6831/as/tomcat/bin/bootstrap.jar:/usr/lib/jvm/jdk1.6.0/lib/tools.jar, hibernate.bytecode.use_reflection_optimizer=false, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/home/d6831/as/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, hibernate.connection.provider_class=org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider, java.io.tmpdir=/tmp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, javax.persistence.sharedCache.mode=UNSPECIFIED, server.loader=, os.arch=amd64, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/usr/lib/jvm/jdk1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/home/d6831, line.separator=
, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, wtp.deploy=/home/d6831/workspace-juno/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps, file.encoding=UTF-8, hibernate.use_identifier_rollback=false, java.specification.version=1.6, hibernate.show_sql=true}
10:11:45,235 DEBUG DefaultIdentifierGeneratorFactory:89 - Setting dialect [org.hibernate.dialect.MySQLDialect]
10:11:45,339 DEBUG AbstractEntityPersister:2936 - Static SQL for entity: it.mrwebmaster.hibernate.Car
10:11:45,339 DEBUG AbstractEntityPersister:2941 - Version select: select id from Car where id =?
10:11:45,339 DEBUG AbstractEntityPersister:2944 - Snapshot select: select car_.id, car_.name as name0_, car_.year as year0_ from Car car_ where car_.id=?
10:11:45,339 DEBUG AbstractEntityPersister:2947 - Insert 0: insert into Car (name, year, id) values (?, ?, ?)
10:11:45,340 DEBUG AbstractEntityPersister:2948 - Update 0: update Car set name=?, year=? where id=?
10:11:45,340 DEBUG AbstractEntityPersister:2949 - Delete 0: delete from Car where id=?
10:11:45,340 DEBUG AbstractEntityPersister:2953 - Identity insert: insert into Car (name, year) values (?, ?)
10:11:45,360 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [NONE]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,360 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [READ]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,361 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [UPGRADE]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? for update
10:11:45,361 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [UPGRADE_NOWAIT]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? for update
10:11:45,362 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [FORCE]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? for update
10:11:45,362 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [PESSIMISTIC_READ]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? lock in share mode
10:11:45,362 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [PESSIMISTIC_WRITE]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? for update
10:11:45,363 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [PESSIMISTIC_FORCE_INCREMENT]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=? for update
10:11:45,363 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [OPTIMISTIC]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,364 DEBUG EntityLoader:124 - Static select for entity it.mrwebmaster.hibernate.Car [OPTIMISTIC_FORCE_INCREMENT]: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,374 DEBUG EntityLoader:56 - Static select for action ACTION_MERGE on entity it.mrwebmaster.hibernate.Car: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,375 DEBUG EntityLoader:56 - Static select for action ACTION_REFRESH on entity it.mrwebmaster.hibernate.Car: select car0_.id as id0_0_, car0_.name as name0_0_, car0_.year as year0_0_ from Car car0_ where car0_.id=?
10:11:45,379 DEBUG SessionFactoryObjectFactory:62 - initializing class SessionFactoryObjectFactory
10:11:45,380 DEBUG SessionFactoryObjectFactory:99 - registered: ff8081813cd2d2ed013cd2d2f1e10000 (unnamed)
10:11:45,381 INFO SessionFactoryObjectFactory:105 - Not binding factory to JNDI, no JNDI name configured
10:11:45,381 DEBUG SessionFactoryImpl:378 - instantiated session factory
10:11:45,391 INFO SchemaUpdate:155 - Running hbm2ddl schema update
10:11:45,392 INFO SchemaUpdate:167 - fetching database metadata
10:11:45,396 INFO SchemaUpdate:179 - updating schema
10:11:45,397 DEBUG AnnotationConfiguration:318 - Execute first pass mapping processing
10:11:45,397 DEBUG AnnotationConfiguration:617 - Process hbm files
10:11:45,397 DEBUG AnnotationConfiguration:625 - Process annotated classes
10:11:45,397 DEBUG AnnotationConfiguration:486 - processing fk mappings (*ToOne and JoinedSubclass)
...................
Feb 13, 2013 10:11:45 AM org.springframework.web.context.ContextLoader initWebApplicationContext
INFO: Root WebApplicationContext: initialization completed in 3301 ms
Feb 13, 2013 10:11:46 AM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'spring-mvc'
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.FrameworkServlet initServletBean
INFO: FrameworkServlet 'spring-mvc': initialization started
Feb 13, 2013 10:11:46 AM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing WebApplicationContext for namespace 'spring-mvc-servlet': startup date [Wed Feb 13 10:11:46 CET 2013]; parent: Root WebApplicationContext
Feb 13, 2013 10:11:46 AM org.springframework.beans.factory.xml.XmlBeanDefinitionReader loadBeanDefinitions
INFO: Loading XML bean definitions from ServletContext resource [/WEB-INF/spring-mvc-servlet.xml]
Feb 13, 2013 10:11:46 AM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@34a083f2: defining beans [org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping#0,org.springframework.format.support.FormattingConversionServiceFactoryBean#0,org.springframework.validation.beanvalidation.LocalValidatorFactoryBean#0,org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter#0,org.springframework.web.servlet.handler.MappedInterceptor#0,it.mrwebmaster.mvc.BookController#0,it.mrwebmaster.mvc.FormController#0,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@1f47e668
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/create] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/create.*] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/create/] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/getview] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/getview.*] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/getview/] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/createBook] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/createBook.*] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/createBook/] onto handler [it.mrwebmaster.mvc.BookController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/formExample] onto handler [it.mrwebmaster.mvc.FormController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/formExample.*] onto handler [it.mrwebmaster.mvc.FormController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/formExample/] onto handler [it.mrwebmaster.mvc.FormController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/submit] onto handler [it.mrwebmaster.mvc.FormController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/submit.*] onto handler [it.mrwebmaster.mvc.FormController#0]
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.handler.AbstractUrlHandlerMapping registerHandler
INFO: Mapped URL path [/submit/] onto handler [it.mrwebmaster.mvc.FormController#0]
10:11:46,168 DEBUG ResourceBundleMessageInterpolator:193 - ValidationMessages not found. Delegating to org.hibernate.validator.ValidationMessages
10:11:46,169 DEBUG DefaultTraversableResolver:68 - Found javax.persistence.PersistenceUtil on classpath.
10:11:46,169 INFO DefaultTraversableResolver:89 - Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.
10:11:46,171 DEBUG ValidationXmlParser:218 - No META-INF/validation.xml found. Using annotation based configuration only
Feb 13, 2013 10:11:46 AM org.springframework.web.servlet.FrameworkServlet initServletBean
INFO: FrameworkServlet 'spring-mvc': initialization completed in 302 ms
Feb 13, 2013 10:11:46 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Feb 13, 2013 10:11:46 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
Feb 13, 2013 10:11:46 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 5768 ms