Rebind Projects and Solution doesn't complete

[kadorken]

I have tried twice to run the Rebind Projects and Solution procedure to change our bindings from classic to enhanced. The second time I left it running from 15:00 to 7:30 the next morning, but it still hadn't come back with a list of projects elegible to convert. It gets past the "while the server searches for projects and solutions" phase, but not the next phase (examining the projects and solutions). We are on 5.1.2 (19281)

Our database statistics are:

Revisions: 213517
Folders: 3890 (+2513 deleted)
Files: 42851 (+27601 deleted)
Tree Size: 8.14 GB
Disk Space Needed: 16.28 GB
Database Size: 7.8 GB

Is our repository too large to do this process?

We have many solutions that have SHARED projects (shared in the vault sense). If we convert one Solution to enhanced, that shared project will be changed. Then all the other solutions that have the same SHARED project have different binding than the solution file referencing it.
What is the recommended procedure to deal with this situation? (Remove ALL bindings from ALL projects first, then rebind ALL ?) - part of the problem is locating all the solutions which is why I was trying the Admin procedure to do it all.

Suggestions?

[kadorken]

I turned on Logging and restarted the procedure: it didn't take long (note: Puma is the machine that initiated the procedure). The log:

System Started
Version 5.1.2.19281
Cache Level = 1
DataBase Buffer Size (KB) = 256
LogFile Path = C:\Windows\Temp\sgvault
Log Level = Debug
Archive Log = Weekly
ReverseDNS Lookup = True
Maximum HTTP Request Length = 102400
Overwrite Log on Startup = False
Session Timeout = 10080
SGVault Working Directory = C:\Windows\Temp
SGVault Server URL =
Identity = IIS APPPOOL\VaultAppPool
----6/15/2012 7:28:40 AM --()-- DB: GetRepositories started.
----6/15/2012 7:28:40 AM --()-- DB: GetRepositories finished.
----6/15/2012 7:28:40 AM --()-- Beginning SQL transaction 7887192
----6/15/2012 7:28:40 AM --()-- DB: CleanLoginAttempts started.
----6/15/2012 7:28:40 AM --()-- DB: CleanLoginAttempts finished.
----6/15/2012 7:28:40 AM --()-- SQL transaction 7887192 successfully committed.
----6/15/2012 7:28:40 AM --()-- Beginning SQL transaction 23390668
----6/15/2012 7:28:40 AM --()-- DB: CleanSessions started.
----6/15/2012 7:28:40 AM --()-- DB: CleanSessions finished.
----6/15/2012 7:28:40 AM --()-- SQL transaction 23390668 successfully committed.
----6/15/2012 7:28:41 AM --puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetSerialNums started.
----6/15/2012 7:28:41 AM --puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetSerialNums finished.
----6/15/2012 7:28:43 AM --whoops.thinkage.ca(192.102.11.96)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Beginning SQL transaction 66955311
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLoginInfo started.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Created GetLoginInfo stored procedure.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Running GetLoginInfo stored procedure.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Reading results from GetLoginInfo stored procedure.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Setting success code after calling GetLoginInfo stored procedure.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLoginInfo finished.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: LoginUser started.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: LoginUser finished.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: AddSession started.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: AddSession finished.
----6/15/2012 7:28:51 AM Admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled SQL transaction 66955311 successfully committed.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Login
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserRepositoryAdmin started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserRepositoryAdmin finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetActiveDirectoryDomains started.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetActiveDirectoryDomains finished.
----6/15/2012 7:28:51 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled WEB: Finshed loading Logging
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories started.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories finished.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin started.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: IsUserGlobalAdmin finished.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories started.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories finished.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetEmailSettings started.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetEmailSettings finished.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: Filling empty cache with current tree.
----6/15/2012 7:28:57 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetRepositoryTree started.
----6/15/2012 7:29:05 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetRepositoryTree finished.
----6/15/2012 7:29:05 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:29:05 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:29:05 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:29:05 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled WEB: Finshed loading Rebind
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories started.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetUserRepositories finished.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile started.
----6/15/2012 7:29:33 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile finished.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile started.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile finished.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:29:34 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.

<EDIT remove all entries from 7:29:34 AM to 7:30:30 AM - all GetDeltaChainForFullFile>

end log when things go wrong appears as:

----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile started.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile finished.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo started.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 220029
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile started.
----6/15/2012 7:30:30 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled DB: GetDeltaChainForFullFile finished.
----6/15/2012 7:30:44 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled WEB: Finshed loading Rebind
----6/15/2012 7:32:30 AM --tetron.thinkage.ca(192.102.11.41)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:33:27 AM --192.102.11.37(192.102.11.37)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:36:24 AM --tetron.thinkage.ca(192.102.11.41)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:38:31 AM --192.102.11.37(192.102.11.37)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:38:36 AM --whoops.thinkage.ca(192.102.11.96)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----6/15/2012 7:40:46 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled Uncaught Exception: Exception of type 'System.Web.HttpUnhandledException' was thrown.
Inner Exception: The URL-encoded form data is not valid.
Inner Exception: Operation is not valid due to the current state of the object.
----6/15/2012 7:40:46 AM admin--puma.thinkage.ca(192.102.11.113)--SSL Disabled at System.Web.UI.Page.HandleError(Exception e)
at System.Web.UI.Page.ProcessRequestMain(Boolean includeStagesBeforeAsyncPoint, Boolean includeStagesAfterAsyncPoint)
at System.Web.UI.Page.ProcessRequest(Boolean includeStagesBeforeAsyncPoint, Boolean includeStagesAfterAsyncPoint)
at System.Web.UI.Page.ProcessRequest()
at System.Web.UI.Page.ProcessRequest(HttpContext context)
at ASP.admin_rebind_aspx.ProcessRequest(HttpContext context)
at System.Web.HttpApplication.CallHandlerExecutionStep.System.Web.HttpApplication.IExecutionStep.Execute()
at System.Web.HttpApplication.ExecuteStep(IExecutionStep step, Boolean& completedSynchronously)
Inner Exeption Stack Trace: at System.Web.HttpRequest.FillInFormCollection()
at System.Web.HttpRequest.get_Form()
at System.Web.HttpRequest.get_HasForm()
at System.Web.UI.Page.GetCollectionBasedOnMethod(Boolean dontReturnNull)
at System.Web.UI.Page.DeterminePostBackMode()
at System.Web.UI.Page.ProcessRequestMain(Boolean includeStagesBeforeAsyncPoint, Boolean includeStagesAfterAsyncPoint)
Inner Exeption Stack Trace: at System.Web.HttpValueCollection.ThrowIfMaxHttpCollectionKeysExceeded()
at System.Web.HttpValueCollection.FillFromEncodedBytes(Byte[] bytes, Encoding encoding)
at System.Web.HttpRequest.FillInFormCollection()

[kadorken]

I also see another temporary directory in windows\temp with timestamps of 7:30am (VaultTemp.GUID\...)
The structure under this directory matches the repository and it appears to be the working copy of all the .sln and .csproj files (with directory structure to match the respository)

If you need to examine the contents of this, let me know.

[Beth]

Can you tell me the size of your sgvault.mdf and sgvault_log.ldf on disk?

Was this a recent VSS hand-off or a VSS import?

Were you using Vault successfully to access these projects before deciding to change which client to use for your Visual Studio integration?

[kadorken]

Mode LastWriteTime Length Name
---- ------------- ------ ----
-a--- 2012/06/15 9:42 AM 3342336 sgmaster.mdf
-a--- 2012/06/15 9:42 AM 36569088 sgmaster_1.LDF
-a--- 2012/06/15 9:42 AM 10714087424 sgvault.mdf
-a--- 2012/06/15 9:42 AM 1662451712 sgvault_1.LDF

No this wasn't a recent handoff; we moved the VSS files back in 2003 or so. This database has been around for a LONG time. We have been using the classic binding for a long time and putting up with it's stupidity. I converted one of our BRANCH releases to the Enhanced client (no shared projects there) and was happier with the behavior so I thought perhaps we should switch ALL the working projects to it to avoid dealing with different binding scenarios.
We have been using Vault fine (except for the constant having to 'fix' the source control bindings, sometimes by hand in the .sln files to keep sane).

[Beth]

I'm wondering if you're not actually running into an issue with how much data is being submitted via the web form. See this forum thread: http://support.sourcegear.com/viewtopic.php?f=5&t=18732.

The error of "Operation is not valid due to the current state of the object" is the same. Try the suggestion in that thread and let me know your results.

[kadorken]

I set the parameter to 6000 and this time I got to the page listing the projects .sln, and project files.

Thanks.

But, the displayed page is displaying in internet explorer without any of the error text associated with each node.
(I know there are errors because I selected ALL the displayed text and pasted it as TEXT into an email message and I saw the error text. Trying to PASTE the entire selection with FORMATTING into Word or Outlook as HTML kills them).

[Beth]

What version of Internet Explorer do you have?

[kadorken]

Sorry, I thought I put the version in. Internet Explorer 9.0.8112.16421
Update Versions: 9.0.7

I'll try with Chrome 17.0.963.46 m

Same behavior.

[kadorken]

I think I know what is happening. Sorry, the output is so wide, that it was hidden to the extreme right and I didn't notice the scroll bar. I stretched the browser window across both 24 inch monitors and the error text (in red) appeared.

Arg. Thanks for you help.

[Beth]

Thank you for the update.