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: 1a5daa3a0231a0fbba4f14db7ec463cf99d7768e 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

[TylerLeonhardt]

It appears again

what is it that appears? Can you send a screenshot?

[TylerLeonhardt]

@jyxjjj I don't think you have the important DEBUG logs turned on. Can you confirm:

• 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...
• Share those logs again

[jyxjjj]

@jyxjjj I don't think you have the important DEBUG logs turned on. Can you confirm:

• 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...

• Share those logs again

Yes, I was confused too, i am sure I turned trace log on, but it only shows info, whatever i restart it how many times.

[TylerLeonhardt]

@jyxjjj if you do it right, you should see a Check mark next to Trace after restarting VS Code:

[jyxjjj]

I didn't see any trace log even i can see this check mark. i am sure those logs above are copied after here has a check mark.

[TylerLeonhardt]

@sandy081 do you know why might my trace logs statements aren't showing up in the Output even though the user has Trace enabled?

[vscodenpa]

This issue has been closed automatically because it needs more information and has not had recent activity. See also our issue reporting guidelines.

Happy Coding!

[jyxjjj]

After once system upgrade, i found a new logic:

1. When VSCode started, Microsoft Authentication Plugin has not been activated, (i don't know if it is a plugin or feature.) Then the blue light was there on settings(people) icon.
2. The log output window has no Microsoft Authentication Channel.
3. Last time, "got 1 session".
4. Click the login menu, now appeared "got 2 sessions".

[jyxjjj]

Before 4, i fully quit VSCode for 5 times and restart, step 1, 2 still here. Before system upgrade, (maybe due to reboot?):

1. Connect to a remote environment via SSH.
2. Close WiFi(by sleep the mac).
3. Recover the mac network.
4. Didn't click the "Reload Window" Button. (DON'T CLICK)
5. Reboot via System Upgrade.

And, system upgrade is maybe one of the way to reproduce it, i am not sure if there is other way to.

[sandy081]

@TylerLeonhardt Please check what log level is configured for your extension, may be it is overriden?

[jyxjjj]

I think i found how to reproduce? But why it didn't activate automatically?

[jyxjjj]

I totally found the trace log on my friend's installation. I think it has no difference with i submitted above. Because after [info] Got 1 sessions for all scopes... it doesn't give any more.

Read stored session
Queued refreshing token
Refreshing token
Attempting to parse token response.
Token response parsed successfully.
Setting refresh timeout for 2801333.333333333 milliseconds
Setting token
Token refresh success
Stored token

It seems like a correct thing.

Then i found this screenshot, It seems logged successfully, but the blue light didn't disappear. Oh yeah the screenshot is not completely , there was a GitHub account above the first line.

[TylerLeonhardt]

Hi @jyxjjj sorry for the delay here... have you still been seeing this issue?

[jyxjjj]

Hi @jyxjjj sorry for the delay here... have you still been seeing this issue?

@TylerLeonhardt Yes, I have even give up🤷‍♂️

[TylerLeonhardt]

Sorry to hear that, @jyxjjj. I would love to get to the bottom of this if you are willing to help me out. I'd like to understand if you're still seeing the Microsoft Account not activating or if you're having the issue with the ever increasing number of sessions.

[jyxjjj]

@TylerLeonhardt yeah, and still due to the plugin did not automatic load.

[jyxjjj]

It is enabled but still not loaded automatically. Could you please check the difference between it and GitHub's one?

And when i click Sign in to Sync Settings (1), #issuecomment-1837756557 continues.

I don't know how to continue to check this issue at all.