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

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

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

1.### direct log messages to stdout ###<br />2.log4j.appender.stdout=org.apache.log4j.ConsoleAppender<br />3.log4j.appender.stdout.Target=System.out<br />4.log4j.appender.stdout.layout=org.apache.log4j.PatternLayout<br />5.log4j.appender.stdout.layout.ConversionPattern=%d{ABSOLUTE} %5p %c{1}:%L - %m%n<br />6.<br />7.### direct messages to file hibernate.log ###<br />8.#log4j.appender.file=org.apache.log4j.FileAppender<br />9.#log4j.appender.file.File=hibernate.log<br />10.#log4j.appender.file.layout=org.apache.log4j.PatternLayout<br />11.#log4j.appender.file.layout.ConversionPattern=%d{ABSOLUTE} %5p %c{1}:%L - %m%n<br />12.<br />13.### set log levels - for more verbose logging change 'info' to 'debug' ###<br />14.<br />15.log4j.rootLogger=warn, stdout<br />16.<br />17.#log4j.logger.org.hibernate=info<br />18.log4j.logger.org.hibernate=debug<br />19.<br />20.### log HQL query parser activity<br />21.#log4j.logger.org.hibernate.hql.ast.AST=debug<br />22.<br />23.### log just the SQL<br />24.#log4j.logger.org.hibernate.SQL=debug<br />25.<br />26.### log JDBC bind parameters ###<br />27.log4j.logger.org.hibernate.type=info28.#log4j.logger.org.hibernate.type=debug<br />29.<br />30.### log schema export/update ###<br />31.log4j.logger.org.hibernate.tool.hbm2ddl=debug<br />32.33.### log HQL parse trees<br />34.#log4j.logger.org.hibernate.hql=debug35.<br />36.### log cache activity ###<br />37.#log4j.logger.org.hibernate.cache=debug38.39.### log transaction activity<br />40.#log4j.logger.org.hibernate.transaction=debug41.42.### log JDBC resource acquisition<br />43.#log4j.logger.org.hibernate.jdbc=debug44.45.### enable the following line if you want to track down connection ###<br />46.### leakages when using DriverManagerConnectionProvider ###<br />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<br />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<br />09:54:06,500  INFO Environment:576 - hibernate.properties not found<br />09:54:06,500  INFO Environment:709 - Bytecode provider name : javassist<br />09:54:06,515  INFO Environment:627 - using JDK 1.4 java.sql.Timestamp handling<br />09:54:06,562  INFO Configuration:1460 - configuring from resource: /hibernate.cfg.xml<br />09:54:06,562  INFO Configuration:1437 - Configuration resource: /hibernate.cfg.xml<br />09:54:06,609 DEBUG DTDEntityResolver:64 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]<br />09:54:06,609 DEBUG DTDEntityResolver:66 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/<br />09:54:06,609 DEBUG DTDEntityResolver:76 - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.url=jdbc:oracle:thin:@10.52.16.138:1521:oragxlu<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.username=cdmagis<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.connection.password=cdmagis<br />09:54:06,640 DEBUG Configuration:1421 - dialect=org.hibernate.dialect.OracleDialect<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.show_sql=true<br />09:54:06,640 DEBUG Configuration:1421 - hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory<br />09:54:06,640 DEBUG Configuration:1620 - cdma<-org.dom4j.tree.DefaultAttribute@fd54d6 [Attribute: name resource value "CdmaBts.hbm.xml"]<br />09:54:06,640  INFO Configuration:586 - Reading mappings from resource : CdmaBts.hbm.xml<br />09:54:06,656 DEBUG DTDEntityResolver:64 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]<br />09:54:06,656 DEBUG DTDEntityResolver:66 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/<br />09:54:06,656 DEBUG DTDEntityResolver:76 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath<br />09:54:06,750  INFO HbmBinder:322 - Mapping class: test.src.CdmaBts -> CDMA_BTS<br />09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Id -> ID<br />09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Code -> CODE<br />09:54:06,750 DEBUG HbmBinder:1289 - Mapped property: Name -> NAME<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Areaid -> AREAID<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Barnid -> BARNID<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Type -> TYPE<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Factory -> FACTORY<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Spec -> SPEC<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Usebegintime -> USEBEGINTIME<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Staff -> STAFF<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Recordtime -> RECORDTIME<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Property -> PROPERTY<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: FixedCode -> FIXED_CODE<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: State -> STATE<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: MaintenanceMode -> MAINTENANCE_MODE<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Note -> NOTE<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Zczt -> ZCZT<br />09:54:06,765 DEBUG HbmBinder:1289 - Mapped property: Local -> LOCAL<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Whburden -> WHBURDEN<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Stationid -> STATIONID<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Covertype -> COVERTYPE<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Chaincount -> CHAINCOUNT<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Shootcount -> SHOOTCOUNT<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Mscid -> MSCID<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Bscid -> BSCID<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Towerid -> TOWERID<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Ifsurvey -> IFSURVEY<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Networdcode -> NETWORDCODE<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Rated -> RATED<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Maxshoot -> MAXSHOOT<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Maintenance -> MAINTENANCE<br />09:54:06,781 DEBUG HbmBinder:1289 - Mapped property: Aegisagreement -> AEGISAGREEMENT<br />09:54:06,781  INFO Configuration:1575 - Configured SessionFactory: cdma<br />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=<br />, 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}<br />09:54:06,781 DEBUG Configuration:1318 - Preparing to build session factory with filters : {}<br />09:54:06,781 DEBUG Configuration:1153 - processing extends queue<br />09:54:06,781 DEBUG Configuration:1157 - processing collection mappings<br />09:54:06,781 DEBUG Configuration:1168 - processing native query and ResultSetMapping mappings<br />09:54:06,781 DEBUG Configuration:1176 - processing association property references<br />09:54:06,781 DEBUG Configuration:1198 - processing foreign key constraints<br />09:54:06,828  INFO DriverManagerConnectionProvider:64 - Using Hibernate built-in connection pool (not for production use!)<br />09:54:06,828  INFO DriverManagerConnectionProvider:65 - Hibernate connection pool size: 20<br />09:54:06,828  INFO DriverManagerConnectionProvider:68 - autocommit mode: false<br />09:54:06,828  INFO DriverManagerConnectionProvider:103 - using driver: Oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@10.52.16.138:1521:oragxlu<br />09:54:06,828  INFO DriverManagerConnectionProvider:106 - connection properties: {user=cdmagis, password=cdmagis}<br />09:54:06,828 DEBUG DriverManagerConnectionProvider:132 - opening new JDBC connection<br />09:54:07,046 DEBUG DriverManagerConnectionProvider:138 - created connection to: jdbc:oracle:thin:@10.52.16.138:1521:oragxlu, Isolation Level: 2<br />09:54:07,046  INFO SettingsFactory:116 - RDBMS: Oracle, version: Oracle9i Enterprise Edition Release 9.2.0.1.0 - Production<br />With the Partitioning, OLAP and Oracle Data Mining options<br />JServer Release 9.2.0.1.0 - Production<br />09:54:07,046  INFO SettingsFactory:117 - JDBC driver: Oracle JDBC driver, version: 10.2.0.1.0<br />09:54:07,062  INFO Dialect:175 - Using dialect: org.hibernate.dialect.OracleDialect<br />09:54:07,062  WARN Oracle9Dialect:60 - The Oracle9Dialect dialect has been deprecated; use either Oracle9iDialect or Oracle10gDialect instead<br />09:54:07,062  WARN OracleDialect:49 - The OracleDialect dialect has been deprecated; use Oracle8iDialect instead<br />09:54:07,078  INFO TransactionFactoryFactory:62 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory<br />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)<br />09:54:07,078  INFO SettingsFactory:170 - Automatic flush during beforeCompletion(): disabled<br />09:54:07,078  INFO SettingsFactory:174 - Automatic session close at end of transaction: disabled<br />09:54:07,078  INFO SettingsFactory:181 - JDBC batch size: 15<br />09:54:07,078  INFO SettingsFactory:184 - JDBC batch updates for versioned data: disabled<br />09:54:07,078  INFO SettingsFactory:189 - Scrollable result sets: enabled<br />09:54:07,078 DEBUG SettingsFactory:193 - Wrap result sets: disabled<br />09:54:07,078  INFO SettingsFactory:197 - JDBC3 getGeneratedKeys(): disabled<br />09:54:07,078  INFO SettingsFactory:205 - Connection release mode: auto<br />09:54:07,078  INFO SettingsFactory:232 - Default batch fetch size: 1<br />09:54:07,078  INFO SettingsFactory:236 - Generate SQL with comments: disabled<br />09:54:07,078  INFO SettingsFactory:240 - Order SQL updates by primary key: disabled<br />09:54:07,078  INFO SettingsFactory:244 - Order SQL inserts for batching: disabled<br />09:54:07,078  INFO SettingsFactory:420 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory<br />09:54:07,078  INFO ASTQueryTranslatorFactory:47 - Using ASTQueryTranslatorFactory<br />09:54:07,078  INFO SettingsFactory:252 - Query language substitutions: {}<br />09:54:07,078  INFO SettingsFactory:257 - JPA-QL strict compliance: disabled<br />09:54:07,078  INFO SettingsFactory:262 - Second-level cache: enabled<br />09:54:07,078  INFO SettingsFactory:266 - Query cache: disabled<br />09:54:07,078  INFO SettingsFactory:405 - Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory<br />09:54:07,078  INFO SettingsFactory:276 - Optimize cache for minimal puts: disabled<br />09:54:07,078  INFO SettingsFactory:285 - Structured second-level cache entries: disabled<br />09:54:07,093  INFO SettingsFactory:305 - Echoing all SQL to stdout<br />09:54:07,093  INFO SettingsFactory:314 - Statistics: disabled<br />09:54:07,093  INFO SettingsFactory:318 - Deleted entity synthetic identifier rollback: disabled<br />09:54:07,093  INFO SettingsFactory:333 - Default entity-mode: pojo<br />09:54:07,093  INFO SettingsFactory:337 - Named query checking : enabled<br />09:54:07,125  INFO SessionFactoryImpl:187 - building session factory<br />09:54:07,125 DEBUG SessionFactoryImpl:205 - Session factory constructed with filter configurations : {}<br />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=<br />, 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}<br />09:54:07,359 DEBUG AbstractEntityPersister:2766 - Static SQL for entity: test.src.CdmaBts<br />09:54:07,359 DEBUG AbstractEntityPersister:2771 -  Version select: select ID from CDMA_BTS where ID =?<br />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=?<br />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 (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)<br />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=?<br />09:54:07,359 DEBUG AbstractEntityPersister:2779 -  Delete 0: delete from CDMA_BTS where ID=?<br />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=?<br />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=?<br />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<br />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<br />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<br />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=?<br />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=?<br />09:54:07,390 DEBUG SessionFactoryObjectFactory:62 - initializing class SessionFactoryObjectFactory<br />09:54:07,390 DEBUG SessionFactoryObjectFactory:99 - registered: 4028e4831df566fe011df566ff9e0000 (cdma)<br />09:54:07,390  INFO SessionFactoryObjectFactory:109 - Factory name: cdma<br />09:54:07,390  INFO NamingHelper:49 - JNDI InitialContext properties:{}<br />09:54:07,390  WARN SessionFactoryObjectFactory:121 - Could not bind factory to JNDI<br />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<br /> at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:645)<br /> at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:247)<br /> at javax.naming.InitialContext.getURLOrDefaultInitCtx(InitialContext.java:284)<br /> at javax.naming.InitialContext.getNameParser(InitialContext.java:439)<br /> at org.hibernate.util.NamingHelper.bind(NamingHelper.java:75)<br /> at org.hibernate.impl.SessionFactoryObjectFactory.addInstance(SessionFactoryObjectFactory.java:113)<br /> at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:338)<br /> at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1327)<br /> at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:101)<br /> at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:88)<br /> at test.src.base._BaseRootDAO.initialize(_BaseRootDAO.java:79)<br /> at test.src.CdmaBtsTest.testInsert(CdmaBtsTest.java:33)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)<br /> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)<br /> at java.lang.reflect.Method.invoke(Method.java:585)<br /> at junit.framework.TestCase.runTest(TestCase.java:168)<br /> at junit.framework.TestCase.runBare(TestCase.java:134)<br /> at junit.framework.TestResult$1.protect(TestResult.java:110)<br /> at junit.framework.TestResult.runProtected(TestResult.java:128)<br /> at junit.framework.TestResult.run(TestResult.java:113)<br /> at junit.framework.TestCase.run(TestCase.java:124)<br /> at junit.framework.TestSuite.runTest(TestSuite.java:232)<br /> at junit.framework.TestSuite.run(TestSuite.java:227)<br /> at org.junit.internal.runners.OldTestClassRunner.run(OldTestClassRunner.java:76)<br /> at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:45)<br /> at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38)<br /> at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:460)<br /> at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:673)<br /> at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:386)<br /> at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:196)<br />09:54:07,406 DEBUG SessionFactoryImpl:340 - instantiated session factory<br />09:54:07,406 DEBUG SessionFactoryImpl:426 - Checking 0 named HQL queries<br />09:54:07,406 DEBUG SessionFactoryImpl:446 - Checking 0 named SQL queries<br />09:54:07,437 DEBUG SessionImpl:247 - opened session at timestamp: 12281828474<br />09:54:07,437 DEBUG JDBCTransaction:82 - begin<br />09:54:07,437 DEBUG ConnectionManager:444 - opening JDBC connection<br />09:54:07,437 DEBUG JDBCTransaction:87 - current autocommit status: false<br />09:54:07,437 DEBUG AbstractBatcher:410 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)<br />09:54:07,437 DEBUG SQL:111 - select hibernate_sequence.nextval from dual<br />Hibernate: select hibernate_sequence.nextval from dual<br />09:54:07,593 DEBUG SequenceGenerator:105 - Sequence identifier generated: 22<br />09:54:07,593 DEBUG AbstractBatcher:418 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)<br />09:54:07,593 DEBUG AbstractSaveEventListener:135 - generated identifier: 22, using strategy: org.hibernate.id.SequenceGenerator<br />09:54:07,609 DEBUG JDBCTransaction:134 - commit<br />09:54:07,609 DEBUG AbstractFlushingEventListener:134 - processing flush-time cascades<br />09:54:07,609 DEBUG AbstractFlushingEventListener:177 - dirty checking collections<br />09:54:07,609 DEBUG AbstractFlushingEventListener:108 - Flushed: 1 insertions, 0 updates, 0 deletions to 1 objects<br />09:54:07,609 DEBUG AbstractFlushingEventListener:114 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections<br />09:54:07,609 DEBUG Printer:106 - listing entities:<br />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}<br />09:54:07,625 DEBUG AbstractBatcher:410 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)<br />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 (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)<br />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 (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)<br />09:54:07,625 DEBUG AbstractBatcher:66 - Executing batch size: 1<br />09:54:07,625 DEBUG Expectations:77 - success of batch update unknown: 0<br />09:54:07,625 DEBUG AbstractBatcher:418 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)<br />09:54:07,625 DEBUG JDBCTransaction:147 - committed JDBC Connection<br />09:54:07,625 DEBUG ConnectionManager:427 - aggressively releasing JDBC connection<br />09:54:07,625 DEBUG ConnectionManager:464 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]

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

离开睁眼闭眼看见的城市，逃离身边的纷纷扰扰，