数据库代理错误日志

错误#1 16:50 2014-5-20
安装好数据库(08R2)，启动数据库代理服务失败，当时也没在意。后来装上SQL12，再次启动数据库代理依旧失败。

不能再得过且过，该找找具体原因了。查看SQLAGENT代理日志：

2014-05-20 16:51:33 - ? [100] Microsoft SQLServerAgent 版本 11.0.2100.60 (内部版本号 x86 unicode 零售): 进程 ID 3076
2014-05-20 16:51:33 - ? [495] SQL Server 代理启动服务帐户是 NT Service\SQLAgent$SQL12。
2014-05-20 16:51:33 - ! [150] SQL Server 不接受连接(错误: 18456)。请等待 SQL Server 允许连接。尝试的操作为: 启动时验证连接。
2014-05-20 16:51:33 - ! [000] 无法连接到服务器“USER-67NP5R8LGK\SQL12”；SQLServerAgent 无法启动
2014-05-20 16:51:33 - ! [298] SQLServer 错误:  18456，用户 'NT AUTHORITY\ANONYMOUS LOGON' 登录失败。 [SQLSTATE 28000] 
2014-05-20 16:51:33 - ! [382] 无法登录到服务器“USER-67NP5R8LGK\SQL12”(DisableAgentXPs)
2014-05-20 16:51:33 - ? [098] SQLServerAgent 已终止(一般)

查看服务登录帐户为网络服务，将其改成Administrator后再次启动，代理日志信息：

2014-05-20 16:53:26 - ? [100] Microsoft SQLServerAgent 版本 11.0.2100.60 (内部版本号 x86 unicode 零售): 进程 ID 8128
2014-05-20 16:53:26 - ? [495] SQL Server 代理启动服务帐户是 USER-67NP5R8LGK\Administrator。
2014-05-20 16:53:26 - ! [150] SQL Server 不接受连接(错误: 18452)。请等待 SQL Server 允许连接。尝试的操作为: 启动时验证连接。
2014-05-20 16:53:26 - ! [000] 无法连接到服务器“USER-67NP5R8LGK\SQL12”；SQLServerAgent 无法启动
2014-05-20 16:53:26 - ! [298] SQLServer 错误:  18452，登录失败。该登录名来自不受信任的域，不能与 Windows 身份验证一起使用。 [SQLSTATE 28000] 
2014-05-20 16:53:26 - ! [382] 无法登录到服务器“USER-67NP5R8LGK\SQL12”(DisableAgentXPs)
2014-05-20 16:53:26 - ? [098] SQLServerAgent 已终止(一般)

对应这段时间ERRORLOG下的信息为：

2014-05-20 16:51:33.30 登录          Login failed for user 'NT AUTHORITY\ANONYMOUS LOGON'. 原因: 找不到与提供的名称匹配的登录名。 [客户端: <local machine>]
2014-05-20 16:53:26.65 登录          错误: 17806，严重性: 20，状态: 14。
2014-05-20 16:53:26.65 登录          SSPI handshake failed with error code 0x8009030c, state 14 while establishing a connection with integrated security; the connection has been closed. Reason: AcceptSecurityContext 失败。Windows 错误代码指示失败的原因。 登录没有成功   [客户端: <local machine>]
2014-05-20 16:53:26.69 登录          错误: 18452，严重性: 14，状态: 1。
2014-05-20 16:53:26.69 登录          Login failed. The login is from an untrusted domain and cannot be used with Windows authentication. [客户端: <local machine>]
2014-05-20 16:53:26.75 登录          错误: 17806，严重性: 20，状态: 14。
2014-05-20 16:53:26.75 登录          SSPI handshake failed with error code 0x8009030c, state 14 while establishing a connection with integrated security; the connection has been closed. Reason: AcceptSecurityContext 失败。Windows 错误代码指示失败的原因。 登录没有成功   [客户端: <local machine>]
2014-05-20 16:53:26.75 登录          错误: 18452，严重性: 14，状态: 1。
2014-05-20 16:53:26.75 登录          Login failed. The login is from an untrusted domain and cannot be used with Windows authentication. [客户端: <local machine>]

解答#1 网上找到解决方法，测试可行
1.open regedit
2.go to path: HKLM\System\CurrentControlSet\Control\LSA
3.add DWORD "DisableLoopbackCheck”
4.set value to 1
错误#2 17:54 2014-5-26
SQL12代理日志中很多Reloading agent settings。本来有一个登录名专门用于执行SSIS包，近几天作业报错，错误信息为登录超时。

EXEC xp_readerrorlog 0,1,null,null,'2014-05-26','2014-05-27','DESC'
EXEC xp_readerrorlog 0,2,null,null,'2014-05-26','2014-05-27','DESC'

查看ERRORLOG下没有这个登录名的信息，代理日志中发现很多Reloading agent settings。晚上回来后查看自己电脑上的代理日志情况，发现也有很多Reloading agent settings。好吧，我承认都没怎么去细看代理日志。

2014-05-20 17:05:49 - ? [100] Microsoft SQLServerAgent 版本 11.0.2100.60 (内部版本号 x86 unicode 零售): 进程 ID 3896
2014-05-20 17:05:49 - ? [495] SQL Server 代理启动服务帐户是 USER-67NP5R8LGK\Administrator。
2014-05-20 17:05:50 - ? [000] 配置选项 'show advanced options' 已从 0 更改为 1。请运行 RECONFIGURE 语句进行安装。 [SQLSTATE 01000] (消息 15457)  配置选项 'Agent XPs' 已从 0 更改为 1。请运行 RECONFIGURE 语句进行安装。 [SQLSTATE 01000] (消息 15457)  配置选项 'show advanced options' 已从 1 更改为 0。请运行 RECONFIGURE 语句进行安装。 [SQLSTATE 01000] (消息 15457)
2014-05-20 17:05:51 - ? [101] SQL Server USER-67NP5R8LGK\SQL12 版本 11.00.2100 (连接限制: 0)
2014-05-20 17:05:51 - ? [102] SQL Server ODBC 驱动程序版本 11.00.2100
2014-05-20 17:05:51 - ? [103] 驱动程序使用的 NetLib 是 DBNETLIB；本地主机服务器是 USER-67NP5R8LGK\SQL12
2014-05-20 17:05:51 - ? [310] 检测到 4 个处理器和 3018 MB RAM
2014-05-20 17:05:51 - ? [339] 本地计算机是 USER-67NP5R8LGK，运行的是 Windows NT 6.1 (7601) Service Pack 1
2014-05-20 17:05:51 - ? [432] 子系统缓存中有 12 个子系统
2014-05-20 17:06:04 - ! [364] 尚未启动 Messenger 服务 - 将不发送 NetSend 通知
2014-05-20 17:06:04 - ? [129] SQLAgent$SQL12 在 Windows NT 服务控制下启动
2014-05-20 17:06:04 - + [475] 没有为代理通知启用数据库邮件。
2014-05-20 17:06:04 - + [396] 尚未定义空闲 CPU 条件 - OnIdle 作业计划将不起任何作用
2014-05-20 17:07:34 - ? Reloading agent settings
2014-05-20 17:09:04 - ? Reloading agent settings
2014-05-20 17:10:34 - ? Reloading agent settings
2014-05-20 17:12:05 - ? Reloading agent settings
2014-05-20 17:13:35 - ? Reloading agent settings
2014-05-20 17:15:05 - ? Reloading agent settings
2014-05-20 17:16:35 - ? Reloading agent settings
2014-05-20 17:18:06 - ? Reloading agent settings
2014-05-20 17:19:36 - ? Reloading agent settings
2014-05-20 17:21:06 - ? Reloading agent settings
2014-05-20 17:22:36 - ? Reloading agent settings
2014-05-20 17:24:06 - ? Reloading agent settings
2014-05-20 17:25:36 - ? Reloading agent settings
2014-05-20 17:27:06 - ? Reloading agent settings
2014-05-20 17:28:37 - ? Reloading agent settings
2014-05-20 17:30:07 - ? Reloading agent settings
2014-05-20 17:31:37 - ? Reloading agent settings
2014-05-20 17:33:07 - ? Reloading agent settings
2014-05-20 17:34:37 - ? Reloading agent settings
2014-05-20 17:36:07 - ? Reloading agent settings
2014-05-20 17:37:37 - ? Reloading agent settings
2014-05-20 17:39:08 - ? Reloading agent settings
2014-05-20 17:40:38 - ? Reloading agent settings
2014-05-20 17:42:08 - ? Reloading agent settings
2014-05-20 17:43:38 - ? Reloading agent settings
2014-05-20 17:45:08 - ? Reloading agent settings
2014-05-20 17:46:38 - ? Reloading agent settings

解答#2 网上找到解决方法

Go to registry on your SQL Server machine and navigate to corresponding SQL Instance registry key: HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Microsoft SQL Server\ MSSQL11.YOUR_INSTANCE_NAME\SQLServerAgent
Change registry settings called “AutoRegistryRefresh” from 1 to 0.
The side impact of this: if you change any default settings behavior for SQL Agent, you may need to restart SQL Agent to get it.

在自己电脑上测试修改后没再出现那条信息，暂时没应用于生产环境。 附上两张截图，SQL代理没能正常启动时查看SQL Server日志所报的错误。但现在正常了，不知是不是因为解答#1的缘故？望知道的同学告诉一声。