疑难杂症--SQL SERVER 18056的错误

摘要:
朋友遇到一个很棘手的问题,查看服务器日志,报以下错误:01/21/201411:47:43,spid296,未知,错误:18056,严重性:20,状态:29。

朋友遇到一个很棘手的问题,查看服务器日志,报以下错误:

01/21/2014 11:47:43,spid296,未知,错误: 18056,严重性: 20,状态: 2901/21/2014 11:47:43,spid495,未知,
The client was unable to reuse a session with SPID 495<c/>
which had been reset for connection pooling. The failure ID is 29. 
This error may have been caused byan earlier operation failing. 
Check the error logs for failed operations immediately before this error message.

百度一下, 找到相关文章:http://blog.csdn.net/yangzhawen/article/details/8209167

一方面让开发从IIS角度去解决,另一方面从SQL SERVER入手,继续查看错误日志,发现以下错误:

01/21/2014 11:46:10,spid8s,未知,
SQL Server has encountered 3 occurrence(s) of I/O requests taking longer 
than 15 seconds to complete on file [H:templog.ldf] in database [tempdb]
The OS file handle is 0x0000000000001254.  
The offset of the latest long I/O is: 0x000000184a6a00
01/21/2014 11:46:10,spid8s,未知,
SQL Server has encountered 3 occurrence(s) of I/O 
requests taking longer than 15 seconds tocomplete 
on file [H:HisDataTXX.mdf] in database [xxx]
The OS file handle is 0x0000000000001268.  
The offset of the latest long I/O is: 0x00000349a6e000

使用以下代码查看当前耗CPU和IO比较多的执行计划

--最耗费CPU的前个查询以及它们的执行计划
SETTRANSACTION ISOLATION LEVEL READ UNCOMMITTED;
WITHTEMP AS(
SELECTCAST((qs.total_worker_time) / 1000000.0 AS DECIMAL(28,2)) ASTotalSecondsForCPUTime
,CAST(qs.total_worker_time* 100.0 / qs.total_elapsed_time  AS DECIMAL(28,2)) ASCPUPersent
,CAST((qs.total_elapsed_time- qs.total_worker_time)* 100.0 / qs.total_elapsed_time AS DECIMAL(28, 2)) ASWaitingPersent
,qs.execution_countExecutionCount
,CAST((qs.total_worker_time)/ 1000000.0 / qs.execution_count AS DECIMAL(28, 2)) ASAvgSecondsForCPUTime
,SUBSTRING (qt.text,(qs.statement_start_offset/2) + 1,     
    ((CASEWHEN qs.statement_end_offset = -1
      THEN LEN(CONVERT(NVARCHAR(MAX), qt.text)) * 2
      ELSEqs.statement_end_offset
      END - qs.statement_start_offset)/2) + 1) ASIndividualQuery
,qt.text ASParentQuery
,DB_NAME(qt.dbid) ASDatabaseName
,qp.query_planQueryPlan
FROMsys.dm_exec_query_stats qs
CROSS APPLY sys.dm_exec_sql_text(qs.sql_handle) asqt
CROSSAPPLY sys.dm_exec_query_plan(qs.plan_handle) qp
WHEREqs.total_elapsed_time > 0)
SELECTTOP(20)* FROM TEMP
ORDERBY TEMP.TotalSecondsForCPUTime DESC
--最占IO的前个查询以及它们的执行计划
SETTRANSACTION ISOLATION LEVEL READ UNCOMMITTED;
WITHTEMP AS(
SELECT (qs.total_logical_reads + qs.total_logical_writes) ASTotalIO
,(qs.total_logical_reads+ qs.total_logical_writes) / qs.execution_count ASAvgIO
,qs.execution_count ASExecutionCount
,SUBSTRING (qt.text,(qs.statement_start_offset/2) + 1,     
((CASEWHEN qs.statement_end_offset = -1
THENLEN(CONVERT(NVARCHAR(MAX), qt.text)) * 2
ELSEqs.statement_end_offset
END- qs.statement_start_offset)/2) + 1) ASIndividualQuery
,qt.text ASParentQuery
, DB_NAME(qt.dbid) ASDatabaseName
,qp.query_plan ASQueryPlan
FROMsys.dm_exec_query_stats qs
CROSS APPLY sys.dm_exec_sql_text(qs.sql_handle) asqt
CROSSAPPLY sys.dm_exec_query_plan(qs.plan_handle) qp)
SELECTTOP(20)* FROM TEMP
ORDERBY TEMP.TotalIO DESC
View Code

发现一部分耗时长和IO高的执行语句,提起这些语句待分析优化

在使用以下代码来查看当前请求和阻塞:

SELECT
    SPID                =er.session_id 
    ,STATUS             =ses.STATUS 
    ,[LOGIN]            =ses.login_name 
    ,HOST               = ses.host_name
    ,BlkBy              =er.blocking_session_id 
    ,DBName             = DB_NAME(er.database_id) 
    ,CommandType        =er.command 
    ,SQLStatement       = st.text
    ,ObjectName         = OBJECT_NAME(st.objectid) 
    ,ElapsedMS          =er.total_elapsed_time 
    ,CPUTime            =er.cpu_time 
    ,IOReads            = er.logical_reads +er.reads 
    ,IOWrites           =er.writes 
    ,LastWaitType       =er.last_wait_type 
    ,StartTime          =er.start_time 
    ,Protocol           =con.net_transport 
    ,ConnectionWrites   =con.num_writes 
    ,ConnectionReads    =con.num_reads 
    ,ClientAddress      =con.client_net_address 
    ,Authentication     =con.auth_scheme 
FROMsys.dm_exec_requests er 
OUTERAPPLY sys.dm_exec_sql_text(er.sql_handle) st 
LEFT JOINsys.dm_exec_sessions ses 
ON ses.session_id =er.session_id 
LEFT JOINsys.dm_exec_connections con 
ON con.session_id =ses.session_id 
WHERE er.session_id > 50 
ORDER BY er.blocking_session_id DESC,er.session_id
View Code

发现以下问题:

疑难杂症--SQL SERVER 18056的错误第1张

MSDN上有如下介绍:

ConnectionWrites:此连接中已发生的读包次数。可为空值。
ConnectionReads:此连接中已发生的写数据包次数。可为空值。

在普通的OLTP数据上,ConnectionWrites和ConnectionReads 基本在几十到上百,而在这台服务器上达到了352W之多,经确认,192.168.8.16 上是新产品服务器,服务器出现问题与该产品上线时间基本吻合,基本可断定问题根源就是该新产品。

剩下问题表示分析为什么需要如此庞大的网络包,寻找解决之道。

免责声明:文章转载自《疑难杂症--SQL SERVER 18056的错误》仅用于学习参考。如对内容有疑问,请及时联系本站处理。

上篇VMware虚拟机 Ubuntu 实用技巧 (1) -- 安装VMware Tool笔记本硬盘升级--SSD换成三星evo 970 plus注意事项下篇

宿迁高防,2C2G15M,22元/月;香港BGP,2C5G5M,25元/月 雨云优惠码:MjYwNzM=

相关文章

uboot常用命令详解

dnw:在进入系统之前进入指令行,输入该指令可下载烧录文件。 re:重新启动嵌入式系统。 printenv:打印当前系统环境变量。 setenv:设置环境变量,格式:setenv name value ...,表示将name 变量设置成value 值;如果没有这个参数,表示删除该变量。 saveenv:保存环境变量到NAND中。 sleep:延迟执行,格式...

更换Mariadb库为mysql 5.7

https://www.cnblogs.com/get-rich/articles/10623208.html entOS 7 版本将MySQL数据库软件从默认的程序列表中移除,用MariaDB代替了,MariaDB数据库管理系统是MySQL的一个分支,主要由开源社区在维护,采用GPL授权许可。开发这个分支的原因之一是:甲骨文公司收购了MySQL后,有将M...

SQL Server 阻塞原因分析

这里通过连接在sysprocesses里字段值的组合来分析阻塞源头,可以把阻塞分为以下5种常见的类型(见表)。waittype,open_tran,status,都是sysprocesses里的值,“自我修复?”列的意思,就是指阻塞能不能自动消失。  5种常见的阻塞类型 类型 waittype open_tran status 自我修复 原因/其他特征...

mysql--日志文件

1 选择常规查询日志和慢查询日志输出目标 1.1  log_output查看、定义   所谓的输出目标就是日志写入到哪里,mysql中用系统变量 log_output来指定输出目标,log_output的值有三个: NONE   FILE   TABLE  (其中NONE优先级最高)   mysql中log_output语法 :   show variab...

MySQL数据库

MySQL数据库 一、库操作 1、创建数据库 1.1语法:CREATE DATABASE数据库名[charset utf8]; 1.2数据库命名规则:可以由字母、数字、下划线、@、#、$,区分大小写,唯一性,不能使用关键字如 create select,不能单独使用数字,最长128位,#基本上跟。,python或者js的命名规则一样。 2、数据库相关操作:...

SQL优化

SQL优化   sql执行性能低、执行时间长、等待时间长、SQL语句欠佳(连接查询)、索引失效、服务器参数设置。 (1.)SQL解析过程: 编写过程:select distinct ... from ... join..on..where..group by...having...order 解析过程:from .. on.. join..where.....