Auto re-login needs a very long time

[jyxjjj]

Does this issue occur when all extensions are disabled?: Yes

• VS Code Version:

Version: 1.84.2
Commit: 1a5daa3
Date: 2023-11-09T10:52:57.054Z
Electron: 25.9.2
ElectronBuildId: 24603566
Chromium: 114.0.5735.289
Node.js: 18.15.0
V8: 11.4.183.29-electron.0
OS: Darwin arm64 23.1.0

• OS Version:

Apple M1 Max 64 GB
Version 14.1 (23B74)

Steps to Reproduce:

1. Restart vscode with all windows closed but dock light still on
2. Or restart vscode with all process quited

The GitHub Account will auto re-login in 1 second, regardless of whether I'm using (enabled or disabled) Copilot.
The Microsoft Account needs maybe 30 seconds to be active, before that, there is always a blue light on settings icon to remind me login to sync settings.

[jyxjjj]

I am sure the network is good because if i manually re-login, microsoft web page is very fast.
Full HTTP workflow:

1. vscode Request an UUID
2. Microsoft webpage opened in Chrome
3. Chrome Download HTML from server
4. Chrome Render HTML
5. Login
6. Chrome call vscode

In these steps, except the 1st, others are very fast.

[jyxjjj]

I think we can do an alt way: remove the blue light on settings icon before it is really failed.

[TylerLeonhardt]

Can you follow these steps so that we can get more information on what's going on?

• Open the Command Palette
• Run the Developer: Set Log Level... command
• To the right of Trace, click the double check mark button. ✔️ ✔️ (enables trace logging as the default)
• Wait for the issue to happen...
• Open the Output pane (ctrl+shift+u I think on Windows)
• drop in this issue he contents of the following:

  • Microsoft Authentication Output channel

  • GitHub Authentication Output channel

  • Log (Settings Sync) Output channel

  • Log (Main) Output channel

• Open the Command Palette
• Run the Developer: Set Log Level... command
• To the right of Info, click the double check mark button. ✔️ ✔️ (to set the default back to info)

[jyxjjj]

GitHub Authentication:

2023-12-04 10:44:30.671 [info] Reading sessions from keychain...
2023-12-04 10:44:30.671 [info] Getting sessions for all scopes...
2023-12-04 10:44:30.671 [info] Getting sessions for read:user...
2023-12-04 10:44:30.671 [info] Getting sessions for user:email...
2023-12-04 10:44:30.671 [info] Getting sessions for all scopes...
2023-12-04 10:44:30.698 [info] Got stored sessions!
2023-12-04 10:44:30.698 [info] Got 1 verified sessions.
2023-12-04 10:44:30.698 [info] Got 1 sessions for ...
2023-12-04 10:44:30.698 [info] Got 1 sessions for read:user...
2023-12-04 10:44:30.698 [info] Got 0 sessions for user:email...
2023-12-04 10:44:30.698 [info] Got 1 sessions for ...
2023-12-04 10:44:30.777 [info] Getting sessions for read:user...
2023-12-04 10:44:30.778 [info] Got 1 sessions for read:user...
2023-12-04 10:44:31.756 [info] Getting sessions for user:email...
2023-12-04 10:44:31.756 [info] Got 0 sessions for user:email...
2023-12-04 10:44:31.757 [info] Getting sessions for read:user...
2023-12-04 10:44:31.757 [info] Got 1 sessions for read:user...
2023-12-04 10:44:31.758 [info] Getting sessions for user:email...
2023-12-04 10:44:31.759 [info] Got 0 sessions for user:email...
2023-12-04 10:44:31.759 [info] Getting sessions for read:user...
2023-12-04 10:44:31.759 [info] Got 1 sessions for read:user...

Microsoft Authentication:
Cannot find this channel
Log (Settings Sync):

2023-12-04 10:44:29.681 [info] Using settings sync service https://vscode-sync.trafficmanager.net/
2023-12-04 10:44:29.681 [info] Auto Sync is enabled.
2023-12-04 10:44:29.681 [info] Auto Sync: Suspended until auth token is available.

Log (Main):

2023-12-04 10:44:29.270 [info] update#setState idle
2023-12-04 10:44:59.188 [info] update#setState checking for updates
2023-12-04 10:45:00.146 [info] update#setState idle

[jyxjjj]

Only if i click manually login to sync, Microsoft Authentication channel will appear.
And the 1st floor of this issue, i said:

The Microsoft Account needs maybe 30 seconds to be active, before that, there is always a blue light on settings icon to remind me login to sync settings.

But actually, it is the best appearance, usually, it always needs my manual login.

[jyxjjj]

After i manually login:

2023-12-04 10:48:09.931 [info] Getting sessions for all scopes...
2023-12-04 10:48:09.931 [info] Got 17 sessions for all scopes...
2023-12-04 10:48:09.933 [info] [email offline_access openid profile] Creating session
2023-12-04 10:50:04.151 [info] [email offline_access openid profile] '{{SOME_UUID_FOR_PRIVACY}}/1:live.com:{{SOME_ID_FOR_PRIVACY}}/{{SOME_UUID_FOR_PRIVACY}}' session successfully created!
2023-12-04 10:50:04.153 [info] [email offline_access openid profile] Getting sessions
2023-12-04 10:50:04.153 [info] [email offline_access openid profile] Got 18 sessions

[jyxjjj]

Got {{nums}} sessions Nums become more and more after each time i restart whole vscode.

I may be can understand why (in the perspective of an end user), then, can i manually edit the saved auth file to edit which session to keep and which to forget?

[jyxjjj]

The left window created before the right window

The Left is what i said:

The Microsoft Account needs maybe 30 seconds to be active, before that, there is always a blue light on settings icon to remind me login to sync settings.

The Right is what i said:

But actually, it is the best appearance, usually, it always needs my manual login.

Same with #issuecomment-1837772841 , two window of the same process, it still increasing

[TylerLeonhardt]

The fact that you have 20 sessions is so wrong... though it's not clear how you got into this state. Can you please sign out of your Microsoft via the account menu (the person icon) and then reload the window and then include these logs again?

[jyxjjj]

After sign-out, it successfully fixed.

2023-12-05 15:09:07.403 [info] Getting sessions for all scopes...
2023-12-05 15:09:07.403 [info] Got 1 sessions for all scopes...
2023-12-05 15:09:07.404 [info] [email offline_access openid profile] Getting sessions
2023-12-05 15:09:07.404 [info] [email offline_access openid profile] Got 1 sessions

But as you say, i don't know how i entered this state too. Now i cannot reproduce it, how can you fix it as an issue? Only let people sign-out and re-login manually?

[jyxjjj]

Some more addons:
I installed OneDrive, 365 Suites and kept login.
With no Apple keyChain Passwords and Fully using all parts of Google Chrome.
Kept login to Microsoft Web in Chrome with "remember me".
After sign-out in VSCode, OneDrive logged out too.

[jyxjjj]

After login back in OneDrive and 365 Suites, VSCode doesn't reproduce this issue too.

[jyxjjj]

It appears again, then i manually sign out to fix it:

2023-12-07 17:01:43.274 [info] Getting sessions for all scopes...
2023-12-07 17:01:43.274 [info] Got 1 sessions for all scopes...
2023-12-07 17:01:43.276 [info] [email offline_access openid profile] Getting sessions
2023-12-07 17:01:43.276 [info] [email offline_access openid profile] Got 1 sessions
2023-12-08 09:21:43.245 [info] Getting sessions for all scopes...
2023-12-08 09:21:43.245 [info] Got 1 sessions for all scopes...
2023-12-08 09:21:43.245 [info] [email offline_access openid profile] Getting sessions
2023-12-08 09:21:43.245 [info] [email offline_access openid profile] Got 1 sessions
2023-12-08 09:56:41.594 [info] [email offline_access openid profile] Creating session
2023-12-08 09:56:51.044 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' session successfully created!
2023-12-08 09:56:51.048 [info] [email offline_access openid profile] Getting sessions
2023-12-08 09:56:51.048 [info] [email offline_access openid profile] Got 2 sessions
2023-12-08 09:57:18.598 [info] Getting sessions for all scopes...
2023-12-08 09:57:18.598 [info] Got 2 sessions for all scopes...
2023-12-08 09:57:21.243 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' Logging out of session
2023-12-08 09:57:21.243 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' Logged out of session successfully!
2023-12-08 09:57:21.243 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' Logging out of session
2023-12-08 09:57:21.263 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' Logged out of session successfully!
2023-12-08 09:57:21.263 [info] [email offline_access openid profile] Getting sessions
2023-12-08 09:57:21.263 [info] [email offline_access openid profile] Got 0 sessions
2023-12-08 09:57:24.200 [info] [email offline_access openid profile] Creating session
2023-12-08 09:57:30.362 [info] [email offline_access openid profile] '{{HIDDEN_FOR_PRIVACY}}/1:live.com:{{HIDDEN_FOR_PRIVACY}}/{{HIDDEN_FOR_PRIVACY}}' session successfully created!
2023-12-08 09:57:30.399 [info] [email offline_access openid profile] Getting sessions
2023-12-08 09:57:30.399 [info] [email offline_access openid profile] Got 1 sessions