Session is no longer valid

If you are having a problem using Vault, post a message here.

Moderator: SourceGear

Post Reply
jepthy
Posts: 6
Joined: Mon Dec 20, 2010 5:01 pm

Session is no longer valid

Post by jepthy » Sat Dec 14, 2013 9:53 pm

Hello,
We are running an older version of Vault 4.1.4. We have been running into an issue for a while where it gives the error "Session is no longer valid. Either the server restarted, or your session timed out. You may need to login again to re-authenticate.", but it has really gotten bad lately, where we cant get into the repository.

I am able to log into the admin website, and every so often it has me log out and then back in again. When I start the Vault Client on my machine it will log me in, I will choose a repository, then it will give me this message. I have tried this with both of our repositories, and there is no getting in.

I have gone through all of the online tips I could find. I reset my password, I reset the admin password, I went onto the server and made changes to the App Pool, all to no avail. I have even set the app pool to log to the Event Viewer when it is recycled, and there is no recycling going on.

I have generated a debug log file and it says the following:
----12/14/2013 7:44:07 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Login started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Beginning SQL transaction 42715336
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLoginInfo started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLoginInfo finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: LoginUser started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: LoginUser finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: AddSession started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: AddSession finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled SQL transaction 42715336 successfully committed.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Login
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Login finished.
----12/14/2013 7:44:07 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetUserOptions started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserOptions started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserOptions finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetUserOptions returned: Success
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetUserOptions finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetHistoryFavorites started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetHistoryFavorites started.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetHistoryFavorites finished.
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Success
----12/14/2013 7:44:07 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetHistoryFavorites finished.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetVersionInfo started.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetVersionInfo finished.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositories started.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetRepositories started.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetRepositories finished.
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetRepositories returned: Success
----12/14/2013 7:44:08 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositories finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositoryStructure started.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Getting repository Structure-> Rep ID: 3 Base: 0 Target: -1
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserRepositoryLastChange started.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserRepositoryLastChange finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo started.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 3358
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLastTreeSecurityChange started.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLastTreeSecurityChange finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo started.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultServiceAPI::GetRepositoryTreeDelta() UserID:3 RepID:3 Base:0 Target:3358 Calling VaultRepUtil.DiffRepTrees() - in-memory tree diff.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultServiceAPI::PrepareTreeDeltaForTransport() nNumDeltaNodes:2835 Base:0 Target:3358
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositoryStructure finished.
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetRepositoryStructure returned: Success
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultFileDownload starting
----12/14/2013 7:44:09 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetLatest wrote 0 bytes to the Response Stream
----12/14/2013 7:44:09 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Ending download process
----12/14/2013 7:44:09 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----12/14/2013 7:44:11 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Logout started.
----12/14/2013 7:44:11 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Logout
----12/14/2013 7:44:11 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Logout finished.
----12/14/2013 7:44:11 PM --()-- Beginning SQL transaction 6494098
----12/14/2013 7:44:11 PM --()-- DB: DeleteSession started.
----12/14/2013 7:44:11 PM --()-- DB: DeleteSession finished.
----12/14/2013 7:44:11 PM --()-- SQL transaction 6494098 successfully committed.
----12/14/2013 7:44:13 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Login started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: CountActiveUsers finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Beginning SQL transaction 35287174
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLoginInfo started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLoginInfo finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: LoginUser started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: LoginUser finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: AddSession started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: AddSession finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled SQL transaction 35287174 successfully committed.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Login
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Login finished.
----12/14/2013 7:44:13 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetUserOptions started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserOptions started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserOptions finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetUserOptions returned: Success
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetUserOptions finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetHistoryFavorites started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetHistoryFavorites started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetHistoryFavorites finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Success
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetHistoryFavorites finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetVersionInfo started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetVersionInfo finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositories started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetRepositories started.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetRepositories finished.
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetRepositories returned: Success
----12/14/2013 7:44:13 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositories finished.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositoryStructure started.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Getting repository Structure-> Rep ID: 3 Base: 0 Target: -1
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserRepositoryLastChange started.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetUserRepositoryLastChange finished.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo started.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled TreeManager: cache matches repository revision and folder security hasn't changed. Returning cached tree, revID 3358
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLastTreeSecurityChange started.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLastTreeSecurityChange finished.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo started.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled DB: GetLatestRepositoryInfo finished.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultServiceAPI::GetRepositoryTreeDelta() UserID:3 RepID:3 Base:0 Target:3358 Calling VaultRepUtil.DiffRepTrees() - in-memory tree diff.
----12/14/2013 7:44:14 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultServiceAPI::PrepareTreeDeltaForTransport() nNumDeltaNodes:2835 Base:0 Target:3358
----12/14/2013 7:44:15 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: GetRepositoryStructure finished.
----12/14/2013 7:44:15 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetRepositoryStructure returned: Success
----12/14/2013 7:44:15 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled VaultFileDownload starting
----12/14/2013 7:44:15 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled GetLatest wrote 0 bytes to the Response Stream
----12/14/2013 7:44:15 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Ending download process
----12/14/2013 7:44:15 PM --c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled AuthTokenHash matched, but couldn't get the userid
----12/14/2013 7:44:16 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Logout started.
----12/14/2013 7:44:16 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled Logout
----12/14/2013 7:44:16 PM bhaynie--c-98-202-25-76.hsd1.ut.comcast.net(98.202.25.76)--SSL Disabled ASMX: Logout finished.
----12/14/2013 7:44:16 PM --()-- Beginning SQL transaction 49382823
----12/14/2013 7:44:16 PM --()-- DB: DeleteSession started.
----12/14/2013 7:44:16 PM --()-- DB: DeleteSession finished.
----12/14/2013 7:44:16 PM --()-- SQL transaction 49382823 successfully committed.
I don't see anything else in the event logs.
Any idea what might be going on?
Top
lbauer
Posts: 9736
Joined: Tue Dec 16, 2003 1:25 pm
Location: SourceGear

Re: Session is no longer valid

Post by lbauer » Sun Dec 15, 2013 12:23 pm

How long has the problem been going on?

What operating system is Vault 4.1.4 on?

We'd like to see more of the Vault Server log, perhaps from the last few days.

Send the logs zipped up to support at sourcegear.com, Attn: Linda. Please include a link to this forum post.
Linda Bauer
SourceGear
Technical Support Manager
Top
jepthy
Posts: 6
Joined: Mon Dec 20, 2010 5:01 pm

Re: Session is no longer valid

Post by jepthy » Tue Dec 17, 2013 8:19 am

I have emailed the requested information.
Top
lbauer
Posts: 9736
Joined: Tue Dec 16, 2003 1:25 pm
Location: SourceGear

Re: Session is no longer valid

Post by lbauer » Tue Dec 17, 2013 11:27 am

I got the logs and am following up.

HS:229287
Linda Bauer
SourceGear
Technical Support Manager
Top
korazy
Posts: 2
Joined: Thu Aug 20, 2009 10:18 am

Re: Session is no longer valid

Post by korazy » Wed Jun 25, 2014 9:49 am

Was there a solution to this? I am experiencing the same problem.
Top
Beth
Posts: 8550
Joined: Wed Jun 21, 2006 8:24 pm
Location: SourceGear
Contact:
Contact Beth

Re: Session is no longer valid

Post by Beth » Wed Jun 25, 2014 1:50 pm

IIS was restarting a lot and sessions were being lost when that happened. Make sure no application pool that connects to either Vault or the website Vault is installed to has recycling turned on.
Beth Kieler
SourceGear Technical Support
Top
Post Reply

Return to “Support (Vault)”