Copy Permalink fails frequently, seemingly on the first copy of the day #5185
Description
Typical repro (I haven't nailed it down entirely):
- Have VS Code open
- Put PC to sleep in afternoon
- Wake it up in the morning
- Select a code section
GitHub Issues: Copy GitHub Permalink, 🐛 fails sometimes
I have been hitting this for literally years and it's one of my biggest papercuts, but since it's such a pain to reproduce I never end up following it up after mentioning it. Since it's so annoying I think it's worth spending a couple of hours in a debug session together, restarting my computer if that's what it takes.
Trace logs:
GitHub Pull Request:
2023-08-18 05:15:54.267 [debug] GitHubRepository> Fetch pull request 190609 - enter
2023-08-18 05:15:54.269 [debug] RateLimit> Extension rate limit remaining: 140, PullRequest
2023-08-18 05:15:54.759 [debug] RateLimit> GraphQL Rate limit remaining: 4740, PullRequest
2023-08-18 05:15:54.760 [debug] GitHubRepository> Fetch pull request 190609 - done
2023-08-18 05:15:54.762 [debug] RateLimit> Extension rate limit remaining: 140, GetBranch
2023-08-18 05:15:55.025 [debug] RateLimit> GraphQL Rate limit remaining: 4739, GetBranch
2023-08-18 05:15:55.025 [debug] PullRequestModel> Fetch file changes, base, head and merge base of PR #190609 - enter
2023-08-18 05:15:55.025 [debug] IssueModel> Fetch viewers latest review commit
2023-08-18 05:15:55.026 [debug] RateLimit> Extension rate limit remaining: 140, LatestReviewCommit
2023-08-18 05:15:55.399 [debug] RateLimit> GraphQL Rate limit remaining: 4738, LatestReviewCommit
2023-08-18 05:15:55.666 [debug] RateLimit> Extension rate limit remaining: 140, PullRequestComments
2023-08-18 05:15:55.666 [debug] RateLimit> Extension rate limit remaining: 139, PullRequestComments
2023-08-18 05:15:55.666 [debug] RateLimit> Extension rate limit remaining: 138, PullRequestFiles
2023-08-18 05:15:55.955 [debug] RateLimit> GraphQL Rate limit remaining: 4736, PullRequestComments
2023-08-18 05:15:55.955 [debug] RateLimit> GraphQL Rate limit remaining: 4736, PullRequestComments
2023-08-18 05:15:56.046 [debug] RateLimit> GraphQL Rate limit remaining: 4737, PullRequestFiles
2023-08-18 05:15:56.048 [debug] RateLimit> Extension rate limit remaining: 140, GetPendingReviewId
2023-08-18 05:15:56.369 [debug] RateLimit> GraphQL Rate limit remaining: 4735, GetPendingReviewId
2023-08-18 05:16:31.516 [debug] ReviewCommentController> No commenting ranges: File does not match any of the files in the review.
2023-08-18 05:16:31.516 [debug] ReviewCommentController> Providing 0 commenting ranges for vscode.d.ts.
2023-08-18 05:17:22.559 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:17:22.559 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:17:22.560 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:17:22.560 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:17:57.233 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:17:57.233 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:17:57.233 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:17:57.233 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:18:04.210 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:18:04.210 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:18:04.211 [debug] GitHubRepository> Fetch metadata - enter
2023-08-18 05:18:04.211 [debug] GitHubRepository> Fetch metadata microsoft/vscode - done
2023-08-18 05:20:57.135 [debug] GitHubRepository> Fetch pull request 190609 - enter
2023-08-18 05:20:57.135 [debug] RateLimit> Extension rate limit remaining: 140, PullRequest
2023-08-18 05:20:57.626 [debug] RateLimit> GraphQL Rate limit remaining: 4734, PullRequest
2023-08-18 05:20:57.627 [debug] GitHubRepository> Fetch pull request 190609 - done
2023-08-18 05:20:57.628 [debug] RateLimit> Extension rate limit remaining: 140, GetBranch
2023-08-18 05:20:57.933 [debug] RateLimit> GraphQL Rate limit remaining: 4733, GetBranch
2023-08-18 05:20:57.933 [debug] PullRequestModel> Fetch file changes, base, head and merge base of PR #190609 - enter
2023-08-18 05:20:57.933 [debug] IssueModel> Fetch viewers latest review commit
2023-08-18 05:20:57.933 [debug] RateLimit> Extension rate limit remaining: 140, LatestReviewCommit
2023-08-18 05:20:58.235 [debug] RateLimit> GraphQL Rate limit remaining: 4732, LatestReviewCommit
2023-08-18 05:20:58.518 [debug] RateLimit> Extension rate limit remaining: 140, PullRequestComments
2023-08-18 05:20:58.518 [debug] RateLimit> Extension rate limit remaining: 139, PullRequestComments
2023-08-18 05:20:58.518 [debug] RateLimit> Extension rate limit remaining: 138, PullRequestFiles
2023-08-18 05:20:58.795 [debug] RateLimit> GraphQL Rate limit remaining: 4730, PullRequestComments
2023-08-18 05:20:58.796 [debug] RateLimit> GraphQL Rate limit remaining: 4730, PullRequestComments
2023-08-18 05:20:58.799 [debug] RateLimit> GraphQL Rate limit remaining: 4731, PullRequestFiles
2023-08-18 05:20:58.802 [debug] RateLimit> Extension rate limit remaining: 140, GetPendingReviewId
2023-08-18 05:20:59.114 [debug] RateLimit> GraphQL Rate limit remaining: 4729, GetPendingReviewId
Git:
2023-08-18 05:15:53.541 [trace] Operation start: Config (blocking: false, readOnly: false; retry: false; showProgress: true)
2023-08-18 05:15:53.579 [info] > git config --local branch.tyriar/162950_4.github-pr-owner-number [39ms]
2023-08-18 05:15:53.623 [info] > git config --get commit.template [40ms]
2023-08-18 05:15:54.161 [info] > git for-each-ref --format=%(refname)%00%(upstream:short)%00%(objectname)%00%(upstream:track)%00%(upstream:remotename)%00%(upstream:remoteref) --ignore-case refs/heads/tyriar/162950_4 refs/remotes/tyriar/162950_4 [574ms]
2023-08-18 05:15:54.263 [info] > git status -z -uall [98ms]
2023-08-18 05:15:54.267 [trace] Operation end: Config (blocking: false, readOnly: false; retry: false; showProgress: true)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.664 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.737 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/api/browser/mainThreadTerminalService.ts [108ms]
2023-08-18 05:15:55.751 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/api/common/extHost.api.impl.ts [113ms]
2023-08-18 05:15:55.754 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/api/common/extHostTypes.ts [113ms]
2023-08-18 05:15:55.762 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/contrib/terminalContrib/quickFix/browser/quickFix.ts [117ms]
2023-08-18 05:15:55.768 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/contrib/terminalContrib/quickFix/browser/quickFixAddon.ts [119ms]
2023-08-18 05:15:55.774 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vscode-dts/vscode.proposed.terminalQuickFixProvider.d.ts [110ms]
2023-08-18 05:15:55.778 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/contrib/terminalContrib/quickFix/browser/terminalQuickFixBuiltinActions.ts [125ms]
2023-08-18 05:15:55.782 [info] > git show --textconv 719a6a6cdfe132852ab26434e5e80fae16a44941:src/vs/workbench/contrib/terminalContrib/quickFix/test/browser/quickFixAddon.test.ts [125ms]
2023-08-18 05:15:55.855 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/api/browser/ [114ms]
2023-08-18 05:15:55.855 [info] fatal: not a tree object
2023-08-18 05:15:55.856 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.866 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/api/common/ [112ms]
2023-08-18 05:15:55.866 [info] fatal: not a tree object
2023-08-18 05:15:55.866 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.868 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/api/common/ [111ms]
2023-08-18 05:15:55.868 [info] fatal: not a tree object
2023-08-18 05:15:55.869 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.880 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vscode-dts/ [103ms]
2023-08-18 05:15:55.880 [info] fatal: not a tree object
2023-08-18 05:15:55.881 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.881 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/contrib/terminalContrib/quickFix/browser/ [99ms]
2023-08-18 05:15:55.881 [info] fatal: not a tree object
2023-08-18 05:15:55.881 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.881 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/contrib/terminalContrib/quickFix/browser/ [109ms]
2023-08-18 05:15:55.881 [info] fatal: not a tree object
2023-08-18 05:15:55.881 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.882 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/contrib/terminalContrib/quickFix/browser/ [114ms]
2023-08-18 05:15:55.882 [info] fatal: not a tree object
2023-08-18 05:15:55.882 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:15:55.889 [info] > git ls-tree -l 719a6a6cdfe132852ab26434e5e80fae16a44941 -- src/vs/workbench/contrib/terminalContrib/quickFix/test/browser/ [103ms]
2023-08-18 05:15:55.889 [info] fatal: not a tree object
2023-08-18 05:15:55.889 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:31.399 [trace] [svte] Scan visible text editors. autoRepositoryDetection=true
2023-08-18 05:16:31.667 [trace] [svte] Scan visible text editors. autoRepositoryDetection=true
2023-08-18 05:16:31.668 [trace] [svte] Repository for editor resource c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts already exists: c:\Github\microsoft\vscode
2023-08-18 05:16:31.791 [trace] Operation start: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:31.801 [trace] Operation start: GetObjectDetails (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:31.906 [info] > git ls-files --stage -- C:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts [104ms]
2023-08-18 05:16:31.924 [trace] Operation start: CheckIgnore (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:31.933 [info] > git show --textconv :src/vscode-dts/vscode.d.ts [141ms]
2023-08-18 05:16:31.933 [trace] Operation end: Show (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:31.992 [info] > git check-ignore -v -z --stdin [67ms]
2023-08-18 05:16:31.992 [trace] Operation end: CheckIgnore (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:16:32.008 [info] > git cat-file -s db7d29f3b1b07f33a0869bc0e9c150d3525c7c8e [96ms]
2023-08-18 05:16:32.008 [trace] Operation end: GetObjectDetails (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:17:19.370 [debug] [wt] Change: c:\Github\microsoft\vscode\.vscode\shared.code-snippets
2023-08-18 05:17:20.377 [trace] Operation start: Status (blocking: false, readOnly: false; retry: false; showProgress: true)
2023-08-18 05:17:20.419 [info] > git config --get commit.template [36ms]
2023-08-18 05:17:20.988 [info] > git for-each-ref --format=%(refname)%00%(upstream:short)%00%(objectname)%00%(upstream:track)%00%(upstream:remotename)%00%(upstream:remoteref) --ignore-case refs/heads/tyriar/162950_4 refs/remotes/tyriar/162950_4 [600ms]
2023-08-18 05:17:21.067 [trace] Operation start: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:21.089 [info] > git status -z -uall [93ms]
2023-08-18 05:17:21.091 [trace] Operation end: Status (blocking: false, readOnly: false; retry: false; showProgress: true)
2023-08-18 05:17:21.816 [info] > git log -n1 --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z -- c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts [748ms]
2023-08-18 05:17:21.817 [trace] Operation end: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:22.559 [info] > git show -s --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z 85de2f21c63608e8bbc5c2347fe927b00c8300bf [738ms]
2023-08-18 05:17:22.565 [trace] Operation start: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:22.565 [trace] Operation start: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:17:22.674 [info] > git for-each-ref --count=1 --sort -committerdate --format %(refname) %(objectname) %(*objectname) refs/remotes/origin/tyriar/162950_4 --contains 85de2f21c63608e8bbc5c2347fe927b00c8300bf [109ms]
2023-08-18 05:17:22.675 [trace] Operation end: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:17:22.675 [trace] Operation end: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:55.700 [trace] Operation start: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:56.467 [info] > git log -n1 --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z -- c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts [768ms]
2023-08-18 05:17:56.468 [trace] Operation end: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:57.232 [info] > git show -s --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z 85de2f21c63608e8bbc5c2347fe927b00c8300bf [761ms]
2023-08-18 05:17:57.237 [trace] Operation start: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:17:57.237 [trace] Operation start: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:17:57.360 [info] > git for-each-ref --count=1 --sort -committerdate --format %(refname) %(objectname) %(*objectname) refs/remotes/origin/tyriar/162950_4 --contains 85de2f21c63608e8bbc5c2347fe927b00c8300bf [122ms]
2023-08-18 05:17:57.360 [trace] Operation end: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:17:57.360 [trace] Operation end: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:18:02.699 [trace] Operation start: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:18:03.445 [info] > git log -n1 --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z -- c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts [747ms]
2023-08-18 05:18:03.446 [trace] Operation end: Log (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:18:04.210 [info] > git show -s --format=%H%n%aN%n%aE%n%at%n%ct%n%P%n%D%n%B -z 85de2f21c63608e8bbc5c2347fe927b00c8300bf [759ms]
2023-08-18 05:18:04.215 [trace] Operation start: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:18:04.216 [trace] Operation start: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:18:04.337 [info] > git for-each-ref --count=1 --sort -committerdate --format %(refname) %(objectname) %(*objectname) refs/remotes/origin/tyriar/162950_4 --contains 85de2f21c63608e8bbc5c2347fe927b00c8300bf [121ms]
2023-08-18 05:18:04.337 [trace] Operation end: GetRefs (blocking: false, readOnly: true; retry: false; showProgress: false)
2023-08-18 05:18:04.337 [trace] Operation end: GetBranches (blocking: false, readOnly: true; retry: false; showProgress: true)
2023-08-18 05:18:15.738 [trace] [svte] Scan visible text editors. autoRepositoryDetection=true
2023-08-18 05:18:15.738 [trace] [svte] Repository for editor resource c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts already exists: c:\Github\microsoft\vscode
2023-08-18 05:18:18.468 [trace] [svte] Scan visible text editors. autoRepositoryDetection=true
2023-08-18 05:18:18.468 [trace] [svte] Repository for editor resource c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts already exists: c:\Github\microsoft\vscode
2023-08-18 05:18:18.483 [trace] [svte] Scan visible text editors. autoRepositoryDetection=true
2023-08-18 05:18:18.483 [trace] [svte] Repository for editor resource c:\Github\microsoft\vscode\src\vscode-dts\vscode.d.ts already exists: c:\Github\microsoft\vscode