EntityFramework监控sql和EntityFramework中的事务

???紵

　　继续上篇:EntityFramework和EntityFramework.Extended使用说明——性能,语法和产生的sql

1.监控sql
　　上篇中的sql监控采用的是 Microsoft SQL Server Management Studio中工具->profiler去监控的.
当然,Express版本是没有此功能的.
如果您使用不了profiler或者觉得不方便,那么,给出监控sql的第二方案.
　　找到的资料是英文的,还好,容易理解.
原文链接:https://blog.oneunicorn.com/2013/05/08/ef6-sql-logging-part-1-simple-logging/

a.控制台打印



var db = new PhoneBookEntities();//以后的db都是指它
db.Database.Log = Console.Write;//打印sql语句
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hello' where GroupId=220");//此语句不用db.SaveChanges();一样生效
　　监控结果:



已于 2016/6/21 星期二 10:30:23 +08:00
打开了连接已于 2016/6/21 星期二 10:30:23 +08:00
启动了事务update GroupInfo set GroupName='hi' where GroupId=220
-- 正在 2016/6/21 星期二 10:30:23 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 10:30:23 +08:00
提交了事务已于 2016/6/21 星期二 10:30:23 +08:00
关闭了连接
b.记录sql到文件
　　如果控制台打印不能满足您的需求,也可以记录到文件里.
方法:



var db = new PhoneBookEntities();
db.Database.Log = s => LogHelper.Write(s);//调用LogHelper类的方法
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hello' where GroupId=220");//此语句不用db.SaveChanges();一样生效
　　LogHelper.cs



    public class LogHelper
{
public static void Write(string str)
{
var path = Path.Combine(new DirectoryInfo(AppDomain.CurrentDomain.BaseDirectory).Parent.Parent.FullName, "logs.txt");
string errmsg = string.Format("{0}", str);
System.IO.File.AppendAllText(path, errmsg);
}
}
　　就是这么简单!

2.EF中的事务
　　上面例子看到产生的sql监控记录,
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hello' where GroupId=220");
此语句执行启动了事务.
　　测试研究一下怎么回事.
以下两句:
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hi' where GroupId=209");
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hello' where GroupId=220");
经监控,分别启动并提交了两次事务.(如果sql命令为insert,delete也是同样道理)
　　怎么把这两个sql语句放到一个事务里呢?别急,最后再说明.
　　先看看最熟悉的db.SaveChanges()语句:以下语句本身没有实际意义,为了测试而写.



var gi = db.GroupInfo.FirstOrDefault(c => c.GroupName.Contains("g1!"));
var ci = db.ContactInfo.FirstOrDefault(c => c.ID == 12);
ci.ContactName += "!";
gi.GroupName += "!";
db.SaveChanges();
ci.ContactName += "!";
gi.GroupName += "!";
db.SaveChanges();
　　监控产生的sql:





已于 2016/6/21 星期二 11:06:52 +08:00
打开了连接SELECT TOP (1)
[Extent1].[GroupId] AS [GroupId],
[Extent1].[GroupName] AS [GroupName]
FROM [dbo].[GroupInfo] AS [Extent1]
WHERE [Extent1].[GroupName] LIKE N'%g1!%'
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 1 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:06:52 +08:00
关闭了连接已于 2016/6/21 星期二 11:06:52 +08:00
打开了连接SELECT TOP (1)
[Extent1].[ID] AS [ID],
[Extent1].[ContactId] AS [ContactId],
[Extent1].[IsDelete] AS [IsDelete],
[Extent1].[Account] AS [Account],
[Extent1].[ContactName] AS [ContactName],
[Extent1].[CommonMobile] AS [CommonMobile],
[Extent1].[HeadPortrait] AS [HeadPortrait],
[Extent1].[AttFile] AS [AttFile],
[Extent1].[GroupId] AS [GroupId]
FROM [dbo].[ContactInfo] AS [Extent1]
WHERE 12 = [Extent1].[ID]
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 1 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:06:52 +08:00
关闭了连接已于 2016/6/21 星期二 11:06:52 +08:00
打开了连接已于 2016/6/21 星期二 11:06:52 +08:00
启动了事务UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!!!!!!!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!' (Type = String, Size = 300)
-- @1: '216' (Type = Int32)
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 11:06:52 +08:00
提交了事务已于 2016/6/21 星期二 11:06:52 +08:00
关闭了连接已于 2016/6/21 星期二 11:06:52 +08:00
打开了连接已于 2016/6/21 星期二 11:06:52 +08:00
启动了事务UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!!!!!!!!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!' (Type = String, Size = 300)
-- @1: '216' (Type = Int32)
-- 正在 2016/6/21 星期二 11:06:52 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 11:06:52 +08:00
提交了事务已于 2016/6/21 星期二 11:06:52 +08:00
关闭了连接
监控记录　　每次db.SaveChanges()会默认开启事务,执行sql语句,提交事务.多看看,好好理解一下.
　　如何把两个db.SaveChanges()放到一个事务里呢?
　　写法:



    var gi = db.GroupInfo.FirstOrDefault(c => c.GroupName.Contains("g1!"));
var ci = db.ContactInfo.FirstOrDefault(c => c.ID == 12);
using (var tx = db.Database.BeginTransaction())
{
try
{
ci.ContactName += "!";
gi.GroupName += "!";
//db.SaveChanges();//这个语句影响到事务中sql的数量,有此语句事务里4条sql,没有就是2条.对于此案例,不影响结果.
ci.ContactName += "!";
gi.GroupName += "!";
db.SaveChanges();//必须要有
tx.Commit();//此语句不要漏了,否则监控结果会是释放了事务,而不是提交了事务!
    }
catch (Exception)
{
tx.Rollback();
}
}
　　监控产生的sql:
　　[没有第一个db.SaveChanges()]





已于 2016/6/21 星期二 11:20:49 +08:00
打开了连接SELECT TOP (1)
[Extent1].[GroupId] AS [GroupId],
[Extent1].[GroupName] AS [GroupName]
FROM [dbo].[GroupInfo] AS [Extent1]
WHERE [Extent1].[GroupName] LIKE N'%g1!%'
-- 正在 2016/6/21 星期二 11:20:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:20:49 +08:00
关闭了连接已于 2016/6/21 星期二 11:20:49 +08:00
打开了连接SELECT TOP (1)
[Extent1].[ID] AS [ID],
[Extent1].[ContactId] AS [ContactId],
[Extent1].[IsDelete] AS [IsDelete],
[Extent1].[Account] AS [Account],
[Extent1].[ContactName] AS [ContactName],
[Extent1].[CommonMobile] AS [CommonMobile],
[Extent1].[HeadPortrait] AS [HeadPortrait],
[Extent1].[AttFile] AS [AttFile],
[Extent1].[GroupId] AS [GroupId]
FROM [dbo].[ContactInfo] AS [Extent1]
WHERE 12 = [Extent1].[ID]
-- 正在 2016/6/21 星期二 11:20:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:20:49 +08:00
关闭了连接已于 2016/6/21 星期二 11:20:49 +08:00
打开了连接已于 2016/6/21 星期二 11:20:49 +08:00
启动了事务UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:20:49 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!!' (Type = String, Size = 300)
-- @1: '222' (Type = Int32)
-- 正在 2016/6/21 星期二 11:20:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 11:20:49 +08:00
提交了事务已于 2016/6/21 星期二 11:20:49 +08:00
关闭了连接
监控记录　　[有第一个db.SaveChanges()]





已于 2016/6/21 星期二 11:21:49 +08:00
打开了连接SELECT TOP (1)
[Extent1].[GroupId] AS [GroupId],
[Extent1].[GroupName] AS [GroupName]
FROM [dbo].[GroupInfo] AS [Extent1]
WHERE [Extent1].[GroupName] LIKE N'%g1!%'
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 1 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:21:49 +08:00
关闭了连接已于 2016/6/21 星期二 11:21:49 +08:00
打开了连接SELECT TOP (1)
[Extent1].[ID] AS [ID],
[Extent1].[ContactId] AS [ContactId],
[Extent1].[IsDelete] AS [IsDelete],
[Extent1].[Account] AS [Account],
[Extent1].[ContactName] AS [ContactName],
[Extent1].[CommonMobile] AS [CommonMobile],
[Extent1].[HeadPortrait] AS [HeadPortrait],
[Extent1].[AttFile] AS [AttFile],
[Extent1].[GroupId] AS [GroupId]
FROM [dbo].[ContactInfo] AS [Extent1]
WHERE 12 = [Extent1].[ID]
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:21:49 +08:00
关闭了连接已于 2016/6/21 星期二 11:21:49 +08:00
打开了连接已于 2016/6/21 星期二 11:21:49 +08:00
启动了事务UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!' (Type = String, Size = 300)
-- @1: '222' (Type = Int32)
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!!' (Type = String, Size = 300)
-- @1: '222' (Type = Int32)
-- 正在 2016/6/21 星期二 11:21:49 +08:00
执行-- 已在 0 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 11:21:49 +08:00
提交了事务已于 2016/6/21 星期二 11:21:49 +08:00
关闭了连接
监控记录　　解决最初的问题:



    var gi = db.GroupInfo.FirstOrDefault(c => c.GroupName.Contains("g1!"));
var ci = db.ContactInfo.FirstOrDefault(c => c.ID == 12);
ci.ContactName += "!";//
    gi.GroupName += "!";  //这两句放到了using外面,也是可以的
using (var tx = db.Database.BeginTransaction())
{
try
{
db.Database.ExecuteSqlCommand("update GroupInfo set GroupName='hello' where GroupId=209");
db.SaveChanges();//这个语句
tx.Commit();//此语句不要漏了,否则监控结果会是释放了事务,而不是提交了事务!
        }
catch (Exception)
{
tx.Rollback();
}
}
　　监控记录:





已于 2016/6/21 星期二 11:28:28 +08:00
打开了连接SELECT TOP (1)
[Extent1].[GroupId] AS [GroupId],
[Extent1].[GroupName] AS [GroupName]
FROM [dbo].[GroupInfo] AS [Extent1]
WHERE [Extent1].[GroupName] LIKE N'%g1!%'
-- 正在 2016/6/21 星期二 11:28:28 +08:00
执行-- 已在 1 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:28:28 +08:00
关闭了连接已于 2016/6/21 星期二 11:28:28 +08:00
打开了连接SELECT TOP (1)
[Extent1].[ID] AS [ID],
[Extent1].[ContactId] AS [ContactId],
[Extent1].[IsDelete] AS [IsDelete],
[Extent1].[Account] AS [Account],
[Extent1].[ContactName] AS [ContactName],
[Extent1].[CommonMobile] AS [CommonMobile],
[Extent1].[HeadPortrait] AS [HeadPortrait],
[Extent1].[AttFile] AS [AttFile],
[Extent1].[GroupId] AS [GroupId]
FROM [dbo].[ContactInfo] AS [Extent1]
WHERE 12 = [Extent1].[ID]
-- 正在 2016/6/21 星期二 11:28:28 +08:00
执行-- 已在 1 毫秒内完成，结果为: SqlDataReader

已于 2016/6/21 星期二 11:28:28 +08:00
关闭了连接已于 2016/6/21 星期二 11:28:28 +08:00
打开了连接已于 2016/6/21 星期二 11:28:28 +08:00
启动了事务
update GroupInfo set GroupName='hello' where GroupId=209
-- 正在 2016/6/21 星期二 11:28:28 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[ContactInfo]
SET [ContactName] = @0
WHERE ([ID] = @1)
-- @0: '李四1!!!!!' (Type = String, Size = 50)
-- @1: '12' (Type = Int32)
-- 正在 2016/6/21 星期二 11:28:28 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1
UPDATE [dbo].[GroupInfo]
SET [GroupName] = @0
WHERE ([GroupId] = @1)
-- @0: 'g1!!!!!!' (Type = String, Size = 300)
-- @1: '222' (Type = Int32)
-- 正在 2016/6/21 星期二 11:28:28 +08:00
执行-- 已在 1 毫秒内完成，结果为: 1

已于 2016/6/21 星期二 11:28:28 +08:00
提交了事务已于 2016/6/21 星期二 11:28:28 +08:00
关闭了连接
监控记录　　事务部分,我参考的网址:https://msdn.microsoft.com/zh-cn/magazine/dn532202.aspx
　　 https://msdn.microsoft.com/en-us/data/dn456843#retry
　　解释: Name += "!";这么写而不是 Name="李四"; 是为了防止EF的自动优化sql.
　　over...