一次寻找IBatisNet事务bug的过程

最新推荐文章于 2022-07-01 11:18:14 发布
最新推荐文章于 2022-07-01 11:18:14 发布
阅读量487 收藏 1
点赞数
原文链接：http://www.cnblogs.com/lhking/p/9645791.html
版权

本文的上下文环境

操作系统：Win7 x64 Professional

开发工具：Visual Studio 2017   语言：C#

数据库ORM：IBatisNet 1.6.2

一、前言

这个项目的前端有Web端，公众号，微信小程序，后端是用WCF写的，部署成windows service。后端使用了IBatisNet这样的轻量级ORM框架，sql是写在xml里面的，每个模块都有一个xml文件。sql的返回值对应的实体类配置在同一个xml文件里，像这样。

二、问题出现

 这个项目一直运行的很正常，直到有一天发布了某一个功能之后，问题就出现了，异常日志经常看到这样的文字“SqlMap could not invoke BeginTransaction(). A Transaction is already started. Call CommitTransaction() or RollbackTransaction first”，一看代码，这个异常是从SqlMapper.BeginTransaction方法里面抛出的，字面意思就是启动事务，不能正常启动，需要首先调用提交或回滚事务。按着这个逻辑来说，应该是某一次事务忘记提交或回滚了。但是这个错误在本地开发环境复现不了，线上环境时不时的出现。如果本地能够reproduce，应该会比较好解决一些。

三、查看bug起因

我们项目中事务用了PostSharp里面的AOP编程，只要在需要事务的方法上面加个attribute就能实现事务支持。代码如下：

[Serializable]
    [AttributeUsage(AttributeTargets.Method, AllowMultiple = true)]
    public class AopTransactionMethodAttribute : MethodInterceptionAspect { //调用本函数时截取异常 public override void OnInvoke(MethodInterceptionArgs args) { Mapper.Instance().BeginTransaction(); try { //执行方法 base.OnInvoke(args); } catch { Mapper.Instance().RollBackTransaction(); throw; } TData result = args.ReturnValue as TData; if (result == null || result.Tag <= 0) { //回滚事务  Mapper.Instance().RollBackTransaction(); } else { //提交事务  Mapper.Instance().CommitTransaction(); } } }
AopTransactionMethodAttribute

这样就是很方便写代码，不用在每个操作数据库多表的地方都显式地调用事务。

一开始怀疑这个地方可能有问题，在try catch的基础上又加了一个try catch finally 用来彻底提交或回归事务。发布到线上之后，错误仍然时不时的出现。

由于只能在线上环境可以重现，考虑只能使用日志来记录执行过程了，我把事务的BeginTransaction，CommitTransaction，RollbackTransaction的执行全部记上日志，考虑业务会并发执行，日志肯定要加锁，并且把每次事务的CallStack和ThreadId给输出来，代码如下：

 public static readonly object LockTransaction = new object();
public ISqlMapSession BeginTransaction()
        {
            lock (LockTransaction)
            {
                string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                sw.Write(" Begin  Transaction Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                if (_sessionStore.LocalSession != null)
                {
                    sw.WriteLine();
                    StackTrace trace = new StackTrace(true);
                    for (int i = 0; i < trace.FrameCount; i++)
                    {
                        StackFrame frame = trace.GetFrame(i);
                        MethodBase method = frame.GetMethod();
                        if (method.DeclaringType != null)
                        {
                            sw.WriteLine(method.DeclaringType.FullName + " " + method.Name);
                        }
                        else
                        {
                            sw.WriteLine(method.Name);
                        }
                    }
                    sw.WriteLine(" SqlMap could not invoke BeginTransaction(). A Transaction is already started. Call CommitTransaction() or RollbackTransaction first");
                    //throw new DataMapperException("SqlMap could not invoke BeginTransaction(). A Transaction is already started. Call CommitTransaction() or RollbackTransaction first.");
                }
                ISqlMapSession session = CreateSqlMapSession();
                _sessionStore.Store(session);
                session.BeginTransaction();
                sw.WriteLine();
                sw.Close();
                return session;
            }
        }
BeginTransaction 
public void CommitTransaction()
        {
            lock (LockTransaction)
            {
                string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                sw.Write(" Commit Transaction Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                if (_sessionStore.LocalSession == null)
                {
                    throw new DataMapperException("SqlMap could not invoke CommitTransaction(). No Transaction was started. Call BeginTransaction() first.");
                }
                try
                {
                    ISqlMapSession session = _sessionStore.LocalSession;
                    session.CommitTransaction();
                }
                finally
                {
                    _sessionStore.Dispose();
                }
                if (_sessionStore.LocalSession != null)
                {
                    sw.Write(" Commit Transaction Not Successfully " + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                }
                else
                {
                    sw.Write(" Commit Transaction " + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                }
                sw.WriteLine();
                sw.Close();
            }
        }
CommitTransaction 
  public void RollBackTransaction()
        {
            lock (LockTransaction)
            {
                string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                sw.Write(" Roll   Transaction Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                if (_sessionStore.LocalSession == null)
                {
                    throw new DataMapperException("SqlMap could not invoke RollBackTransaction(). No Transaction was started. Call BeginTransaction() first.");
                }
                try
                {
                    ISqlMapSession session = _sessionStore.LocalSession;
                    session.RollBackTransaction();
                }
                finally
                {
                    _sessionStore.Dispose();
                }
                if (_sessionStore.LocalSession != null)
                {
                    sw.Write(" Roll Transaction Not Successfully " + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                }
                else
                {
                    sw.Write(" Roll Transaction " + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                }
                sw.WriteLine();
                sw.Close();
            }
        }
RollbackTransaction

 结果出乎意料的是，事务一个BeginTransaction后面要么跟一个Rollback Transaction，要么跟一个Commit Transaction，这完全是正确的啊，心里顿时真不是滋味，什么破代码竟然没问题。

不过，问题总归是需要解决的，不然永远都是止步不前。

回到最开始错误的地方，因为在BeginTransaction的时候，_sessionStore.LocalSession != null，抛出了那个错误，我就需要调查这个LocalSession。

因为LocalSession对应的对象SqlMapSession里面没有标识对象唯一性的建，我就在SqlMapSession里面加了一个Id标识，用来确定这个LocalSession到底有没有被销毁。

public SqlMapSession(ISqlMapper sqlMapper)
        {
            _dataSource = sqlMapper.DataSource;
            _sqlMapper = sqlMapper;
            lock (IBatisNet.DataMapper.SqlMapper.LockTransaction)
            {
                id = long.Parse(DateTime.Now.ToString("yyyyMMddHHmmssfff"));
            }
        }

IBatisNet在保存session的时候，在Winform程序里面使用CallContextSessionStore这个类来获取、保存、销毁session。CallContextSessionStore类里面用CallContext.GetData(name)获取，CallContext.SetData(name, session)保存，CallContext.SetData(name, null)销毁。CallContext这个类支持在同一个线程内，访问的是同一个对象，也就是说，当前线程对对象进行储存到线程本地储存区，对象随着线程的销毁而销毁。

同样地，我把每个方法的调用输出在另外一个日志文件里面，输出在同一个文件会报文件拒绝访问，可能是由于在Dispose的时候，我去调用GetSession所致。代码如下：

public override ISqlMapSession LocalSession
        {
            get
            {
                lock (IBatisNet.DataMapper.SqlMapper.LockTransaction)
                {
                    var session = CallContext.GetData(sessionName) as SqlMapSession;
                    if (session != null)
                    {
                        string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}_1.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                        System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                        sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                        sw.Write(" LocalSession Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                        sw.Write(" session Id:" + session.Id);
                        sw.WriteLine();
                        sw.Close();
                    }
                    return session;
                }
            }
        }
Get Session 
public override void Store(ISqlMapSession session)
        {
            lock (IBatisNet.DataMapper.SqlMapper.LockTransaction)
            {
                string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}_1.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                sw.Write(" Store        Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                sw.Write(" session Id:" + session.Id);
                sw.WriteLine();

                StackTrace trace = new StackTrace(true);
                for (int i = 0; i < trace.FrameCount; i++)
                {
                    StackFrame frame = trace.GetFrame(i);
                    MethodBase method = frame.GetMethod();
                    if (method.DeclaringType != null)
                    {
                        sw.WriteLine(method.DeclaringType.FullName + " " + method.Name);
                    }
                    else
                    {
                        sw.WriteLine(method.Name);
                    }
                }

                sw.Close();
                CallContext.SetData(sessionName, session);
            }
        }
Store Session 
public override void Dispose()
        {
            lock (IBatisNet.DataMapper.SqlMapper.LockTransaction)
            {
                var session = CallContext.GetData(sessionName) as SqlMapSession;
                string file = string.Format(AppDomain.CurrentDomain.BaseDirectory + "Log\\{0}\\{1}_1.log", DateTime.Now.ToString("yyyy-MM"), DateTime.Now.ToString("yyyy-MM-dd"));
                System.IO.StreamWriter sw = new System.IO.StreamWriter(file, true);
                sw.Write(DateTime.Now.ToString("yyyy-MM-dd HH:mm:ss"));
                sw.Write(" Dispose      Thread Id:" + System.Threading.Thread.CurrentThread.ManagedThreadId.ToString().PadLeft(2, '0'));
                sw.Write(" session Id:" + session.Id);
                sw.WriteLine();
                sw.Close();

                CallContext.SetData(sessionName, null);
            }
        }
Remove Session

找到上一个日志文件出错的时间，再和这个日志文件对照，顿时发现有一个session id只有Store，没有Dispose。并且通过CallStack知道是哪个方法在调用和使用这个session，简直欣喜若狂啊：）

Finally，问题找到了，是一个业务方法不经意地调用了一个获取Sql的方法，来获取当前执行的sql，但是这个方法的写法有问题，session只有open，没有close。

四、总结

一沙一世界，一叶一菩提

用心去看，去体悟，哪怕再小的事物都可以成为一个独立的空间，哪怕是一粒沙子都可以是一个世界，容纳万物。问题在那里啊？就在那一叶上。

转载于:https://www.cnblogs.com/lhking/p/9645791.html

weixin_30551947
关注
asp.net IBatisNet事务，支持事务嵌套
冻死的企鹅
07-19 618
IBatisNet事务嵌套，主要用户多层级事务操作
IbatisNet调用存储过程
05-06
在.NET开发环境中，IbatisNet是一个流行的持久层框架，它为开发者提供了灵活的数据访问机制，包括直接执行SQL语句和调用存储过程。本篇文章将深入探讨如何在IbatisNet中配置并使用存储过程。 首先，理解IbatisNet的...
IBatisNet的问题总结
netjxz的专栏
07-02 4182
1.一个未解决的 iBatisNet 并发访问问题iBatisNet 是通过 SqlMap 实例执行 SQL Statement 的, 一个 SqlMap 实例里封装了数据库连接和事务, 让人难受的是, Cache 竟然也封装在 SqlMap 里。大家知道, 我们可以通过以下方式获得 SqlMap 实例:a. SqlMap map = Mapper.Instance(); // 单
IBatisNet 事务
T2的专栏
01-19 1370
Mapper.Instance().BeginTransaction();//启动事务 Mapper.Instance().CommitTransaction();//提交事务   Mapper.Instance().RollBackTransaction();//回滚事务
ibatis 批量更新与事务
husw_2008的专栏
03-13 6079
public int addMessages(List list) {     int result = 0;     try {         this.getSqlMapTransactionManager().startTransaction();         this.startBatch();         for(int i=0;i              this.inse
项目开发-iBatis事务源码之开启新事务
本博客纯属个人网络笔记，如对阁下有用，甚喜；否则，关掉即可，不喜勿喷。专心写代码，不做键盘侠！
04-14 1259
iBatis事务源码流程整理如下。    iBatis的事务使用
关于 SQLMAP报错如何解决
zengcaomeiii的博客
07-01 1839
11
事务操作
zust6314的专栏
04-02 453
1、java只要把事务操作设置为不自动提交，通过手动提交就能实现事务的处理，代码如下： package com.oracle; import java.sql.Connection; import java.sql.DriverManager; import java.sql.ResultSet; import java.sql.Statement; /** * java事务控制 * @a
IBatisNet-执行存储过程
10-12
通过上述分析可以看出，IBatisNet提供了一种灵活的方式来执行存储过程。从定义结果和参数映射到实际编写C#代码调用存储过程，每一步都需要精心设计。对于复杂的应用程序来说，这种灵活性是非常宝贵的资源。理解...
IBatisNet-执行存储过程-mysql 请点击分页按钮
05-11
在IT行业中，数据库操作是必不可少的一部分，而存储过程作为数据库中的高级程序设计功能，能够封装复杂的业务逻辑，提高数据处理的效率。本文将详细介绍如何在.NET环境中，利用IBatisNet框架来执行MySQL数据库中的...
ibatisnet下载
10-15
IbatisNet是一个基于.NET平台的数据访问层（Data Access Layer，DAL）框架，它是一个轻量级的ORM（Object-Relational Mapping，对象关系映射）工具。ORM的主要目的是简化数据库操作，将数据库中的数据与应用程序中的...
关于ibatis批量操作的代码
gaoxing_china
05-18 283
Mysql数据库，批量入库操作 [b]使用ibatis批处理[/b] 代码如下： public int addMessages(List list) { int result = 0; try { //this.getSqlMapTransactionManager().startTransaction(); this.startBat...
SqlMap异常的处理
weixin_30668887的博客
05-10 718
摘自：http://jun1986.iteye.com/blog/1150606 ibatIS需要注意的几个地方，否则很容易出错，避免低级错误的方法： 1.TPhonesale.xml中，必须要定义namespace 2.TPhonesale.xml中parameterClass="java.lang.String" resultClass="com.hanpeng.base...
TortoiseSVN 提交时报错could not begin a transaction
boise的专栏
10-20 8257
TortoiseSVN 提交时报错   could not begin a transaction 此时，在iF.SVNAdmin的管理页面上，“设置”---"后端"的 “Subversion 授权相关” 进行 Test 操作时进行报错： The file exists but is not writable   经过搜索之后的解决办法： SELinux problem again It...
Failure executing javac, but could not parse the error
大数据训练营
08-17 1万+
解决方案： 通过 eclipse IDE 工具的 project -->clean 运行后，可以执行mvn install命令 通过 IntelliJ IDEA 工具的 Bulid-->Make Project ,Rebulid Projdec 运行后，可以执行mvn package命令
解决Transaction is already completed - do not call commit or rollback more than once per transaction问题
热门推荐
凌飞安
07-15 1万+
解决Transaction is already completed - do not call commit or rollback more than once per transaction问题问题描述问题原因解决方案 问题描述 系统自定义异常处理，在rollback时出现异常，日记如下所示 17:29:47 ERROR c.z.p.c.t.s.i.TmStatDetailServiceIm...
iBatisNet操作GridView与DetailsView的入门示例源码
iBatisNet是一个数据持久层框架，它基于经典的iBatis框架，为.NET平台提供了一个简单而强大的解决方案。iBatisNet允许开发者通过简单的XML配置文件或者.NET的特性来管理SQL语句，将对象与SQL语句映射起来，从而实现...
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值