hibernate3 学习笔记(三) hibernate 日志配置文件 log4j.properties

        hibernate 采用 apach common logging 、并结合 log4j 进行日志输出和控制。前面的简单实例中，之所以要引入log4j.properties，就是对日志输出的配置。

        完整的log4j.properties文件如下所示：

1. ### direct log messages to stdout ###
2. log4j.appender.stdout=org.apache.log4j.ConsoleAppender
3. log4j.appender.stdout.Target=System.out
4. log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
5. log4j.appender.stdout.layout.ConversionPattern=%d{ABSOLUTE} %5p %c{1}:%L - %m%n
7. ### direct messages to file hibernate.log ###
8. #log4j.appender.file=org.apache.log4j.FileAppender
9. #log4j.appender.file.File=hibernate.log
10. #log4j.appender.file.layout=org.apache.log4j.PatternLayout
11. #log4j.appender.file.layout.ConversionPattern=%d{ABSOLUTE} %5p %c{1}:%L - %m%n
13. ### set log levels - for more verbose logging change 'info' to 'debug' ###
15. log4j.rootLogger=warn, stdout
17. #log4j.logger.org.hibernate=info
18. log4j.logger.org.hibernate=debug
20. ### log HQL query parser activity
21. #log4j.logger.org.hibernate.hql.ast.AST=debug
23. ### log just the SQL
24. #log4j.logger.org.hibernate.SQL=debug
26. ### log JDBC bind parameters ###
27. log4j.logger.org.hibernate.type=info
28. #log4j.logger.org.hibernate.type=debug
30. ### log schema export/update ###
31. log4j.logger.org.hibernate.tool.hbm2ddl=debug
33. ### log HQL parse trees
34. #log4j.logger.org.hibernate.hql=debug
36. ### log cache activity ###
37. #log4j.logger.org.hibernate.cache=debug
39. ### log transaction activity
40. #log4j.logger.org.hibernate.transaction=debug
42. ### log JDBC resource acquisition
43. #log4j.logger.org.hibernate.jdbc=debug
45. ### enable the following line if you want to track down connection ###
46. ### leakages when using DriverManagerConnectionProvider ###
47. #log4j.logger.org.hibernate.connection.DriverManagerConnectionProvider=trace

    将  log4j.logger.org.hibernate=debug  更改成 log4j.logger.org.hibernate=error 时，只输出SQL和错误信息。

如下所示：

Hibernate: select hibernate_sequence.nextval from dual
Hibernate: insert into CDMA_BTS (CODE, NAME, AREAID, BARNID, TYPE, FACTORY, SPEC, USEBEGINTIME, STAFF, RECORDTIME, PROPERTY, FIXED_CODE, STATE, MAINTENANCE_MODE, NOTE, ZCZT, LOCAL, WHBURDEN, STATIONID, COVERTYPE, CHAINCOUNT, SHOOTCOUNT, MSCID, BSCID, TOWERID, IFSURVEY, NETWORDCODE, RATED, MAXSHOOT, MAINTENANCE, AEGISAGREEMENT, ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)

    如果是 log4j.logger.org.hibernate=debug  则输出：

09:54:06,484  INFO Environment:543 - Hibernate 3.3.1.GA
09:54:06,500  INFO Environment:576 - hibernate.properties not found
09:54:06,500  INFO Environment:709 - Bytecode provider name : javassist
09:54:06,515  INFO Environment:627 - using JDK 1.4 java.sql.Timestamp handling
09:54:06,562  INFO Configuration:1460 - configuring from resource: /hibernate.cfg.xml
09:54:06,562  INFO Configuration:1437 - Configuration resource: /hibernate.cfg.xml
09:54:06,609 DEBUG DTDEntityResolver:64 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
09:54:06,609 DEBUG DTDEntityResolver:66 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
09:54:06,609 DEBUG DTDEntityResolver:76 - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.url=jdbc:oracle:thin:@10.52.16.138:1521:oragxlu
09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver
09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.username=cdmagis
09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.password=cdmagis
09:54:06,640 DEBUG Configuration:1421 - dialect=org.hibernate.dialect.OracleDialect
09:54:06,640 DEBUG Configuration:1421 - hibernate.show_sql=true
09:54:06,640 DEBUG Configuration:1421 - hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory
09:54:06,640 DEBUG Configuration:1620 - cdma<-org.dom4j.tree.DefaultAttribute@fd54d6 [Attribute: name resource value "CdmaBts.hbm.xml"]
09:54:06,640  INFO Configuration:586 - Reading mappings from resource : CdmaBts.hbm.xml
09:54:06,656 DEBUG DTDEntityResolver:64 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
09:54:06,656 DEBUG DTDEntityResolver:66 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
09:54:06,656 DEBUG DTDEntityResolver:76 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
09:54:06,750  INFO HbmBinder:322 - Mapping class: test.src.CdmaBts -> CDMA_BTS
09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Id -> ID
09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Code -> CODE
09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Name -> NAME
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Areaid -> AREAID
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Barnid -> BARNID
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Type -> TYPE
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Factory -> FACTORY
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Spec -> SPEC
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Usebegintime -> USEBEGINTIME
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Staff -> STAFF
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Recordtime -> RECORDTIME
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Property -> PROPERTY
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: FixedCode -> FIXED_CODE
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: State -> STATE
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: MaintenanceMode -> MAINTENANCE_MODE
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Note -> NOTE
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Zczt -> ZCZT
09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Local -> LOCAL
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Whburden -> WHBURDEN
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Stationid -> STATIONID
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Covertype -> COVERTYPE
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Chaincount -> CHAINCOUNT
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Shootcount -> SHOOTCOUNT
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Mscid -> MSCID
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Bscid -> BSCID
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Towerid -> TOWERID
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Ifsurvey -> IFSURVEY
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Networdcode -> NETWORDCODE
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Rated -> RATED
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Maxshoot -> MAXSHOOT
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Maintenance -> MAINTENANCE
09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Aegisagreement -> AEGISAGREEMENT
09:54:06,781  INFO Configuration:1575 - Configured SessionFactory: cdma
09:54:06,781 DEBUG Configuration:1576 - properties: {hibernate.connection.password=cdmagis, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=D:/JavaDev/jdk_1.5/jre/bin, java.vm.version=1.5.0_10-b03, hibernate.connection.username=cdmagis, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=CN, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=D:/JavaDev/MyProject/HibernateSample, java.runtime.version=1.5.0_10-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=D:/JavaDev/jdk_1.5/jre/lib/endorsed, os.arch=x86, java.io.tmpdir=C:/DOCUME~1/wanghq/LOCALS~1/Temp/, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.jnu.encoding=GBK, java.library.path=D:/JavaDev/jdk_1.5/bin;.;C:/WINDOWS/system32;C:/WINDOWS;d:/sybase/OCS-12_5/lib3p;d:/sybase/OCS-12_5/dll;d:/sybase/OCS-12_5/bin;d:/sybase/SQLRemote/dll;d:/sybase/RPL-12_5/bin;d:/sybase/JS-12_5/bin;d:/sybase/ASE-12_5/dll;d:/sybase/ASE-12_5/bin;C:/Program Files/Java/jdk1.5.0_04/bin;C:/PROGRA~1/Borland/CBUILD~1/Projects/Bpl;C:/PROGRA~1/Borland/CBUILD~1/Bin;d:/oracle/ora92/bin;C:/Program Files/Oracle/jre/1.3.1/bin;C:/Program Files/Oracle/jre/1.1.8/bin;C:/WINDOWS/system32;C:/WINDOWS;C:/WINDOWS/System32/Wbem;C:/Program Files/IDM Computer Solutions/UltraEdit-32;C:/Program Files/Rational/common;C:/Program Files/Common Files/Roxio Shared/9.0/DLLShared/, java.specification.name=Java Platform API Specification, java.class.version=49.0, sun.management.compiler=HotSpot Client Compiler, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, os.version=5.1, user.home=C:/Documents and Settings/wanghq, user.timezone=Asia/Shanghai, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=GBK, java.specification.version=1.5, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, user.name=wanghq, java.class.path=D:/JavaDev/MyProject/HibernateSample/bin;D:/JavaDev/MyProject/HibernateSample/lib/classes12.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/hibernate3.jar;D:/JavaDev/MyProject/HibernateSample/lib/dom4j-1.6.1.jar;D:/JavaDev/MyProject/HibernateSample/lib/slf4j-log4j12-1.5.2.jar;D:/JavaDev/MyProject/HibernateSample/lib/slf4j-api-1.5.2.jar;D:/JavaDev/MyProject/HibernateSample/lib/log4j-1.2.15.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/jta-1.1.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/antlr-2.7.6.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/commons-collections-3.1.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/javassist-3.4.GA.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/hibernate-testing.jar;D:/JavaDev/Ganymede/eclipse/plugins/org.junit4_4.3.1/junit.jar;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/322/1/.cp/;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/320/1/.cp/;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/321/1/.cp/, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=D:/JavaDev/jdk_1.5/jre, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.OracleDialect, hibernate.connection.url=jdbc:oracle:thin:@10.52.16.138:1521:oragxlu, user.language=zh, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, sharing, java.version=1.5.0_10, java.ext.dirs=D:/JavaDev/jdk_1.5/jre/lib/ext, sun.boot.class.path=D:/JavaDev/jdk_1.5/jre/lib/rt.jar;D:/JavaDev/jdk_1.5/jre/lib/i18n.jar;D:/JavaDev/jdk_1.5/jre/lib/sunrsasign.jar;D:/JavaDev/jdk_1.5/jre/lib/jsse.jar;D:/JavaDev/jdk_1.5/jre/lib/jce.jar;D:/JavaDev/jdk_1.5/jre/lib/charsets.jar;D:/JavaDev/jdk_1.5/jre/classes, java.vendor=Sun Microsystems Inc., file.separator=/, hibernate.session_factory_name=cdma, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, sun.desktop=windows, dialect=org.hibernate.dialect.OracleDialect, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86}
09:54:06,781 DEBUG Configuration:1318 - Preparing to build session factory with filters : {}
09:54:06,781 DEBUG Configuration:1153 - processing extends queue
09:54:06,781 DEBUG Configuration:1157 - processing collection mappings
09:54:06,781 DEBUG Configuration:1168 - processing native query and ResultSetMapping mappings
09:54:06,781 DEBUG Configuration:1176 - processing association property references
09:54:06,781 DEBUG Configuration:1198 - processing foreign key constraints
09:54:06,828  INFO DriverManagerConnectionProvider:64 - Using Hibernate built-in connection pool (not for production use!)
09:54:06,828  INFO DriverManagerConnectionProvider:65 - Hibernate connection pool size: 20
09:54:06,828  INFO DriverManagerConnectionProvider:68 - autocommit mode: false
09:54:06,828  INFO DriverManagerConnectionProvider:103 - using driver: Oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@10.52.16.138:1521:oragxlu
09:54:06,828  INFO DriverManagerConnectionProvider:106 - connection properties: {user=cdmagis, password=cdmagis}
09:54:06,828 DEBUG DriverManagerConnectionProvider:132 - opening new JDBC connection
09:54:07,046 DEBUG DriverManagerConnectionProvider:138 - created connection to: jdbc:oracle:thin:@10.52.16.138:1521:oragxlu, Isolation Level: 2
09:54:07,046  INFO SettingsFactory:116 - RDBMS: Oracle, version: Oracle9i Enterprise Edition Release 9.2.0.1.0 - Production
With the Partitioning, OLAP and Oracle Data Mining options
JServer Release 9.2.0.1.0 - Production
09:54:07,046  INFO SettingsFactory:117 - JDBC driver: Oracle JDBC driver, version: 10.2.0.1.0
09:54:07,062  INFO Dialect:175 - Using dialect: org.hibernate.dialect.OracleDialect
09:54:07,062  WARN Oracle9Dialect:60 - The Oracle9Dialect dialect has been deprecated; use either Oracle9iDialect or Oracle10gDialect instead
09:54:07,062  WARN OracleDialect:49 - The OracleDialect dialect has been deprecated; use Oracle8iDialect instead
09:54:07,078  INFO TransactionFactoryFactory:62 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
09:54:07,078  INFO TransactionManagerLookupFactory:80 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
09:54:07,078  INFO SettingsFactory:170 - Automatic flush during beforeCompletion(): disabled
09:54:07,078  INFO SettingsFactory:174 - Automatic session close at end of transaction: disabled
09:54:07,078  INFO SettingsFactory:181 - JDBC batch size: 15
09:54:07,078  INFO SettingsFactory:184 - JDBC batch updates for versioned data: disabled
09:54:07,078  INFO SettingsFactory:189 - Scrollable result sets: enabled
09:54:07,078 DEBUG SettingsFactory:193 - Wrap result sets: disabled
09:54:07,078  INFO SettingsFactory:197 - JDBC3 getGeneratedKeys(): disabled
09:54:07,078  INFO SettingsFactory:205 - Connection release mode: auto
09:54:07,078  INFO SettingsFactory:232 - Default batch fetch size: 1
09:54:07,078  INFO SettingsFactory:236 - Generate SQL with comments: disabled
09:54:07,078  INFO SettingsFactory:240 - Order SQL updates by primary key: disabled
09:54:07,078  INFO SettingsFactory:244 - Order SQL inserts for batching: disabled
09:54:07,078  INFO SettingsFactory:420 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
09:54:07,078  INFO ASTQueryTranslatorFactory:47 - Using ASTQueryTranslatorFactory
09:54:07,078  INFO SettingsFactory:252 - Query language substitutions: {}
09:54:07,078  INFO SettingsFactory:257 - JPA-QL strict compliance: disabled
09:54:07,078  INFO SettingsFactory:262 - Second-level cache: enabled
09:54:07,078  INFO SettingsFactory:266 - Query cache: disabled
09:54:07,078  INFO SettingsFactory:405 - Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
09:54:07,078  INFO SettingsFactory:276 - Optimize cache for minimal puts: disabled
09:54:07,078  INFO SettingsFactory:285 - Structured second-level cache entries: disabled
09:54:07,093  INFO SettingsFactory:305 - Echoing all SQL to stdout
09:54:07,093  INFO SettingsFactory:314 - Statistics: disabled
09:54:07,093  INFO SettingsFactory:318 - Deleted entity synthetic identifier rollback: disabled
09:54:07,093  INFO SettingsFactory:333 - Default entity-mode: pojo
09:54:07,093  INFO SettingsFactory:337 - Named query checking : enabled
09:54:07,125  INFO SessionFactoryImpl:187 - building session factory
09:54:07,125 DEBUG SessionFactoryImpl:205 - Session factory constructed with filter configurations : {}
09:54:07,125 DEBUG SessionFactoryImpl:209 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=cdmagis, sun.boot.library.path=D:/JavaDev/jdk_1.5/jre/bin, java.vm.version=1.5.0_10-b03, hibernate.connection.username=cdmagis, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=CN, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=D:/JavaDev/MyProject/HibernateSample, java.runtime.version=1.5.0_10-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=D:/JavaDev/jdk_1.5/jre/lib/endorsed, os.arch=x86, java.io.tmpdir=C:/DOCUME~1/wanghq/LOCALS~1/Temp/, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.jnu.encoding=GBK, java.library.path=D:/JavaDev/jdk_1.5/bin;.;C:/WINDOWS/system32;C:/WINDOWS;d:/sybase/OCS-12_5/lib3p;d:/sybase/OCS-12_5/dll;d:/sybase/OCS-12_5/bin;d:/sybase/SQLRemote/dll;d:/sybase/RPL-12_5/bin;d:/sybase/JS-12_5/bin;d:/sybase/ASE-12_5/dll;d:/sybase/ASE-12_5/bin;C:/Program Files/Java/jdk1.5.0_04/bin;C:/PROGRA~1/Borland/CBUILD~1/Projects/Bpl;C:/PROGRA~1/Borland/CBUILD~1/Bin;d:/oracle/ora92/bin;C:/Program Files/Oracle/jre/1.3.1/bin;C:/Program Files/Oracle/jre/1.1.8/bin;C:/WINDOWS/system32;C:/WINDOWS;C:/WINDOWS/System32/Wbem;C:/Program Files/IDM Computer Solutions/UltraEdit-32;C:/Program Files/Rational/common;C:/Program Files/Common Files/Roxio Shared/9.0/DLLShared/, java.specification.name=Java Platform API Specification, java.class.version=49.0, sun.management.compiler=HotSpot Client Compiler, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, os.version=5.1, user.home=C:/Documents and Settings/wanghq, user.timezone=Asia/Shanghai, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=GBK, java.specification.version=1.5, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, java.class.path=D:/JavaDev/MyProject/HibernateSample/bin;D:/JavaDev/MyProject/HibernateSample/lib/classes12.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/hibernate3.jar;D:/JavaDev/MyProject/HibernateSample/lib/dom4j-1.6.1.jar;D:/JavaDev/MyProject/HibernateSample/lib/slf4j-log4j12-1.5.2.jar;D:/JavaDev/MyProject/HibernateSample/lib/slf4j-api-1.5.2.jar;D:/JavaDev/MyProject/HibernateSample/lib/log4j-1.2.15.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/jta-1.1.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/antlr-2.7.6.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/commons-collections-3.1.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/lib/required/javassist-3.4.GA.jar;D:/JavaDev/Ganymede/eclipse/myplug/hibernate-distribution-3.3.1.GA/hibernate-testing.jar;D:/JavaDev/Ganymede/eclipse/plugins/org.junit4_4.3.1/junit.jar;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/322/1/.cp/;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/320/1/.cp/;/D:/JavaDev/Ganymede/eclipse/configuration/org.eclipse.osgi/bundles/321/1/.cp/, user.name=wanghq, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=D:/JavaDev/jdk_1.5/jre, hibernate.connection.url=jdbc:oracle:thin:@10.52.16.138:1521:oragxlu, hibernate.dialect=org.hibernate.dialect.OracleDialect, java.specification.vendor=Sun Microsystems Inc., user.language=zh, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, sharing, java.version=1.5.0_10, java.ext.dirs=D:/JavaDev/jdk_1.5/jre/lib/ext, sun.boot.class.path=D:/JavaDev/jdk_1.5/jre/lib/rt.jar;D:/JavaDev/jdk_1.5/jre/lib/i18n.jar;D:/JavaDev/jdk_1.5/jre/lib/sunrsasign.jar;D:/JavaDev/jdk_1.5/jre/lib/jsse.jar;D:/JavaDev/jdk_1.5/jre/lib/jce.jar;D:/JavaDev/jdk_1.5/jre/lib/charsets.jar;D:/JavaDev/jdk_1.5/jre/classes, java.vendor=Sun Microsystems Inc., file.separator=/, hibernate.session_factory_name=cdma, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.desktop=windows, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, dialect=org.hibernate.dialect.OracleDialect}
09:54:07,359 DEBUG AbstractEntityPersister:2766 - Static SQL for entity: test.src.CdmaBts
09:54:07,359 DEBUG AbstractEntityPersister:2771 -  Version select: select ID from CDMA_BTS where ID =?
09:54:07,359 DEBUG AbstractEntityPersister:2774 -  Snapshot select: select cdmabts_.ID, cdmabts_.CODE as CODE0_, cdmabts_.NAME as NAME0_, cdmabts_.AREAID as AREAID0_, cdmabts_.BARNID as BARNID0_, cdmabts_.TYPE as TYPE0_, cdmabts_.FACTORY as FACTORY0_, cdmabts_.SPEC as SPEC0_, cdmabts_.USEBEGINTIME as USEBEGIN9_0_, cdmabts_.STAFF as STAFF0_, cdmabts_.RECORDTIME as RECORDTIME0_, cdmabts_.PROPERTY as PROPERTY0_, cdmabts_.FIXED_CODE as FIXED13_0_, cdmabts_.STATE as STATE0_, cdmabts_.MAINTENANCE_MODE as MAINTEN15_0_, cdmabts_.NOTE as NOTE0_, cdmabts_.ZCZT as ZCZT0_, cdmabts_.LOCAL as LOCAL0_, cdmabts_.WHBURDEN as WHBURDEN0_, cdmabts_.STATIONID as STATIONID0_, cdmabts_.COVERTYPE as COVERTYPE0_, cdmabts_.CHAINCOUNT as CHAINCOUNT0_, cdmabts_.SHOOTCOUNT as SHOOTCOUNT0_, cdmabts_.MSCID as MSCID0_, cdmabts_.BSCID as BSCID0_, cdmabts_.TOWERID as TOWERID0_, cdmabts_.IFSURVEY as IFSURVEY0_, cdmabts_.NETWORDCODE as NETWORD28_0_, cdmabts_.RATED as RATED0_, cdmabts_.MAXSHOOT as MAXSHOOT0_, cdmabts_.MAINTENANCE as MAINTEN31_0_, cdmabts_.AEGISAGREEMENT as AEGISAG32_0_ from CDMA_BTS cdmabts_ where cdmabts_.ID=?
09:54:07,359 DEBUG AbstractEntityPersister:2777 -  Insert 0: insert into CDMA_BTS (CODE, NAME, AREAID, BARNID, TYPE, FACTORY, SPEC, USEBEGINTIME, STAFF, RECORDTIME, PROPERTY, FIXED_CODE, STATE, MAINTENANCE_MODE, NOTE, ZCZT, LOCAL, WHBURDEN, STATIONID, COVERTYPE, CHAINCOUNT, SHOOTCOUNT, MSCID, BSCID, TOWERID, IFSURVEY, NETWORDCODE, RATED, MAXSHOOT, MAINTENANCE, AEGISAGREEMENT, ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
09:54:07,359 DEBUG AbstractEntityPersister:2778 -  Update 0: update CDMA_BTS set CODE=?, NAME=?, AREAID=?, BARNID=?, TYPE=?, FACTORY=?, SPEC=?, USEBEGINTIME=?, STAFF=?, RECORDTIME=?, PROPERTY=?, FIXED_CODE=?, STATE=?, MAINTENANCE_MODE=?, NOTE=?, ZCZT=?, LOCAL=?, WHBURDEN=?, STATIONID=?, COVERTYPE=?, CHAINCOUNT=?, SHOOTCOUNT=?, MSCID=?, BSCID=?, TOWERID=?, IFSURVEY=?, NETWORDCODE=?, RATED=?, MAXSHOOT=?, MAINTENANCE=?, AEGISAGREEMENT=? where ID=?
09:54:07,359 DEBUG AbstractEntityPersister:2779 -  Delete 0: delete from CDMA_BTS where ID=?
09:54:07,375 DEBUG EntityLoader:102 - Static select for entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=?
09:54:07,375 DEBUG EntityLoader:102 - Static select for entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=?
09:54:07,375 DEBUG EntityLoader:102 - Static select for entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=? for update
09:54:07,375 DEBUG EntityLoader:102 - Static select for entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=? for update nowait
09:54:07,375 DEBUG EntityLoader:102 - Static select for entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=? for update nowait
09:54:07,390 DEBUG EntityLoader:57 - Static select for action ACTION_MERGE on entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=?
09:54:07,390 DEBUG EntityLoader:57 - Static select for action ACTION_REFRESH on entity test.src.CdmaBts: select cdmabts0_.ID as ID0_0_, cdmabts0_.CODE as CODE0_0_, cdmabts0_.NAME as NAME0_0_, cdmabts0_.AREAID as AREAID0_0_, cdmabts0_.BARNID as BARNID0_0_, cdmabts0_.TYPE as TYPE0_0_, cdmabts0_.FACTORY as FACTORY0_0_, cdmabts0_.SPEC as SPEC0_0_, cdmabts0_.USEBEGINTIME as USEBEGIN9_0_0_, cdmabts0_.STAFF as STAFF0_0_, cdmabts0_.RECORDTIME as RECORDTIME0_0_, cdmabts0_.PROPERTY as PROPERTY0_0_, cdmabts0_.FIXED_CODE as FIXED13_0_0_, cdmabts0_.STATE as STATE0_0_, cdmabts0_.MAINTENANCE_MODE as MAINTEN15_0_0_, cdmabts0_.NOTE as NOTE0_0_, cdmabts0_.ZCZT as ZCZT0_0_, cdmabts0_.LOCAL as LOCAL0_0_, cdmabts0_.WHBURDEN as WHBURDEN0_0_, cdmabts0_.STATIONID as STATIONID0_0_, cdmabts0_.COVERTYPE as COVERTYPE0_0_, cdmabts0_.CHAINCOUNT as CHAINCOUNT0_0_, cdmabts0_.SHOOTCOUNT as SHOOTCOUNT0_0_, cdmabts0_.MSCID as MSCID0_0_, cdmabts0_.BSCID as BSCID0_0_, cdmabts0_.TOWERID as TOWERID0_0_, cdmabts0_.IFSURVEY as IFSURVEY0_0_, cdmabts0_.NETWORDCODE as NETWORD28_0_0_, cdmabts0_.RATED as RATED0_0_, cdmabts0_.MAXSHOOT as MAXSHOOT0_0_, cdmabts0_.MAINTENANCE as MAINTEN31_0_0_, cdmabts0_.AEGISAGREEMENT as AEGISAG32_0_0_ from CDMA_BTS cdmabts0_ where cdmabts0_.ID=?
09:54:07,390 DEBUG SessionFactoryObjectFactory:62 - initializing class SessionFactoryObjectFactory
09:54:07,390 DEBUG SessionFactoryObjectFactory:99 - registered: 4028e4831df566fe011df566ff9e0000 (cdma)
09:54:07,390  INFO SessionFactoryObjectFactory:109 - Factory name: cdma
09:54:07,390  INFO NamingHelper:49 - JNDI InitialContext properties:{}
09:54:07,390  WARN SessionFactoryObjectFactory:121 - Could not bind factory to JNDI
javax.naming.NoInitialContextException: Need to specify class name in environment or system property, or as an applet parameter, or in an application resource file:  java.naming.factory.initial
 at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:645)
 at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:247)
 at javax.naming.InitialContext.getURLOrDefaultInitCtx(InitialContext.java:284)
 at javax.naming.InitialContext.getNameParser(InitialContext.java:439)
 at org.hibernate.util.NamingHelper.bind(NamingHelper.java:75)
 at org.hibernate.impl.SessionFactoryObjectFactory.addInstance(SessionFactoryObjectFactory.java:113)
 at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:338)
 at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1327)
 at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:101)
 at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:88)
 at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:79)
 at test.src.CdmaBtsTest.testInsert(CdmaBtsTest.java:33)
 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
 at java.lang.reflect.Method.invoke(Method.java:585)
 at junit.framework.TestCase.runTest(TestCase.java:168)
 at junit.framework.TestCase.runBare(TestCase.java:134)
 at junit.framework.TestResult$1.protect(TestResult.java:110)
 at junit.framework.TestResult.runProtected(TestResult.java:128)
 at junit.framework.TestResult.run(TestResult.java:113)
 at junit.framework.TestCase.run(TestCase.java:124)
 at junit.framework.TestSuite.runTest(TestSuite.java:232)
 at junit.framework.TestSuite.run(TestSuite.java:227)
 at org.junit.internal.runners.OldTestClassRunner.run(OldTestClassRunner.java:76)
 at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:45)
 at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38)
 at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:460)
 at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:673)
 at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:386)
 at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:196)
09:54:07,406 DEBUG SessionFactoryImpl:340 - instantiated session factory
09:54:07,406 DEBUG SessionFactoryImpl:426 - Checking 0 named HQL queries
09:54:07,406 DEBUG SessionFactoryImpl:446 - Checking 0 named SQL queries
09:54:07,437 DEBUG SessionImpl:247 - opened session at timestamp: 12281828474
09:54:07,437 DEBUG JDBCTransaction:82 - begin
09:54:07,437 DEBUG ConnectionManager:444 - opening JDBC connection
09:54:07,437 DEBUG JDBCTransaction:87 - current autocommit status: false
09:54:07,437 DEBUG AbstractBatcher:410 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
09:54:07,437 DEBUG SQL:111 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
09:54:07,593 DEBUG SequenceGenerator:105 - Sequence identifier generated: 22
09:54:07,593 DEBUG AbstractBatcher:418 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
09:54:07,593 DEBUG AbstractSaveEventListener:135 - generated identifier: 22, using strategy: org.hibernate.id.SequenceGenerator
09:54:07,609 DEBUG JDBCTransaction:134 - commit
09:54:07,609 DEBUG AbstractFlushingEventListener:134 - processing flush-time cascades
09:54:07,609 DEBUG AbstractFlushingEventListener:177 - dirty checking collections
09:54:07,609 DEBUG AbstractFlushingEventListener:108 - Flushed: 1 insertions, 0 updates, 0 deletions to 1 objects
09:54:07,609 DEBUG AbstractFlushingEventListener:114 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:54:07,609 DEBUG Printer:106 - listing entities:
09:54:07,609 DEBUG Printer:113 - test.src.CdmaBts{Rated=null, Type=null, Property=null, Local=null, Spec=null, Usebegintime=null, Areaid=null, Ifsurvey=null, Towerid=null, Recordtime=null, FixedCode=null, Chaincount=null, MaintenanceMode=null, Bscid=null, Networdcode=null, Staff=null, Factory=null, Shootcount=null, Zczt=null, Code=test.src.CdmaBts@175078b, Covertype=null, Note=null, Name=null, Maxshoot=null, Barnid=null, Mscid=null, State=null, Aegisagreement=null, Maintenance=null, Stationid=null, Whburden=null, Id=22}
09:54:07,625 DEBUG AbstractBatcher:410 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
09:54:07,625 DEBUG SQL:111 - insert into CDMA_BTS (CODE, NAME, AREAID, BARNID, TYPE, FACTORY, SPEC, USEBEGINTIME, STAFF, RECORDTIME, PROPERTY, FIXED_CODE, STATE, MAINTENANCE_MODE, NOTE, ZCZT, LOCAL, WHBURDEN, STATIONID, COVERTYPE, CHAINCOUNT, SHOOTCOUNT, MSCID, BSCID, TOWERID, IFSURVEY, NETWORDCODE, RATED, MAXSHOOT, MAINTENANCE, AEGISAGREEMENT, ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
Hibernate: insert into CDMA_BTS (CODE, NAME, AREAID, BARNID, TYPE, FACTORY, SPEC, USEBEGINTIME, STAFF, RECORDTIME, PROPERTY, FIXED_CODE, STATE, MAINTENANCE_MODE, NOTE, ZCZT, LOCAL, WHBURDEN, STATIONID, COVERTYPE, CHAINCOUNT, SHOOTCOUNT, MSCID, BSCID, TOWERID, IFSURVEY, NETWORDCODE, RATED, MAXSHOOT, MAINTENANCE, AEGISAGREEMENT, ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
09:54:07,625 DEBUG AbstractBatcher:66 - Executing batch size: 1
09:54:07,625 DEBUG Expectations:77 - success of batch update unknown: 0
09:54:07,625 DEBUG AbstractBatcher:418 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
09:54:07,625 DEBUG JDBCTransaction:147 - committed JDBC Connection
09:54:07,625 DEBUG ConnectionManager:427 - aggressively releasing JDBC connection
09:54:07,625 DEBUG ConnectionManager:464 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]

 

        综上所看，合适的日志输出有利用快速定位和分析，多输出有多输出的好处，少也有少的好处，具体情况具体处理。