locked
Web test hanging on heartbeat when run against load balancer

    Dotaz

  • Here’s the scenario:  web test against a load balancer versus sharpshooting the specific server.

     When running against the specific servers, it runs fine.  When pointed to the load balancer, there is a ‘hang’ in the timer.

     Here is the relevant logfile entry from vsts log:  There is something going on with the heartbeat (test heartbeat, not load balancer heartbeat, I believe). 

     [V, 5872, 5, 2010/03/01 08:44:11.873] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd

    [V, 5872, 5, 2010/03/01 08:44:11.874] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd

    [V, 5872, 5, 2010/03/01 08:44:11.876] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/EVLogo.jpg

    [V, 5872, 5, 2010/03/01 08:44:11.876] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://server.iad.liveperson.net/hc/58366488/

    [V, 5872, 5, 2010/03/01 08:44:11.877] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/TopNav.gif

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/eagleview.css

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/Footer.gif

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd?d=EEEUv0uYlTwNms2ScWokNw2&t=633802380069218315

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/bbbsealh1US.gif

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://server.iad.liveperson.net/hc/58366488/?cmd=repstate&site=58366488&channel=web&&ver=1&imageUrl=https://server.iad.liveperson.net/hcp/Gallery/ChatButton-Gallery/English/small/1a

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/Footer.gif

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/bbbsealh1US.gif

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd?d=AvAVInGPe3mLIIAHl6MfiA2&t=633802380069218315

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.888] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/TopNav.gif

    [V, 5872, 5, 2010/03/01 08:44:11.889] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 16, 2010/03/01 08:44:19.034] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:19.034] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 12, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Enqueuing listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 10, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Dequeued listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 12, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Sent heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:29.035] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:29.035] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 16, 2010/03/01 08:44:39.036] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:39.036] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 12, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Enqueuing listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 10, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Dequeued listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 12, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Sent heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:49.037] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:49.037] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/EVLogo.jpg

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: RequestResultBuilder completed building request

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTestAdapter sending: WebTestRequestResult

     Note the huge delay of ~42 sec.  This happens on several but not all requests during the test.  (Not in a browser manual).

    2. března 2010 16:43

Odpovědi

Všechny reakce

  • Here’s the scenario:  web test against a load balancer versus sharpshooting the specific server.

     

    When running against the specific servers, it runs fine.  When pointed to the load balancer, there is a ‘hang’ in the timer.

     

    Here is the relevant logfile entry from vsts log:  There is something going on with the heartbeat (test heartbeat, not load balancer heartbeat, I believe). 

     

    [V, 5872, 5, 2010/03/01 08:44:11.873] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd

    [V, 5872, 5, 2010/03/01 08:44:11.874] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd

    [V, 5872, 5, 2010/03/01 08:44:11.876] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/EVLogo.jpg

    [V, 5872, 5, 2010/03/01 08:44:11.876] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://server.iad.liveperson.net/hc/58366488/

    [V, 5872, 5, 2010/03/01 08:44:11.877] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/TopNav.gif

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/eagleview.css

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/Footer.gif

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd?d=EEEUv0uYlTwNms2ScWokNw2&t=633802380069218315

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.881] vstesthost.exe: WebLoadTestAdapter: WebTest: PreRequestCallback: Enter Url=https://prod.eagleview.com/image/bbbsealh1US.gif

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://server.iad.liveperson.net/hc/58366488/?cmd=repstate&site=58366488&channel=web&&ver=1&imageUrl=https://server.iad.liveperson.net/hcp/Gallery/ChatButton-Gallery/English/small/1a

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/Footer.gif

    [V, 5872, 5, 2010/03/01 08:44:11.882] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/bbbsealh1US.gif

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/WebResource.axd?d=AvAVInGPe3mLIIAHl6MfiA2&t=633802380069218315

    [V, 5872, 5, 2010/03/01 08:44:11.887] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:11.888] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/TopNav.gif

    [V, 5872, 5, 2010/03/01 08:44:11.889] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 16, 2010/03/01 08:44:19.034] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:19.034] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 12, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Enqueuing listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 10, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Dequeued listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 12, 2010/03/01 08:44:28.853] vstesthost.exe: ControllerExecution: Sent heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:29.035] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:29.035] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 16, 2010/03/01 08:44:39.036] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:39.036] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 12, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Enqueuing listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 10, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Dequeued listener message: Microsoft.VisualStudio.TestTools.Common.HeartbeatMessage

    [V, 5872, 12, 2010/03/01 08:44:48.855] vstesthost.exe: ControllerExecution: Sent heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:49.037] vstesthost.exe: AgentObject: Sending heartbeat to listeners

    [V, 5872, 16, 2010/03/01 08:44:49.037] vstesthost.exe: AgentObject: Returned from sending heartbeat

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Enter Url=https://prod.eagleview.com/image/EVLogo.jpg

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: PostRequestCallback: Exit

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTest: RequestResultBuilder completed building request

    [V, 5872, 5, 2010/03/01 08:44:53.879] vstesthost.exe: WebLoadTestAdapter: WebTestAdapter sending: WebTestRequestResult

     

    Note the huge delay of ~42 sec.  This happens on several but not all requests during the test.  (Not in a browser manual).

    1. března 2010 21:17
  • Hello,

    As I do not know much about how load balancer works under the hood, could you please offer more detailed information and phenomenon about 'When pointed to the load balancer, there is a ‘hang’ in the timer'?
    Please remember to mark the replies as answers if they help and unmark them if they provide no help.
    Send us any feedback you have about the help from MSFT at fbmsdn@microsoft.com.
    4. března 2010 9:01
  • Here are some articles you may refer to:
    http://msdn.microsoft.com/en-us/library/ms404664(VS.80).aspx
    http://msdn.microsoft.com/en-us/library/ms404661(VS.80).aspx


    Please remember to mark the replies as answers if they help and unmark them if they provide no help.
    9. března 2010 2:26