程序博客网 > 伦诺克斯 刘易斯 知乎

（OpenSessionInView模式）spring对session和事务的管理以及OpenSessionInViewFilter是如何工作的

来源：互联网 发布：伦诺克斯 刘易斯 知乎 编辑：程序博客网 时间：2024/04/29 20:59
 为了弄清楚spring对session和事务的管理以及OpenSessionInViewFilter是如何工作的，可监控以下类的日志：
　　org.springframework.orm.hibernate3.support.OpenSessionInViewFilter，
　　org.springframework.orm.hibernate3.HibernateTransactionManager，
　　org.hibernate.transaction.JDBCTransaction，
　　org.hibernate.impl.SessionImpl
具体可参考下面的配置：

view plaincopy to clipboardprint?
  1.    <appender name="sessionTransactionAppender" class="org.apache.log4j.DailyRollingFileAppender">  
  2.     <param name="File" value="session_transaction.log" />  
  3.     <param name="Append" value="false" />  
  4.     <param name="Threshold" value="DEBUG" />  
  5.     <param name="DatePattern" value="'.'yyyy-MM-dd" />  
  6.     <layout class="org.apache.log4j.PatternLayout">  
  7.         <param name="ConversionPattern" value="[%t] %d %-5p [%c] %m%n" />  
  8.     </layout>  
  9. </appender>  
  10. <category name="org.hibernate.impl.SessionImpl">  
  11.       <priority value="ALL" />  
  12.      <appender-ref ref="sessionTransactionAppender"/>  
  13. </category>  
  14.    <category name="org.springframework.orm.hibernate3.HibernateTransactionManager">  
  15.       <priority value="ALL" />  
  16.      <appender-ref ref="sessionTransactionAppender"/>  
  17. </category>  
  18. <category name="org.springframework.orm.hibernate3.support.OpenSessionInViewFilter">  
  19.       <priority value="debug" />  
  20.      <appender-ref ref="sessionTransactionAppender"/>  
  21. </category>  

下面是在一个不使用OpenSessionInViewFilter的项目中，一次请求(所有条目都是在一个线程里的)调用两次getAllForumGroups方法（此方法上声明了事务控制）打出的日志：
日志中可以清楚地看到：每次事务开启时会打开一个新的session，事务结束时会关闭该session,session不再和当前线程绑定。

view plaincopy to clipboardprint?
  1. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,304 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 5330116498333696  
  2. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,698 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Creating new transaction with name [oobbs.application.service.forum.ForumGroupService.getAllForumGroups]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT  
  3. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,700 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 5330116500275200  
  4. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,701 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Opened new Session [org.hibernate.impl.SessionImpl@a0e220] for Hibernate transaction  
  5. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,712 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@a0e220]  
  6. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,737 DEBUG [org.hibernate.transaction.JDBCTransaction] begin  
  7. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,739 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: true  
  8. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,740 DEBUG [org.hibernate.transaction.JDBCTransaction] disabling autocommit  
  9. [7143355@qtp-21544349-0] 2011-03-28 15:39:33,746 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Exposing Hibernate transaction as JDBC transaction [org.apache.commons.dbcp.PoolableConnection@1ac9683]  
  10. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,205 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [oobbs.domainmodel.user.User#-1]  
  11. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,419 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Initiating transaction commit  
  12. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,423 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Committing Hibernate transaction on Session [org.hibernate.impl.SessionImpl@a0e220]  
  13. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,426 DEBUG [org.hibernate.transaction.JDBCTransaction] commit  
  14. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,447 DEBUG [org.hibernate.transaction.JDBCTransaction] re-enabling autocommit  
  15. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,452 DEBUG [org.hibernate.transaction.JDBCTransaction] committed JDBC Connection  
  16. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,458 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Closing Hibernate Session [org.hibernate.impl.SessionImpl@a0e220] after transaction  
  17. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,464 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Creating new transaction with name [oobbs.application.service.forum.ForumGroupService.getAllForumGroups]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT  
  18. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,465 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 5330116503408640  
  19. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,466 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Opened new Session [org.hibernate.impl.SessionImpl@f7d5a6] for Hibernate transaction  
  20. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,469 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@f7d5a6]  
  21. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,470 DEBUG [org.hibernate.transaction.JDBCTransaction] begin  
  22. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,474 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: true  
  23. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,476 DEBUG [org.hibernate.transaction.JDBCTransaction] disabling autocommit  
  24. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,478 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Exposing Hibernate transaction as JDBC transaction [org.apache.commons.dbcp.PoolableConnection@1ac9683]  
  25. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,652 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [oobbs.domainmodel.user.User#-1]  
  26. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,795 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Initiating transaction commit  
  27. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,796 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Committing Hibernate transaction on Session [org.hibernate.impl.SessionImpl@f7d5a6]  
  28. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,798 DEBUG [org.hibernate.transaction.JDBCTransaction] commit  
  29. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,804 DEBUG [org.hibernate.transaction.JDBCTransaction] re-enabling autocommit  
  30. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,805 DEBUG [org.hibernate.transaction.JDBCTransaction] committed JDBC Connection  
  31. [7143355@qtp-21544349-0] 2011-03-28 15:39:34,810 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Closing Hibernate Session [org.hibernate.impl.SessionImpl@f7d5a6] after transaction  

　　下面是在一个使用了OpenSessionInViewFilter的项目中，一次请求(所有条目都是在一个线程里的)调用两次getRole方法（此方法上声明了事务控制）打出的日志：
日志中可以清楚地看到：请求刚发起时就会创建一个session实例，并绑定到当前线程上，至请求结束时，关闭该线程。在请求期间所有事务都使用这一个session。
　　另一方面我们还可以看到：数据库连接connection保持打开的时间正是事务从开始到提交的时间，为了测试，getRole方法中故意设定了3秒的延迟，从日志上看出数据库连接也基本上维持了3秒。这是很多系统出现无法获取数据库连接问题的原因。在一个使用了连接池的系统里，连接数有一个最大上限，如果某个请求的处理耗时过长，则当前数据库连接在短时间内就得不到释放。当前并发访问量急速上升时，连接池的连接用会迅速用光，导致后续的请求因无法得到数据库连接而失败。
　　最后，至于为什么只需要保证session一直打开就能避免lazy loading时不报错，原因就在于，观察下面的log可以发现，每当在事务期间时autocommit就被禁用，事务一执行完毕就又恢复至可用。这样，在jsp页面上读取lazy对象时，并不是以事务方式进行的，而是以autocommit的方式提交select语句的。因此只需要保持session打开即可，无需事务的支持。

view plaincopy to clipboardprint?
  1. [http-8080-4] 2011-03-28 15:25:59,367 DEBUG [org.springframework.orm.hibernate3.support.OpenSessionInViewFilter] Using SessionFactory 'sessionFactory' for OpenSessionInViewFilter  
  2. [http-8080-4] 2011-03-28 15:25:59,367 DEBUG [org.springframework.orm.hibernate3.support.OpenSessionInViewFilter] Opening single Hibernate Session in OpenSessionInViewFilter  
  3. [http-8080-4] 2011-03-28 15:25:59,367 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 5330113164767232  
  4. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Found thread-bound Session [org.hibernate.impl.SessionImpl@1d8f985] for Hibernate transaction  
  5. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Creating new transaction with name [sf.demo.RoleService.getRole]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT;  
  6. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@1d8f985]  
  7. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.hibernate.transaction.JDBCTransaction] begin  
  8. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: true  
  9. [http-8080-4] 2011-03-28 15:25:59,439 DEBUG [org.hibernate.transaction.JDBCTransaction] disabling autocommit  
  10. [http-8080-4] 2011-03-28 15:25:59,440 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Exposing Hibernate transaction as JDBC transaction [com.mchange.v2.c3p0.impl.NewProxyConnection@16f7142]  
  11. [http-8080-4] 2011-03-28 15:26:02,440 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Initiating transaction commit  
  12. [http-8080-4] 2011-03-28 15:26:02,440 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Committing Hibernate transaction on Session [org.hibernate.impl.SessionImpl@1d8f985]  
  13. [http-8080-4] 2011-03-28 15:26:02,440 DEBUG [org.hibernate.transaction.JDBCTransaction] commit  
  14. [http-8080-4] 2011-03-28 15:26:02,442 DEBUG [org.hibernate.transaction.JDBCTransaction] re-enabling autocommit  
  15. [http-8080-4] 2011-03-28 15:26:02,443 DEBUG [org.hibernate.transaction.JDBCTransaction] committed JDBC Connection  
  16. [http-8080-4] 2011-03-28 15:26:02,443 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Not closing pre-bound Hibernate Session [org.hibernate.impl.SessionImpl@1d8f985] after transaction  
  17. [http-8080-4] 2011-03-28 15:26:02,443 DEBUG [org.hibernate.impl.SessionImpl] disconnecting session  
  18. [http-8080-4] 2011-03-28 15:26:02,445 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Found thread-bound Session [org.hibernate.impl.SessionImpl@1d8f985] for Hibernate transaction  
  19. [http-8080-4] 2011-03-28 15:26:02,445 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Creating new transaction with name [sf.demo.RoleService.getRole]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT;  
  20. [http-8080-4] 2011-03-28 15:26:02,445 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Preparing JDBC Connection of Hibernate Session [org.hibernate.impl.SessionImpl@1d8f985]  
  21. [http-8080-4] 2011-03-28 15:26:02,445 DEBUG [org.hibernate.transaction.JDBCTransaction] begin  
  22. [http-8080-4] 2011-03-28 15:26:02,445 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: true  
  23. [http-8080-4] 2011-03-28 15:26:02,446 DEBUG [org.hibernate.transaction.JDBCTransaction] disabling autocommit  
  24. [http-8080-4] 2011-03-28 15:26:02,446 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Exposing Hibernate transaction as JDBC transaction [com.mchange.v2.c3p0.impl.NewProxyConnection@1fb8de1]  
  25. [http-8080-4] 2011-03-28 15:26:05,447 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Initiating transaction commit  
  26. [http-8080-4] 2011-03-28 15:26:05,447 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Committing Hibernate transaction on Session [org.hibernate.impl.SessionImpl@1d8f985]  
  27. [http-8080-4] 2011-03-28 15:26:05,447 DEBUG [org.hibernate.transaction.JDBCTransaction] commit  
  28. [http-8080-4] 2011-03-28 15:26:05,449 DEBUG [org.hibernate.transaction.JDBCTransaction] re-enabling autocommit  
  29. [http-8080-4] 2011-03-28 15:26:05,450 DEBUG [org.hibernate.transaction.JDBCTransaction] committed JDBC Connection  
  30. [http-8080-4] 2011-03-28 15:26:05,450 DEBUG [org.springframework.orm.hibernate3.HibernateTransactionManager] Not closing pre-bound Hibernate Session [org.hibernate.impl.SessionImpl@1d8f985] after transaction  
  31. [http-8080-4] 2011-03-28 15:26:05,450 DEBUG [org.hibernate.impl.SessionImpl] disconnecting session  
  32. [http-8080-4] 2011-03-28 15:26:08,680 DEBUG [org.springframework.orm.hibernate3.support.OpenSessionInViewFilter] Closing single Hibernate Session in OpenSessionInViewFilter 

伦诺克斯 刘易斯 知乎 伦诺克斯 刘易斯 知乎
原创粉丝点击
热门IT博客
mysql 嵌套select语句mysql 嵌套select语句
运动营养学专业知书籍运动营养学专业知书籍
java讲师要求很高吧
程序员励志桌面壁纸
淘宝乔丹运动鞋男鞋
青云志网络播放量
电脑工作提醒软件
kruskal算法 java
中山大学新华学院知乎
交换机允许mac
sybase数据库学习
大数据及云计算
2015省会城市人口数据
笑傲江湖李亚鹏版 知乎
怎么在手机淘宝看直播
淘宝店铺图片上传视频
2016年网络第一红歌
老白 知乎
神话同屏反控 软件
网络大电影 警察
热门问题 老师的惩罚 人脸识别 我在镇武司摸鱼那些年 重生之率土为王 我在大康的咸鱼生活 盘龙之生命进化 天生仙种 凡人之先天五行 春回大明朝 姑娘不必设防，我是瞎子 w7系统密码忘了怎么办 w7开机密码忘了怎么办 把c盘格式化了怎么办 我的电脑反应慢怎么办 ie浏览器误删了怎么办 ie浏览器被删了怎么办 相机图标被删了怎么办 u盘删不了文件怎么办 我的电脑 没有了怎么办 电脑上图标没了怎么办 w 7桌面消失了怎么办 电脑修改ip错了怎么办 微信验证过频繁怎么办 r9玩王者荣耀卡怎么办 安卓王者荣耀卡怎么办 上帝之城压抑了怎么办 起水痘喝鸡汤了怎么办 中了呼死你软件怎么办 房间门被反锁了怎么办 厕所门被反锁了怎么办 家里门被反锁了怎么办 鱼把石头吃了怎么办 fgo重复的从者怎么办 科二考试挂了怎么办 怀孕吃叶酸就吐怎么办 备孕期烫了头发怎么办 小产后受风了怎么办 脑袋受风了头痛怎么办 强制车险单丢了怎么办 强制险保单丢了怎么办 车上强制险丢了怎么办 强险手续贴丢了怎么办 包的链条生锈了怎么办 养的吊兰烂根怎么办 5岁儿童小鸡红肿怎么办 5小孩小鸡痒怎么办急 链条锁钥匙丢了怎么办 自行车车锁坏了怎么办 肛门旁边长个包怎么办 小偷进家里了要怎么办 门锁了没带钥匙怎么办

程序博客网，程序员的互联网技术博客家园。csdn论坛精品 msdn技术资料都在这里