问题描述
我使用的是ibatis框架,配置了数据库的事务由spring管理,配置了切面,也实现了如果异常数据库回滚，但是看log日志，并没有发现如别人提供的日志中会出现的什么setcommit=false之类的断言，不知道为什么，请各位不吝赐教，级别为debug日志如下,这个日志的最后一个插入是我故意搞了一个错误的数据插入的[INFO ] 2012-12-05 19:57:14,358-[HL] ContextLoader.java 187 org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:187) INFO localhost-startStop-1 org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started[INFO ] 2012-12-05 19:57:14,501-[HL] AbstractApplicationContext.java 456 org.springframework.context.support.AbstractApplicationContext.prepareRefresh(AbstractApplicationContext.java:456) INFO localhost-startStop-1 org.springframework.web.context.support.XmlWebApplicationContext - Refreshing Root WebApplicationContext: startup date [Wed Dec 05 19:57:14 CST 2012]; root of context hierarchy[INFO ] 2012-12-05 19:57:14,762-[HL] XmlBeanDefinitionReader.java 315 org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions(XmlBeanDefinitionReader.java:315) INFO localhost-startStop-1 org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext.xml][INFO ] 2012-12-05 19:57:17,828-[HL] PropertiesLoaderSupport.java 177 org.springframework.core.io.support.PropertiesLoaderSupport.loadProperties(PropertiesLoaderSupport.java:177) INFO localhost-startStop-1 org.springframework.beans.factory.config.PropertyPlaceholderConfigurer - Loading properties file from class path resource [jdbc.properties][INFO ] 2012-12-05 19:57:17,989-[HL] DefaultListableBeanFactory.java 555 org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:555) INFO localhost-startStop-1 org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@100c56: defining beans [dataSourceConfigurer,accountDataSource,dataSource,lobHandler,sqlMapClientFactory,txManager,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,baseServiceMethods,org.springframework.aop.support.DefaultBeanFactoryPointcutAdvisor#0,txAdvice,accountDAO,userDAO,iapDAO,bootDAO,appDAO,accountService,userService,iapService,bootService,appService]; root of factory hierarchy[INFO ] 2012-12-05 19:57:20,881-[HL] ContextLoader.java 214 org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:214) INFO localhost-startStop-1 org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 6514 ms[INFO ] 2012-12-05 19:57:23,779-[HL] FrameworkServlet.java 301 org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:301) INFO localhost-startStop-1 org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'controller': initialization started[INFO ] 2012-12-05 19:57:23,787-[HL] AbstractApplicationContext.java 456 org.springframework.context.support.AbstractApplicationContext.prepareRefresh(AbstractApplicationContext.java:456) INFO localhost-startStop-1 org.springframework.web.context.support.XmlWebApplicationContext - Refreshing WebApplicationContext for namespace 'controller-servlet': startup date [Wed Dec 05 19:57:23 CST 2012]; parent: Root WebApplicationContext[INFO ] 2012-12-05 19:57:23,793-[HL] XmlBeanDefinitionReader.java 315 org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions(XmlBeanDefinitionReader.java:315) INFO localhost-startStop-1 org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/controller-servlet.xml][INFO ] 2012-12-05 19:57:28,121-[HL] ClassPathScanningCandidateComponentProvider.java 186 org.springframework.context.annotation.ClassPathScanningCandidateComponentProvider.registerDefaultFilters(ClassPathScanningCandidateComponentProvider.java:186) INFO localhost-startStop-1 org.springframework.context.annotation.ClassPathBeanDefinitionScanner - JSR-330 'javax.inject.Named' annotation found and supported for component scanning[INFO ] 2012-12-05 19:57:28,560-[HL] AutowiredAnnotationBeanPostProcessor.java 137 org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.<init>(AutowiredAnnotationBeanPostProcessor.java:137) INFO localhost-startStop-1 org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring[INFO ] 2012-12-05 19:57:28,581-[HL] DefaultListableBeanFactory.java 555 org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:555) INFO localhost-startStop-1 org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@105b2d3: defining beans [org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping#0,org.springframework.format.support.FormattingConversionServiceFactoryBean#0,org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter#0,org.springframework.web.servlet.handler.MappedInterceptor#0,accountController,appController,bootController,IAPController,userController,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping#1,org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter#1,org.springframework.web.servlet.view.InternalResourceViewResolver#0]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@100c56[INFO ] 2012-12-05 19:57:29,088-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/account/echo.do] onto handler 'accountController'[INFO ] 2012-12-05 19:57:29,089-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/account/checkaccount.do] onto handler 'accountController'[INFO ] 2012-12-05 19:57:29,105-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:29,105-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/command/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:29,106-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/file/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:29,106-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:29,131-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/list.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:29,132-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{bootName}/info.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:29,133-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{bootName}/update.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:29,146-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/iap/info.do] onto handler 'IAPController'[INFO ] 2012-12-05 19:57:29,147-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/iap/downloadsecuritykey.do] onto handler 'IAPController'[INFO ] 2012-12-05 19:57:29,164-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/user/login.do] onto handler 'userController'[INFO ] 2012-12-05 19:57:29,165-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/user/checkout.do] onto handler 'userController'[INFO ] 2012-12-05 19:57:33,544-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/account/echo.do] onto handler 'accountController'[INFO ] 2012-12-05 19:57:33,545-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/account/checkaccount.do] onto handler 'accountController'[INFO ] 2012-12-05 19:57:33,547-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:33,547-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/command/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:33,548-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/file/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:33,551-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{deviceId}/app/version/{id}/list.do] onto handler 'appController'[INFO ] 2012-12-05 19:57:33,552-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/list.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:33,552-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{bootName}/info.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:33,555-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/boot/{bootName}/update.do] onto handler 'bootController'[INFO ] 2012-12-05 19:57:33,556-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/iap/info.do] onto handler 'IAPController'[INFO ] 2012-12-05 19:57:33,556-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/iap/downloadsecuritykey.do] onto handler 'IAPController'[INFO ] 2012-12-05 19:57:33,558-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/user/login.do] onto handler 'userController'[INFO ] 2012-12-05 19:57:33,559-[HL] AbstractUrlHandlerMapping.java 411 org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:411) INFO localhost-startStop-1 org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping - Mapped URL path [/user/checkout.do] onto handler 'userController'[INFO ] 2012-12-05 19:57:33,795-[HL] FrameworkServlet.java 320 org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:320) INFO localhost-startStop-1 org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'controller': initialization completed in 10015 ms[DEBUG] 2012-12-05 19:57:51,314-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.Connection - {conn-100000} Connection[DEBUG] 2012-12-05 19:57:51,345-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.Connection - {conn-100000} Preparing Statement: select [id], [account], [servers], [dbname], [descript], [state], [company], [attn], [email], [phone], [mobil], [makedate], [used] from T0000_00 where [company]= ? [DEBUG] 2012-12-05 19:57:51,453-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100001} Executing Statement: select [id], [account], [servers], [dbname], [descript], [state], [company], [attn], [email], [phone], [mobil], [makedate], [used] from T0000_00 where [company]= ? [DEBUG] 2012-12-05 19:57:51,453-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100001} Parameters: [xuwen][DEBUG] 2012-12-05 19:57:51,454-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100001} Types: [java.lang.String][DEBUG] 2012-12-05 19:57:51,558-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100002} ResultSet[DEBUG] 2012-12-05 19:57:51,597-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100002} Header: [id, account, servers, dbname, descript, state, company, attn, email, phone, mobil, makeDate, used][DEBUG] 2012-12-05 19:57:51,598-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100002} Result: [52, USBIAP_XuWen, 192.168.16.2, USBIAP_XuWen, , 1, XuWen, XuWen, XuWen@manley.com.cn, 123456, 234567, 2011-08-16 15:39:40.263, true][DEBUG] 2012-12-05 19:57:51,634-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.Connection - {conn-100003} Connection[DEBUG] 2012-12-05 19:57:51,635-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.Connection - {conn-100003} Preparing Statement: select count([name]) from master.dbo.sysdatabases where [name] = ? [DEBUG] 2012-12-05 19:57:51,636-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100004} Executing Statement: select count([name]) from master.dbo.sysdatabases where [name] = ? [DEBUG] 2012-12-05 19:57:51,636-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100004} Parameters: [USBIAP_XuWen][DEBUG] 2012-12-05 19:57:51,637-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.PreparedStatement - {pstm-100004} Types: [java.lang.String][DEBUG] 2012-12-05 19:57:52,317-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100005} ResultSet[DEBUG] 2012-12-05 19:57:52,322-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100005} Header: [][DEBUG] 2012-12-05 19:57:52,322-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-3 java.sql.ResultSet - {rset-100005} Result: [1][DEBUG] 2012-12-05 19:57:56,963-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100006} Connection[DEBUG] 2012-12-05 19:57:56,965-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100006} Preparing Statement: select [id], [username], [psw_0], [ip], [mac], [computer] from T9001_00 where [username]= ? [DEBUG] 2012-12-05 19:57:56,966-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100007} Executing Statement: select [id], [username], [psw_0], [ip], [mac], [computer] from T9001_00 where [username]= ? [DEBUG] 2012-12-05 19:57:56,967-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100007} Parameters: [xuwen][DEBUG] 2012-12-05 19:57:56,968-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100007} Types: [java.lang.String][DEBUG] 2012-12-05 19:57:57,039-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100008} ResultSet[DEBUG] 2012-12-05 19:57:57,041-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100008} Header: [id, username, psw_0, mac, ip, computer][DEBUG] 2012-12-05 19:57:57,041-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100008} Result: [8, XuWen, 1, , , ][DEBUG] 2012-12-05 19:57:57,844-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100009} Connection[DEBUG] 2012-12-05 19:57:57,845-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100009} Preparing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:57,846-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100010} Executing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:57,847-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100010} Parameters: [xuwen, 127.0.0.1, , WINDVALLEY_PC, 2012-12-05 19:57:57.043, 620558E76E33942DA2C83CAE1D2BE31A][DEBUG] 2012-12-05 19:57:57,848-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100010} Types: [java.lang.String, java.lang.String, java.lang.String, java.lang.String, java.sql.Timestamp, java.lang.String][DEBUG] 2012-12-05 19:57:57,905-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100009} Preparing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:57,905-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100011} Executing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:57,906-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100011} Parameters: [][DEBUG] 2012-12-05 19:57:57,906-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100011} Types: [][DEBUG] 2012-12-05 19:57:57,908-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100012} ResultSet[DEBUG] 2012-12-05 19:57:57,909-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100012} Header: [id][DEBUG] 2012-12-05 19:57:57,909-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100012} Result: [1953][DEBUG] 2012-12-05 19:57:58,523-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100013} Connection[DEBUG] 2012-12-05 19:57:58,524-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100013} Preparing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:58,525-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100014} Executing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:58,527-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100014} Parameters: [xuwen, 127.0.0.1, , WINDVALLEY_PC, 2012-12-05 19:57:57.043, 620558E76E33942DA2C83CAE1D2BE31A][DEBUG] 2012-12-05 19:57:58,530-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100014} Types: [java.lang.String, java.lang.String, java.lang.String, java.lang.String, java.sql.Timestamp, java.lang.String][DEBUG] 2012-12-05 19:57:58,532-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100013} Preparing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:58,532-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100015} Executing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:58,533-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100015} Parameters: [][DEBUG] 2012-12-05 19:57:58,534-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100015} Types: [][DEBUG] 2012-12-05 19:57:58,535-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100016} ResultSet[DEBUG] 2012-12-05 19:57:58,536-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100016} Header: [id][DEBUG] 2012-12-05 19:57:58,536-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100016} Result: [1954][DEBUG] 2012-12-05 19:57:58,538-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100017} Connection[DEBUG] 2012-12-05 19:57:58,539-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100017} Preparing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:58,550-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100018} Executing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:58,551-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100018} Parameters: [xuwen, 127.0.0.1, , WINDVALLEY_PC, 2012-12-05 19:57:57.043, 620558E76E33942DA2C83CAE1D2BE31A][DEBUG] 2012-12-05 19:57:58,551-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100018} Types: [java.lang.String, java.lang.String, java.lang.String, java.lang.String, java.sql.Timestamp, java.lang.String][DEBUG] 2012-12-05 19:57:58,554-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100017} Preparing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:58,554-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100019} Executing Statement: select @@IDENTITY as id [DEBUG] 2012-12-05 19:57:58,555-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100019} Parameters: [][DEBUG] 2012-12-05 19:57:58,555-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100019} Types: [][DEBUG] 2012-12-05 19:57:58,556-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100020} ResultSet[DEBUG] 2012-12-05 19:57:58,557-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100020} Header: [id][DEBUG] 2012-12-05 19:57:58,557-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.ResultSet - {rset-100020} Result: [1955][DEBUG] 2012-12-05 19:57:59,293-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100021} Connection[DEBUG] 2012-12-05 19:57:59,294-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.Connection - {conn-100021} Preparing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:59,294-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100022} Executing Statement: insert into t9001_02 ([name], [ip], [mac], [computer], [logintime], [sessionid]) values (?, ?, ?, ?, ?, ?) [DEBUG] 2012-12-05 19:57:59,295-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100022} Parameters: [xuwen, 127.0.0.1, , WINDVALLEY_PC, null, 620558E76E33942DA2C83CAE1D2BE31A][DEBUG] 2012-12-05 19:57:59,295-[HL] JakartaCommonsLoggingImpl.java 27 com.ibatis.common.logging.jakarta.JakartaCommonsLoggingImpl.debug(JakartaCommonsLoggingImpl.java:27) DEBUG http-bio-8080-exec-12 java.sql.PreparedStatement - {pstm-100022} Types: [java.lang.String, java.lang.String, java.lang.String, java.lang.String, null, java.lang.String][INFO ] 2012-12-05 19:57:59,595-[HL] XmlBeanDefinitionReader.java 315 org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions(XmlBeanDefinitionReader.java:315) INFO http-bio-8080-exec-12 org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [org/springframework/jdbc/support/sql-error-codes.xml][INFO ] 2012-12-05 19:57:59,689-[HL] SQLErrorCodesFactory.java 126 org.springframework.jdbc.support.SQLErrorCodesFactory.<init>(SQLErrorCodesFactory.java:126) INFO http-bio-8080-exec-12 org.springframework.jdbc.support.SQLErrorCodesFactory - SQLErrorCodes loaded: [DB2, Derby, H2, HSQL, Informix, MS-SQL, MySQL, Oracle, PostgreSQL, Sybase][INFO ] 2012-12-05 19:58:31,065-[HL] AbstractApplicationContext.java 1002 org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:1002) INFO localhost-startStop-2 org.springframework.web.context.support.XmlWebApplicationContext - Closing WebApplicationContext for namespace 'controller-servlet': startup date [Wed Dec 05 19:57:23 CST 2012]; parent: Root WebApplicationContext[INFO ] 2012-12-05 19:58:31,066-[HL] DefaultSingletonBeanRegistry.java 422 org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.destroySingletons(DefaultSingletonBeanRegistry.java:422) INFO localhost-startStop-2 org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@105b2d3: defining beans [org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping#0,org.springframework.format.support.FormattingConversionServiceFactoryBean#0,org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter#0,org.springframework.web.servlet.handler.MappedInterceptor#0,accountController,appController,bootController,IAPController,userController,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.web.servlet.mvc.annotation.DefaultAnnotationHandlerMapping#1,org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter#1,org.springframework.web.servlet.view.InternalResourceViewResolver#0]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@100c56[INFO ] 2012-12-05 19:58:31,213-[HL] AbstractApplicationContext.java 1002 org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:1002) INFO localhost-startStop-2 org.springframework.web.context.support.XmlWebApplicationContext - Closing Root WebApplicationContext: startup date [Wed Dec 05 19:57:14 CST 2012]; root of context hierarchy[INFO ] 2012-12-05 19:58:31,213-[HL] DefaultSingletonBeanRegistry.java 422 org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.destroySingletons(DefaultSingletonBeanRegistry.java:422) INFO localhost-startStop-2 org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@100c56: defining beans [dataSourceConfigurer,accountDataSource,dataSource,lobHandler,sqlMapClientFactory,txManager,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,baseServiceMethods,org.springframework.aop.support.DefaultBeanFactoryPointcutAdvisor#0,txAdvice,accountDAO,userDAO,iapDAO,bootDAO,appDAO,accountService,userService,iapService,bootService,appService]; root of factory hierarchy 问题补充：79,91行为正确的插入点，103行为错误的插入点，数据库使用的sqlserver2000
解决方案
不知道你用啥日志工具记录的，按理说是有的，比如log4j配置log4j.logger.org.springframework=debug,console 即可看到spring相关的日志