控制器操作多次出现 - 在Azure模拟器中运行的ASP.NET MVC 4 App

sam*_*y34 7 asp.net-mvc azure visual-studio-debugging asp.net-mvc-4

这可能需要一些时间来解释,但在这里我去:).

我创建了两个Visual Studio 2012项目:

  1. 在Visual Studio的" 添加项目"对话框中使用(Web - > ASP.NET MVC 4 Web应用程序)模板的项目.我没有添加单元测试项目.
  2. 使用Azure云服务模板的项目(云 - > Windows Azure云服务).我添加了一个角色,一个ASP.NET MVC 4 Web角色,并且再次没有添加单元测试项目.

我让这两个项目完全不受Visual Studio为我创建它们的方式的影响.

对于每个项目,我然后去了HomeController该类并在每个AboutContact动作方法中设置断点(仅返回视图的无聊动作).例如,我在方法的唯一一行设置了一个断点:

public ActionResult About()
{
     return View();
}
Run Code Online (Sandbox Code Playgroud)

然后我开始调试第一个项目(非Azure项目).一切都如预期的那样 - 即当我使用浏览器在"关于"和"联系"页面之间导航时,各个操作方法中的断点将在每个请求中被点击一次.对我来说很好.

然后我开始调试Azure项目.我遵循了在关于和联系页面之间导航的相同过程.这次我发现了有趣的非确定性行为.在某些请求中,在页面呈现之前,action方法中的断点将被多次(有时多于两次)命中.有时请求会挂起,页面也不会呈现(即使在等待几分钟后).

我想知道为什么会发生这种情况,因为它影响了我们正在处理的另一个Azure MVC应用程序中更复杂的事情(例如,由于这个问题,应用程序可能会尝试创建两个或更多模型对象的实例)我的数据库为一个请求).

我在以下环境中:

  • Windows 8 Pro(x64)
  • Visual Studio 2012旗舰版
  • 适用于Visual Studio 2012的Windows Azure SDK
  • IIS 8 Express
  • Firefox浏览器与Firebug(也证实使用IE的行为)
  • SQL Server 2012(我猜不太相关)

我注意到的唯一有趣的事情是,每当我调试Azure项目时,Visual Studio 的" 输出"窗口中都会出现以下警告消息:

在角色"My_Web_Role_Name"中重新映射私有端口80到81,以避免在仿真期间发生冲突.

也许这种重新映射与它有关.然后再说一遍,netstat -ano我看到Azure Development Fabric进程正在侦听端口80,所以也许这就是为什么它需要进行重映射...听起来很公平.

无论如何,我希望有人可能知道可能导致这种行为的原因.以下是我尝试过的一些额外点和方法:

  • 重置IIS,重新启动Azure Compute/Storage模拟器,重新启动整个计算机
  • 剥离所有脚本的视图(即如果页面上只有一个基本的HTML段落,行为仍然存在)
  • 我试图找到相关的IIS Express日志文件,但似乎Azure项目不像其他项目那样通过IIS Express运行(这是正确的吗?).
  • 我监控了Azure Compute Emulator控制台......没有什么非常有趣的.

所以...最后,一些问题:

  • 其他人可以重现这种行为吗?
  • Azure模拟器生成的任何其他日志是否可以帮助我?

我真的很感激在这里推进正确的方向:).

干杯!

编辑

这里有一些更多的信息(我正在更新这个问题,因为我继续调试这个问题):

我决定不运行调试解决方案,我把在双方的一些简单的调试信息AboutContact行动方法,例如:

public ActionResult About()
{
     System.IO.File.AppendAllText(@"c:\Logs\azure.log", DateTime.Now + ": Contact, thread " + System.Threading.Thread.CurrentThread.ManagedThreadId + "\r\n");
     return View();
}
Run Code Online (Sandbox Code Playgroud)

我将Tail附加到此文件,并启动Fiddler以查看HTTP上发生了什么.再次在两者AboutContact链接之间导航,我看到以下输出:

从尾巴:

  • ...
  • 9/12/2012 12:14:07 PM:关于,主题7
  • 9/12/2012 12:14:08 PM:联系,第7条
  • 9/12/2012 12:14:09 PM:关于,主题7
  • 9/12/2012 12:14:10 PM:联系,线程7
  • 9/12/2012 12:14:11 PM:关于,主题6
  • 9/12/2012 12:14:12 PM:联系,线程8
  • 9/12/2012 12:14:31 PM:联系,线程7
  • 9/12/2012 12:14:50 PM:联系,线程7
  • 9/12/2012 12:15:03 PM:关于,主题7
  • 9/12/2012 12:15:05 PM:联系,线程8
  • 9/12/2012 12:15:23 PM:关于,主题6
  • 9/12/2012 12:15:42 PM:关于,线程14
  • 9/12/2012 12:16:01 PM:关于,主题6
  • 9/12/2012 12:16:31 PM:联系,线程7
  • 9/12/2012 12:16:33 PM:关于,线程14
  • ...
  • 9/12/2012 12:17:08 PM:联系,主题12
  • 9/12/2012 12:17:09 PM:关于,线程12
  • 9/12/2012 12:17:28 PM:关于,主题5
  • ...

来自Fiddler的相同请求(注意我也一直在玩我的角色端点设置,但无济于事):

显示HTTP流量的Fiddler屏幕截图

所以...这里的带回家信息是:

  • HTTP流量似乎正常(Fiddler没有显示任何重复请求)
  • 对于同一请求,动作方法有时被调用两次或三次,看似不确定(即上述原始问题)
  • 有趣的是,在我的情况下,在动作方法上发生另一次'命中'之前似乎完全是 19秒(即当多次击中动作方法时,命中之间的时间差是确定性的)
  • 每当动作方法被多次命中时,每次命中的线程ID通常是不同的

我会继续深入挖掘...也许有人会很友好地根据这个更新的信息提供建议:).

编辑2 我决定在每种情况下打印出进程ID以及action方法中的线程ID.对于每个请求,进程ID都是与IIS Express Worker Process对应的进程ID.因此,似乎IIS Express工作进程有时会产生多个线程来处理请求,每个线程相隔19秒.

......我走得更深...... :)

编辑3

我想我正在接近这个问题......我看了一下IIS Express托盘并在启动应用程序后看到了以下内容(没有调试):

IIS Express窗口显示在Web角色下运行的IIS应用程序

我决定直接使用这个URL(而不是如上面的Fiddler所示的127.0.0.2:8888),问题似乎消失了.这现在开始有意义......如果我直接通过端口8889调用IIS应用程序,一切正常,就像它对原始的非Azure项目一样.如果我通过端口8888调用Web角色(即特定于Azure的部分),它有时似乎多次调用IIS应用程序.

所以我现在开始缩小对网络角色的关注.我会试着看看我是否能找到一些相关的网络角色日志......

编辑4

我确保在应用程序上正确设置了诊断并在调试模式下运行它.然后,我转到Azure Compute Emulator UI,右键单击我的Web角色实例,然后选择"Open Local Store".这把我带到资源管理器,在那里我能够找到一个子文件夹"temp\temp\RoleTemp\iisexpress",里面有一个日志文件,内容看起来像这样(请注意我已将我的端点更改回端口80 ,因此IIS Express应用程序现在返回到端口81):

  • 请求开始:"GET"127.0.0.1:81/Home/About
  • 申请已结束:127.0.0.1:81/Home /关于HTTP状态200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/About
  • 申请已结束:127.0.0.1:81/Home /关于HTTP状态200.0
  • 请求开始:"GET"127.0.0.1:81/Home/About
  • 申请已结束:127.0.0.1:81/Home /关于HTTP状态200.0
  • 请求开始:"GET"127.0.0.1:81/Home/About
  • 申请已结束:127.0.0.1:81/Home /关于HTTP状态200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0
  • 请求开始:"GET"127.0.0.1:81/Home/About
  • 申请已结束:127.0.0.1:81/Home /关于HTTP状态200.0
  • 请求开始:"GET"127.0.0.1:81/Home/Contact
  • 请求已结束:127.0.0.1:81/Home/Contact,HTTP状态为200.0

我只是在关于和联系页面之间切换,因此从日志中可以看到单个Web角色端点请求有时会导致Web角色多次调用IIS应用程序.现在要搜索一些更高级别的Web角色日志,以找出它为什么这样做:)!

sam*_*y34 1

因此,根据我在原始帖子的各种编辑中所写的内容:

  • 至少在我的计算机上,DevFC.exe 进程(开发结构、Azure 计算模拟器)有时会从 IIS Express 生成对所请求资源的多个请求。也就是说,它将在模拟器端点上发出单个请求,有时会一个接一个地向底层 IIS Express 应用程序生成多个请求,即使它们都成功且 HTTP 状态代码为 200。这些生成的请求之间的时间差似乎是恒定的,但除此之外,该行为似乎是不确定的(即,并非对模拟器端点的每个请求都发生这种情况)。
  • 为了调试这个问题,我需要访问 Azure 计算模拟器的源代码,但我猜我无法获得。

现在,我的理论(和希望)是,这种行为在某种程度上特定于我的开发环境,并且不会在真正的 Azure 基础设施上重现。也就是说,我想出了一个解决方法......

我为 Firefox 创建了一个简单的代理文件,以将前往本地 Azure 模拟器端口的所有流量重定向到 IIS Express 应用程序端口。这解决了问题,但是我知道我现在基本上绕过了Azure模拟器,我猜我将来可能会遇到问题,因为我正在这样做(例如,如果我决定使用AppFabric缓存? )!

同时,我将继续使用此解决方法进行开发,并定期部署到我的 Azure 临时环境中进行测试。

无论如何,如果有人知道我如何解决根本问题,请告诉我!:)

编辑:我无法再使用 Azure SDK 工具 2.2 版重现该问题。如果您仍然看到此行为,请尝试升级!