Computer startup over HTTP failed: error 1000

[Joe1393]

I'm testing an upgrade from version 2.9 to 2.10 so I can upgrade our ESXi hosts to 6.0u2 to solve the issue of slow attachment of multiple appstacks.  However, I'm getting this error on about half of my desktops.  I've tried both 2012R2 and 2008R2 for the management server.  It's installed with local SQL.  The desktops are Windows 7, floating linked clone pool.  Seems like initially it shows a "HttpInitializeRequest: Manager status 200 response (2 bytes): OK" but then tries again "Computer startup over HTTP failed: error 1000".  Once that fails virtualization is disabled and no appstacks will attach.

I have logs from two desktops, one success one fail:

Fail:

[2016-03-25 19:24:58.937 UTC] [svservice:P828:T856] OnCreateSession called (Session ID 1, Handle 000000000036FC50, Params 0000000000F6E840, Context 0000000000000000)

[2016-03-25 19:25:01.862 UTC] [svservice:P828:T312] Computer UUID (Microsoft format): F6A62A42-87FE-778B-EA05-F56BBADBCD04

[2016-03-25 19:25:01.862 UTC] [svservice:P828:T312] Computer UUID (VMware format): 422aa6f6-fe87-8b77-ea05-f56bbadbcd04

[2016-03-25 19:25:01.862 UTC] [svservice:P828:T312] Initializing HTTP session

[2016-03-25 19:25:01.871 UTC] [svservice:P828:T312] Initializing HTTP connection

[2016-03-25 19:25:01.871 UTC] [svservice:P828:T312] Shuffing 1 Manager(s)

[2016-03-25 19:25:01.892 UTC] [svservice:P828:T312] IsDomainJoinedComputer: NetGetJoinInformation() success, domain name COMPANY and type is 3

[2016-03-25 19:25:01.921 UTC] [svservice:P828:T312] Pre-startup URL: /pre-startup?name=svservice&uuid=422aa6f6-fe87-8b77-ea05-f56bbadbcd04&status=0&osver=6.1.7601&sp=1.0&suite=256&product=1&arch=9&proc=1&agentver=2.10.0.871U&domain=COMPANY&workstation=AV210TSTPA01&machinesid=S-1-5-21-2909739094-3781615540-3712082287

[2016-03-25 19:25:01.921 UTC] [svservice:P828:T312] [0] Connecting to appvtest.COMPANY.com:80 using HTTP (attempt 1)

[2016-03-25 19:25:02.301 UTC] [svservice:P828:T312] HttpReadAllAvailableData: Error 0 in WinHttpQueryDataAvailable: dwSize 0

[2016-03-25 19:25:02.301 UTC] [svservice:P828:T312] HttpInitializeRequest: Manager status 200 response (2 bytes): OK

[2016-03-25 19:25:02.301 UTC] [svservice:P828:T312] LogonMount: agent:0 manager:0 volume(s) to attach

[2016-03-25 19:25:02.347 UTC] [svservice:P828:T312] IsDomainJoinedComputer: NetGetJoinInformation() success, domain name COMPANY and type is 3

[2016-03-25 19:25:02.358 UTC] [svservice:P828:T312] Computer startup URL: /computer-startup?name=svservice&uuid=422aa6f6-fe87-8b77-ea05-f56bbadbcd04&status=0&osver=6.1.7601&sp=1.0&suite=256&product=1&arch=9&proc=1&agentver=2.10.0.871U&domain=COMPANY&workstation=AV210TSTPA01&pre-startup=1&machinesid=S-1-5-21-2909739094-3781615540-3712082287

[2016-03-25 19:25:02.358 UTC] [svservice:P828:T312] [0] Connecting to appvtest.COMPANY.com:80 using HTTP (attempt 1)

[2016-03-25 19:30:01.666 UTC] [svservice:312] WinHttpReceiveResponse timed out waiting for response

[2016-03-25 19:30:01.666 UTC] [svservice:P828:T312] Retrying in 5 seconds (waited 300 seconds out of 300 max)

[2016-03-25 19:30:06.662 UTC] [svservice:P828:T312] Aborting HTTP request after exceeding time limit (300 seconds)

[2016-03-25 19:30:06.662 UTC] [svservice:312] Computer startup over HTTP failed: error 1000

[2016-03-25 19:30:06.662 UTC] [svservice:P828:T312] HttpComputerStartupThread: failed (computer startup)

Success:

[2016-03-25 19:24:58.843 UTC] [svservice:P820:T852] OnCreateSession called (Session ID 1, Handle 00000000003CFB60, Params 0000000000F4E880, Context 0000000000000000)

[2016-03-25 19:25:01.251 UTC] [svservice:P820:T560] Computer UUID (Microsoft format): 72F62A42-0EF6-DBCA-0BE1-DF5682A376C5

[2016-03-25 19:25:01.251 UTC] [svservice:P820:T560] Computer UUID (VMware format): 422af672-f60e-cadb-0be1-df5682a376c5

[2016-03-25 19:25:01.251 UTC] [svservice:P820:T560] Initializing HTTP session

[2016-03-25 19:25:01.262 UTC] [svservice:P820:T560] Initializing HTTP connection

[2016-03-25 19:25:01.262 UTC] [svservice:P820:T560] Shuffing 1 Manager(s)

[2016-03-25 19:25:01.265 UTC] [svservice:P820:T560] IsDomainJoinedComputer: NetGetJoinInformation() success, domain name COMPANY and type is 3

[2016-03-25 19:25:01.584 UTC] [svservice:P820:T560] Pre-startup URL: /pre-startup?name=svservice&uuid=422af672-f60e-cadb-0be1-df5682a376c5&status=0&osver=6.1.7601&sp=1.0&suite=256&product=1&arch=9&proc=1&agentver=2.10.0.871U&domain=COMPANY&workstation=AV210TSTPA02&machinesid=S-1-5-21-2909739094-3781615540-3712082287

[2016-03-25 19:25:01.584 UTC] [svservice:P820:T560] [0] Connecting to appvtest.COMPANY.com:80 using HTTP (attempt 1)

[2016-03-25 19:25:02.039 UTC] [svservice:P820:T560] HttpReadAllAvailableData: Error 0 in WinHttpQueryDataAvailable: dwSize 0

[2016-03-25 19:25:02.039 UTC] [svservice:P820:T560] HttpInitializeRequest: Manager status 200 response (2 bytes): OK

[2016-03-25 19:25:02.039 UTC] [svservice:P820:T560] LogonMount: agent:0 manager:0 volume(s) to attach

[2016-03-25 19:25:02.161 UTC] [svservice:P820:T560] IsDomainJoinedComputer: NetGetJoinInformation() success, domain name COMPANY and type is 3

[2016-03-25 19:25:02.180 UTC] [svservice:P820:T560] Computer startup URL: /computer-startup?name=svservice&uuid=422af672-f60e-cadb-0be1-df5682a376c5&status=0&osver=6.1.7601&sp=1.0&suite=256&product=1&arch=9&proc=1&agentver=2.10.0.871U&domain=COMPANY&workstation=AV210TSTPA02&pre-startup=1&machinesid=S-1-5-21-2909739094-3781615540-3712082287

[2016-03-25 19:25:02.180 UTC] [svservice:P820:T560] [0] Connecting to appvtest.COMPANY.com:80 using HTTP (attempt 1)

[2016-03-25 19:25:03.111 UTC] [svservice:P820:T560] HttpReadAllAvailableData: Error 0 in WinHttpQueryDataAvailable: dwSize 0

[2016-03-25 19:25:03.111 UTC] [svservice:P820:T560] HttpInitializeRequest: Manager status 200 response (34 bytes): STARTUP COMPANY\AV210TSTPA02$

[2016-03-25 19:25:03.112 UTC] [svservice:P820:T560] HttpComputerStartupThread: succeeded (computer startup)

[2016-03-25 19:25:03.112 UTC] [svservice:P820:T560] LogonMount: agent:0 manager:0 volume(s) to attach

Just curious is anyone else is seeing this with 2.10 and if you have, how did you fix it?

[Joe1393]

I have found that if I simply restart the service on the VMs the service connects to the manager correctly.  Is it possible the VMs are starting too fast?  I would like to change the service from Automatic to Automatic (Delayed Start) however when I try it says "The delayed auto-start flag could not be set. Error 87: The parameter is incorrect".  I'm trying now to see if I can get around that and test

[Joe1393]

I was able to delay the service however it didn't seem to help.  Still only about 50% working.

[BlueAceTS]

Hi,

we have the same problem. Do you find a solution for the problem ?

[Ray_handels · Accepted solution]

Could it be that you are using some sort of power management on the VDI's? It seems as if the machine is unable to reach the Appvolumes HTTP service for whatever reason as the log that shows succes connects withion a split second so the server doesn't seem to be to busy.

Maybe you are trying to attach an appstack that has some sort of firewall rule in it??

[agalliasistju]

We seem to have a similar issue.  We've narrowed it down to a couple pools where the common denominators of the broken pools are IE 11 and .Net 4.5.2.  The pools that are working are IE8 and .Net 4.0

I know those aren't ODD versions to be using so I'm not saying it's that, simply that those are the major differences between the pools.

Were you able to fix your issue yet?

(BTW: Hi Ray, I'm Ed Ansbro's co worker)

[Ray_handels]

Welcome to the Appvolumes forums

[Joe1393]

I have not been able to fix this yet, but I have started testing again now that I have upgraded to 2.11.  My desktop is IE11 with .net 4.6.1.  The firewall is disabled on the parent image as well as in our domain GPO so I don't think it's a firewall issue. I'm going to disable all power saving options on the parent VM to see if that helps.  I'll let you know what I find.

[Joe1393]

On the parent image this was set to allow the computer to turn off the device.  I have unchecked that option and since I have done that I have not had the same issue.  I have only tested a handful of times so I will continue to do so.  I have some time today to test again so hopefully I can report back yet today.

[Joe1393]

Marking you as the correct answer.  I have done several tests and I no longer see that error now that power management has been disabled for the network driver.

Thanks!