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
6.
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
12.
13.### set log levels - for more verbose logging change 'info' to 'debug' ###
14.
15.log4j.rootLogger=warn, stdout
16.
17.#log4j.logger.org.hibernate=info
18.log4j.logger.org.hibernate=debug
19.
20.### log HQL query parser activity
21.#log4j.logger.org.hibernate.hql.ast.AST=debug
22.
23.### log just the SQL
24.#log4j.logger.org.hibernate.SQL=debug
25.
26.### log JDBC bind parameters ###
27.log4j.logger.org.hibernate.type=info 28.#log4j.logger.org.hibernate.type=debug
29.
30.### log schema export/update ###
31.log4j.logger.org.hibernate.tool.hbm2ddl=debug
32. 33.### log HQL parse trees
34.#log4j.logger.org.hibernate.hql=debug 35.
36.### log cache activity ###
37.#log4j.logger.org.hibernate.cache=debug 38. 39.### log transaction activity
40.#log4j.logger.org.hibernate.transaction=debug 41. 42.### log JDBC resource acquisition
43.#log4j.logger.org.hibernate.jdbc=debug 44. 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)]
綜上所看，合適的日志輸出有利用快速定位和分析，多輸出有多輸出的好處，少也有少的好處，具體情況具體處理。
