Dual Server Setup 32000 - BisSubscribe.exe - Unable to connect to BIS service

Using the latest installation guide and a culmination of several other successfull install guides, I am attempting to install VSTS in a dual server mode and have been successfull in loading the database tier and am about 98% complete on the app tier before getting the following errors:

1. The is the initial error thrown at the very end of the install at about 98%

Event Type:Error

Event Source:MsiInstaller

Event Category:None

Event ID:10005

Date:7/21/2005

Time:9:33:18 AM

User:PROPHIT\Tfssetup

Computer:PIEPN3

Description:

Product: Visual Studio 2005 Team Foundation Application Tier Beta 2 (English) -- Error 32000.The Commandline '"C:\Program Files\Microsoft Visual Studio 2005Enterprise Server\BISIISDIR\sdk\bin\BisSubscribe.exe" /eventType ArtifactChangedEvent /userid PROPHIT\tfsservice /deliveryType Soap /Address http://PIEPN3:8080/Currituck/BisService.asmx' returned non-zero value: 1.

For more information, see Help andSupportCenter at http://go.microsoft.com/fwlink/events.asp.

Data:

0000: 7b 39 45 42 34 43 36 43{9EB4C6C

0008: 39 2d 32 34 36 31 2d 349-2461-4

0010: 37 44 38 2d 38 39 44 447D8-89DD

0018: 2d 37 46 41 39 45 45 37-7FA9EE7

0020: 45 35 30 36 37 7dE5067}

2. This seems to fail due to the Registration service not available. I have verified that I can pull up the registration.asmx , BUT , when I click “Invoke” , I get a page notfound.

Event Type:Information

Event Source:Tfs

Event Category:None

Event ID:0

Date:7/21/2005

Time:8:57:56 AM

User:N/A

Computer:PIEPN3

Description:

[1]Exception Unable to connect to BIS service. while loading:at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationService.RefreshCacheIfNeeded(Boolean direct)

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationService.GetRegistrationEntries(String toolId)

at Microsoft.VisualStudio.Bis.Proxy.BisProxyServices.GetBisProxyUrl(IRegistration regProxy, Type bisServiceType)

at Microsoft.VisualStudio.Bis.Proxy.BisProxyServices.GetBisProxyInternal(TeamFoundationServer tfsObject, IRegistration regProxy, Type bisServiceType)

at Microsoft.VisualStudio.Bis.Proxy.BisProxyServices.GetTfsProxy(TeamFoundationServer tfs, Type bisServiceType)

at Microsoft.VisualStudio.Bis.Eventing.LocalEventsAdapter..ctor()

For more information, see Help andSupportCenter at http://go.microsoft.com/fwlink/events.asp.

3. No Clue here , apparently tied to the registration piece not running.

Event Type:Warning

Event Source:Tfs

Event Category:None

Event ID:0

Date:7/21/2005

Time:8:59:58 AM

User:N/A

Computer:PIEPN3

Description:

Initialization failed to load task list from TFS Registration service. Loading task list from log file.

For more information, see Help andSupportCenter at http://go.microsoft.com/fwlink/events.asp.

4. This seems to indicate that the user I am logged in as (“Tfssetup”) does not have access rights to the registration service. Even setting the service to Anonymous has not affect on this.

Event Type:Error

Event Source:Tfs

Event Category:None

Event ID:0

Date:7/21/2005

Time:8:59:58 AM

User:N/A

Computer:PIEPN3

Description:

Microsoft.VisualStudio.ELead.Common.Exceptions.ELeadException: Unable to connect to BIS service. > Microsoft.VisualStudio.ELead.Common.Exceptions.ELeadException: Team foundation server is not available; please contact your system administrator (URL =http://piepn3:8080/bisserver/registration.asmx is unaccessible). > System.Net.WebException: The operation has timed out

at System.Web.Services.Protocols.WebClientProtocol.GetWebResponse(WebRequest request)

at System.Web.Services.Protocols.HttpWebClientProtocol.GetWebResponse(WebRequest request)

at Microsoft.VisualStudio.Bis.Services.TfsProxy.GetWebResponse(WebRequest request)

at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationServiceProxyWsdl.GetRegistrationEntries(String toolId)

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationProxy.GetRegistrationEntries(String toolId)

End of inner exception stack trace

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationProxy.GetRegistrationEntries(String toolId)

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationService.RefreshCacheIfNeeded(Boolean direct)

End of inner exception stack trace

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationService.RefreshCacheIfNeeded(Boolean direct)

at Microsoft.VisualStudio.Bis.Proxy.BisRegistrationService.GetRegistrationEntries(String toolId)

at Microsoft.VisualStudio.Bis.Server.Scheduler.TaskScheduler.LazyInit()

For more information, see Help andSupportCenter at http://go.microsoft.com/fwlink/events.asp.

5. Seems to be a repercussion of the Task scheduler not running.

Event Type:Error

Event Source:Tfs

Event Category:None

Event ID:0

Date:7/21/2005

Time:8:59:58 AM

User:N/A

Computer:PIEPN3

Description:

System.Xml.XmlException: Root element is missing.

at System.Xml.XmlTextReaderImpl.Throw(Exception e)

at System.Xml.XmlTextReaderImpl.ThrowWithoutLineInfo(String res)

at System.Xml.XmlTextReaderImpl.ParseDocumentContent()

at System.Xml.XmlTextReaderImpl.Read()

at System.Xml.XmlLoader.Load(XmlDocument doc, XmlReader reader, Boolean preserveWhitespace)

at System.Xml.XmlDocument.Load(XmlReader reader)

at Microsoft.VisualStudio.Bis.Server.Scheduler.TaskScheduler.LoadTimeLog(ArrayList serviceList, Boolean addNew)

For more information, see Help andSupportCenter at http://go.microsoft.com/fwlink/events.asp.
Any help would be greatly apprieciated. I can provide more detail if needed.

Thx,
Bryan

[26765 byte] By [BryanMiller] at [2007-12-16]
# 1
Bryan, appreciate your taking the time to pull all the data. Would be great if I could get one more piece.

In the %temp% directory of the installing user there is a file (VSMsi*.log) that has a lot of information related to setup. This will help us see the context under which the error occurred and on which particular piece it was trying to register when it failed.

Btw, setting to Anonymous in IIS won't really work as TFS does some of its validation.

MarcKuperstein-MSFT at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...
# 2
Thx for responding Marc,
The strange thing is , there are a few logs but there isn't a "VSMsi*.log" of any sort in the temp folder. It was there throughout the installation but dissappears after a certain amount of time throught the install. Its almost like it finished the install and transfeered all error reporting to the event log. The "VSMsi*.log" was critical in getting me where i am today. Is this correct or am i missing something?

Thx,
Bryan

BryanMiller at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...
# 3
on a successful install the log files are supposed to be moved to the installed directory path.

Have you tried yet with the July CTP?

MarcKuperstein-MSFT at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...
# 4
Hi I am facing exactly the same problem, the BisSubscribe.exe just unable to connect to BIS service.

Pasted from my log:
08/01/05 14:12:22 DDSet_Status: Process returned 0
08/01/05 14:12:22 DDSet_Status: Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\CURIISDIR\uninstall\CurrituckRegistration.xml" EAS4 BisDB' returned 0
08/01/05 14:12:22 DDSet_CARetVal: 0
08/01/05 14:12:22 DDSet_Status: QuietExec returned 0
MSI (s) (A0:C4) [14:12:22:053]: Executing op: ActionStart(Name=CA_QuietExec,,)
08/01/05 14:12:22 DDSet_Exit: QuietExec ended
MSI (s) (A0:C4) [14:12:22:069]: Executing op: CustomActionSchedule(Action=CA_QuietExec,ActionType=17409,Source=BinaryData,Target=QuietExec,CustomActionData="C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB|0|0|55|0)
MSI (s) (A0:E0) [14:12:22:084]: Invoking remote custom action. DLL: C:\WINDOWS\Installer\MSI18D.tmp, Entrypoint: QuietExec
08/01/05 14:12:22 DDSet_Status: LANGID: 1033
08/01/05 14:12:22 DDSet_Entry: QuietExec started
08/01/05 14:12:22 DDSet_Status: Commandline: "C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB
08/01/05 14:12:22 DDSet_Status: IgnoreExitCode: 0
08/01/05 14:12:22 DDSet_Status: Silent: 0
08/01/05 14:12:22 DDSet_Status: ActionStart: 55
08/01/05 14:12:22 DDSet_Status: Cost: 0
08/01/05 14:12:22 DDSet_Status: WorkingDirectory:
08/01/05 14:12:22 DDSet_Status: Silent: 0
08/01/05 14:12:22 DDSet_Status: ActionStart: 55
08/01/05 14:12:22 DDSet_Status: Cost: 0
08/01/05 14:12:22 DDSet_Status: WorkingDirectory:
System.Net.WebException: The operation has timed out
at System.Net.HttpWebRequest.GetResponse()
at System.Xml.XmlDownloadManager.GetNonFileStream(Uri uri, ICredentials credentials)
at System.Xml.XmlDownloadManager.GetStream(Uri uri, ICredentials credentials)
at System.Xml.XmlUrlResolver.GetEntity(Uri absoluteUri, String role, Type ofObjectToReturn)
at System.Xml.XmlTextReaderImpl.OpenUrlDelegate(Object xmlResolver)
at System.Threading.CompressedStack.runTryCode(Object userData)
at System.Runtime.CompilerServices.RuntimeHelpers.ExecuteCodeWithGuaranteedCleanup(TryCode code, CleanupCode backoutCode, Object userData)
at System.Threading.CompressedStack.Run(CompressedStack compressedStack, ContextCallback callback, Object state)
at System.Xml.XmlTextReaderImpl.OpenUrl()
at System.Xml.XmlTextReaderImpl.Read()
at System.Xml.XmlLoader.Load(XmlDocument doc, XmlReader reader, Boolean preserveWhitespace)
at System.Xml.XmlDocument.Load(XmlReader reader)
at System.Xml.XmlDocument.Load(String filename)
at Microsoft.VisualStudio.Bis.Tools.BisRegistrationUpdate.Main(String args)
08/01/05 14:14:03 DDSet_Status: Process returned 1
08/01/05 14:14:03 DDSet_Error: The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB' returned non-zero value: 1.
MSI (s) (A0!D4) [14:14:52:666]: Product: Visual Studio 2005 Team Foundation App-Data Tier Beta 2 (English) -- Error 32000.The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB' returned non-zero value: 1.

08/01/05 14:14:52 DDSet_Status: Commandline: "C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB
08/01/05 14:14:52 DDSet_Status: IgnoreExitCode: 0
08/01/05 14:14:52 DDSet_Status: Silent: 0
08/01/05 14:14:52 DDSet_Status: ActionStart: 55
08/01/05 14:14:52 DDSet_Status: Cost: 0
08/01/05 14:14:52 DDSet_Status: WorkingDirectory:
08/01/05 14:14:52 DDSet_Status: Silent: 0
08/01/05 14:14:52 DDSet_Status: ActionStart: 55
08/01/05 14:14:52 DDSet_Status: Cost: 0
08/01/05 14:14:52 DDSet_Status: WorkingDirectory:
System.Net.WebException: The operation has timed out
at System.Net.HttpWebRequest.GetResponse()
at System.Xml.XmlDownloadManager.GetNonFileStream(Uri uri, ICredentials credentials)
at System.Xml.XmlDownloadManager.GetStream(Uri uri, ICredentials credentials)
at System.Xml.XmlUrlResolver.GetEntity(Uri absoluteUri, String role, Type ofObjectToReturn)
at System.Xml.XmlTextReaderImpl.OpenUrlDelegate(Object xmlResolver)
at System.Threading.CompressedStack.runTryCode(Object userData)
at System.Runtime.CompilerServices.RuntimeHelpers.ExecuteCodeWithGuaranteedCleanup(TryCode code, CleanupCode backoutCode, Object userData)
at System.Threading.CompressedStack.Run(CompressedStack compressedStack, ContextCallback callback, Object state)
at System.Xml.XmlTextReaderImpl.OpenUrl()
at System.Xml.XmlTextReaderImpl.Read()
at System.Xml.XmlLoader.Load(XmlDocument doc, XmlReader reader, Boolean preserveWhitespace)
at System.Xml.XmlDocument.Load(XmlReader reader)
at System.Xml.XmlDocument.Load(String filename)
at Microsoft.VisualStudio.Bis.Tools.BisRegistrationUpdate.Main(String args)
08/01/05 14:16:33 DDSet_Status: Process returned 1
08/01/05 14:16:33 DDSet_Error: The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB' returned non-zero value: 1.
MSI (s) (A0!D4) [14:26:09:253]: Product: Visual Studio 2005 Team Foundation App-Data Tier Beta 2 (English) -- Error 32000.The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB' returned non-zero value: 1.

08/01/05 14:26:09 DDSet_Status: Commandline: "C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB
08/01/05 14:26:09 DDSet_Status: IgnoreExitCode: 0
08/01/05 14:26:09 DDSet_Status: Silent: 0
08/01/05 14:26:09 DDSet_Status: ActionStart: 55
08/01/05 14:26:09 DDSet_Status: Cost: 0
08/01/05 14:26:09 DDSet_Status: WorkingDirectory:
08/01/05 14:26:09 DDSet_Status: Silent: 0
08/01/05 14:26:09 DDSet_Status: ActionStart: 55
08/01/05 14:26:09 DDSet_Status: Cost: 0
08/01/05 14:26:09 DDSet_Status: WorkingDirectory:
BIS registration data successfully updated.
08/01/05 14:26:11 DDSet_Status: Process returned 0
08/01/05 14:26:11 DDSet_Status: Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\Bisregedit.exe" "http://EAS4:8080/SCC/public/application/ServiceDefinition.aspx" EAS4 BisDB' returned 0
08/01/05 14:26:11 DDSet_CARetVal: 0
08/01/05 14:26:11 DDSet_Status: QuietExec returned 0
MSI (s) (A0:C4) [14:26:11:706]: Executing op: ActionStart(Name=CA_QuietExec,,)
08/01/05 14:26:11 DDSet_Exit: QuietExec ended
MSI (s) (A0:C4) [14:26:11:706]: Executing op: CustomActionSchedule(Action=CA_QuietExec,ActionType=17409,Source=BinaryData,Target=QuietExec,CustomActionData="C:\WINDOWS\system32\iisreset.exe" /Restart|0|0|0|0)
MSI (s) (A0:60) [14:26:11:721]: Invoking remote custom action. DLL: C:\WINDOWS\Installer\MSI19E.tmp, Entrypoint: QuietExec
08/01/05 14:26:11 DDSet_Status: LANGID: 1033
08/01/05 14:26:12 DDSet_Entry: QuietExec started
08/01/05 14:26:12 DDSet_Status: Commandline: "C:\WINDOWS\system32\iisreset.exe" /Restart
08/01/05 14:26:12 DDSet_Status: IgnoreExitCode: 0
08/01/05 14:26:12 DDSet_Status: Silent: 0
08/01/05 14:26:12 DDSet_Status: ActionStart: 0
08/01/05 14:26:12 DDSet_Status: Cost: 0
08/01/05 14:26:12 DDSet_Status: WorkingDirectory:
08/01/05 14:26:12 DDSet_Status: Silent: 0
08/01/05 14:26:12 DDSet_Status: ActionStart: 0
08/01/05 14:26:12 DDSet_Status: Cost: 0
08/01/05 14:26:12 DDSet_Status: WorkingDirectory:

Attempting stop...

Internet services successfully stopped

Attempting start...

Internet services successfully restarted

08/01/05 14:26:35 DDSet_Status: Process returned 0
08/01/05 14:26:35 DDSet_Status: Commandline '"C:\WINDOWS\system32\iisreset.exe" /Restart' returned 0
08/01/05 14:26:35 DDSet_CARetVal: 0
08/01/05 14:26:35 DDSet_Status: QuietExec returned 0
MSI (s) (A0:C4) [14:26:35:894]: Executing op: ActionStart(Name=CA_QuietExec,,)
08/01/05 14:26:35 DDSet_Exit: QuietExec ended
MSI (s) (A0:C4) [14:26:35:894]: Executing op: CustomActionSchedule(Action=CA_QuietExec,ActionType=17409,Source=BinaryData,Target=QuietExec,CustomActionData="C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\BisSubscribe.exe" /eventType ArtifactChangedEvent /userid HCNET\TFSSERVICE /deliveryType Soap /Address http://EAS4:8080/Currituck/BisService.asmx|0|0|0|0)
MSI (s) (A0:10) [14:26:35:910]: Invoking remote custom action. DLL: C:\WINDOWS\Installer\MSI19F.tmp, Entrypoint: QuietExec
08/01/05 14:26:36 DDSet_Status: LANGID: 1033
08/01/05 14:26:36 DDSet_Entry: QuietExec started
08/01/05 14:26:36 DDSet_Status: Commandline: "C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\BisSubscribe.exe" /eventType ArtifactChangedEvent /userid HCNET\TFSSERVICE /deliveryType Soap /Address http://EAS4:8080/Currituck/BisService.asmx
08/01/05 14:26:36 DDSet_Status: IgnoreExitCode: 0
08/01/05 14:26:36 DDSet_Status: Silent: 0
08/01/05 14:26:36 DDSet_Status: ActionStart: 0
08/01/05 14:26:36 DDSet_Status: Cost: 0
08/01/05 14:26:36 DDSet_Status: WorkingDirectory:
08/01/05 14:26:36 DDSet_Status: Silent: 0
08/01/05 14:26:36 DDSet_Status: ActionStart: 0
08/01/05 14:26:36 DDSet_Status: Cost: 0
08/01/05 14:26:36 DDSet_Status: WorkingDirectory:
Unable to connect to BIS service.
08/01/05 14:28:18 DDSet_Status: Process returned 1
08/01/05 14:28:18 DDSet_Error: The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\BisSubscribe.exe" /eventType ArtifactChangedEvent /userid HCNET\TFSSERVICE /deliveryType Soap /Address http://EAS4:8080/Currituck/BisService.asmx' returned non-zero value: 1.
MSI (s) (A0!7C) [14:34:23:892]: Product: Visual Studio 2005 Team Foundation App-Data Tier Beta 2 (English) -- Error 32000.The Commandline '"C:\Program Files\Microsoft Visual Studio 2005 Enterprise Server\BISIISDIR\sdk\bin\BisSubscribe.exe" /eventType ArtifactChangedEvent /userid HCNET\TFSSERVICE /deliveryType Soap /Address http://EAS4:8080/Currituck/BisService.asmx' returned non-zero value: 1.

I forgot what I did to make the ServiceDefinition.aspx work, too many tweaks from the web search, however no matter how I tried, the Retry on BisSubscribe.exe just can't get through. Any clues?

wlian at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...
# 6

It seems that the databases are timing out. You may want to try turning off any firewalls on the DT

BruceTaimanaMSFT at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...
# 7
Windows Firewall is turned off, BUT , the service is running. This is due to the instructions requiring us to turn on the service (unless im mistaken). Could it be something along the lines of a port being blocked maybe? or what else would block access to the databases?

BTW, We have moved to the July CTP and it seems it still seems to be occuring but the errors are a kind of disquised now. Now we only get a 32000 error with no context. the log file doesnt show the same error as above. However , we are getting access errors on the data tier server so it seems like you are correct in your analysis.

Thanks,
Bryan

BryanMiller at 2007-9-9 > top of Msdn Tech,Visual Studio Team System,Team Foundation Server - Setup...

Visual Studio Team System

Site Classified