Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Solution style tsconfig - TS Server is slow to respond when having a large number of references #39144

Closed
alan-agius4 opened this issue Jun 18, 2020 · 8 comments · Fixed by #39593
Assignees
Labels
Fix Available A PR has been opened for this issue

Comments

@alan-agius4
Copy link
Contributor

alan-agius4 commented Jun 18, 2020

TypeScript Version: 3.9.5

Search Terms:
solution style
ts server solution

Use Cases

In Angular CLI version 10, we opted to use solution style tsconfig's. It was however brought to our attention that when creating a monorepo style project with a lot of project references, TS server takes a lot of time to response.

Examples

In following reproduction https://github.com/alan-agius4/solution-style-tsconfig-perf, we have a monorepo with 100 projects, each of which add 2 tsconfigs in the root level solution style tsconfig references. https://github.com/alan-agius4/solution-style-tsconfig-perf/blob/master/tsconfig.json

Having a solution style tsconfig that references a lot of tsconfig's results in slowdowns when opening a file in the IDE.

For example when opening projects/lib100/src/lib/lib100.component.ts in the above reproduction we get the following logs. Where we see that for updateOpen response it took 21895ms, similarly for projectInfo, getSupportedCodeFixes etc..

Global level solutions tsconfig

[Info  - 10:40:42.579] Starting TS Server 
[Info  - 10:40:42.579] Using tsserver from: /Applications/Visual Studio Code.app/Contents/Resources/app/extensions/node_modules/typescript/lib
[Info  - 10:40:42.580] <syntax> Forking...
[Info  - 10:40:42.583] <syntax> Starting...
[Info  - 10:40:42.584] <semantic> Forking...
[Info  - 10:40:42.587] <semantic> Starting...
[Trace  - 10:40:42.592] <syntax> Sending request: configure (0). Response expected: no. Current queue length: 0
[Trace  - 10:40:42.593] <semantic> Sending request: configure (0). Response expected: no. Current queue length: 0
[Trace  - 10:40:42.593] <semantic> Sending request: compilerOptionsForInferredProjects (1). Response expected: no. Current queue length: 0
[Trace  - 10:40:42.596] <syntax> Sending request: updateOpen (1). Response expected: yes. Current queue length: 0
[Trace  - 10:40:42.596] <semantic> Sending request: updateOpen (2). Response expected: yes. Current queue length: 0
[Trace  - 10:40:42.702] <syntax> Canceled request with sequence number 3
[Trace  - 10:40:42.705] <syntax> Canceled request with sequence number 4
[Trace  - 10:40:42.705] <semantic> Canceled request with sequence number 6
[Trace  - 10:40:42.705] <semantic> Canceled request with sequence number 7
[Trace  - 10:40:42.706] <semantic> Canceled request with sequence number 8
[Trace  - 10:40:42.706] <semantic> Canceled request with sequence number 9
[Trace  - 10:40:42.707] <semantic> Canceled request with sequence number 10
[Trace  - 10:40:42.708] <syntax> Canceled request with sequence number 6
[Trace  - 10:40:42.708] <semantic> Canceled request with sequence number 12
[Trace  - 10:40:42.708] <syntax> Canceled request with sequence number 7
[Trace  - 10:40:42.708] <semantic> Canceled request with sequence number 13
[Trace  - 10:40:42.710] <syntax> Canceled request with sequence number 8
[Trace  - 10:40:42.710] <semantic> Canceled request with sequence number 14
[Trace  - 10:40:42.719] <semantic> Canceled request with sequence number 11
[Trace  - 10:40:42.719] <syntax> Canceled request with sequence number 9
[Trace  - 10:40:42.719] <semantic> Canceled request with sequence number 15
[Trace  - 10:40:42.868] <syntax> Response received: updateOpen (1). Request took 272 ms. Success: true 
[Trace  - 10:40:42.868] <syntax> Sending request: configurePlugin (2). Response expected: no. Current queue length: 2
[Trace  - 10:40:42.868] <syntax> Sending request: navtree (5). Response expected: yes. Current queue length: 1
[Trace  - 10:40:42.877] <syntax> Response received: navtree (5). Request took 175 ms. Success: true 
[Trace  - 10:40:42.877] <syntax> Sending request: configure (10). Response expected: yes. Current queue length: 0
[Trace  - 10:40:42.878] <syntax> Response received: configure (10). Request took 148 ms. Success: true 
[Trace  - 10:40:42.902] <syntax> Sending request: getOutliningSpans (11). Response expected: yes. Current queue length: 0
[Trace  - 10:40:42.905] <syntax> Response received: getOutliningSpans (11). Request took 3 ms. Success: true 
[Trace  - 10:40:52.877] <semantic> Canceled request with sequence number 19
[Trace  - 10:40:54.110] <semantic> Canceled request with sequence number 20
[Trace  - 10:41:04.365] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.366] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.366] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.366] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.367] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.367] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.367] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.368] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.368] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.368] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.369] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.369] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.369] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.369] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.370] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.370] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.370] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.370] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.371] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.371] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.371] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.371] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.372] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.372] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.372] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.373] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.373] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.373] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.373] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.374] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.374] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.374] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.374] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.374] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.374] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.375] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.375] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.375] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.375] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.375] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.375] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.376] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.376] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.376] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.376] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.376] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.376] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.376] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.376] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.377] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.377] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.377] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.377] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.377] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.377] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.377] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.378] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.378] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.378] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.378] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.378] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.378] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.378] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.379] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.379] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.379] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.379] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.379] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.380] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.380] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.380] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.380] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.381] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.381] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.381] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.381] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.381] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.382] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.382] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.382] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.382] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.383] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.383] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.383] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.383] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.383] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.384] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.384] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.384] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.384] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.384] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.384] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.385] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.385] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.385] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.385] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.385] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.385] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.385] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.386] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.386] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.386] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.386] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.386] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.386] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.387] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.387] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.387] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.387] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.387] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.387] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.387] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.387] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.388] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.388] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.388] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.388] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.388] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.388] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.388] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.389] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.389] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.389] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.389] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.390] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.390] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.390] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.390] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.391] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.391] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.392] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.392] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.392] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.392] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.392] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.392] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.393] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.393] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.393] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.393] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.393] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.393] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.394] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.394] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.394] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.395] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.395] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.395] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.395] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.396] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.396] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.396] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.396] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.396] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.397] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.397] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.397] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.397] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.397] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.397] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.397] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.398] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.398] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.398] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.398] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.398] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.398] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.398] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.399] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.399] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.399] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.399] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.399] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.400] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.400] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.400] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.400] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.401] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.430] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.430] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.433] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.433] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.433] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.433] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.433] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.433] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.434] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.434] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.434] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.434] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.434] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.434] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.434] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.436] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.436] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.436] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.436] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.436] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.437] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.437] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.437] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.437] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.438] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.438] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.438] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.438] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.438] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.439] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.439] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.439] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.439] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.439] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.439] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.440] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.440] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.440] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.440] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.440] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.440] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.440] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.441] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.441] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.441] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.441] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.441] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.441] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.441] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.441] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.442] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.442] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.442] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.442] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.442] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.443] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.443] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.443] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.443] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.443] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.443] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.444] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.444] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.444] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.444] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.444] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.444] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.444] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.444] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.445] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.445] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.445] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.446] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.446] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.446] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.446] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.447] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.447] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.447] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.447] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.448] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.448] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.448] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.448] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.448] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.448] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.448] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.448] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.449] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.449] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.449] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.449] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.449] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.449] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.449] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.450] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.450] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.451] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.451] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.451] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.452] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.452] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.452] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.452] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.452] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.452] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.453] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.453] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.453] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.453] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.456] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.456] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.456] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.456] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.456] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.456] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.456] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.456] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.457] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.457] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.457] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.457] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.457] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.457] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.457] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.458] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.458] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.458] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.458] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.458] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.459] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.459] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.459] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.459] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.459] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.459] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.459] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.460] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.460] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.460] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.460] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.460] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.460] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.461] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.461] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.461] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.461] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.461] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.461] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.461] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.461] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.462] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.462] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.462] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.462] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.462] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.462] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.463] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.463] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.463] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.463] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.464] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.464] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.464] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.464] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.465] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.465] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.465] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.465] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.465] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.466] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.466] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.466] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.466] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.466] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.466] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.466] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.467] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.467] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.467] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.467] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.468] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.468] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.468] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.468] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.468] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.468] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.469] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.469] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.469] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.469] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.469] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.469] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.470] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.470] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.470] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.470] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.470] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.470] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.470] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.470] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.471] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.471] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.471] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.471] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.472] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.472] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.472] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.472] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.472] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.472] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.473] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.473] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.473] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.473] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.473] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.473] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.473] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.473] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.474] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.474] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.474] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.474] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.474] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.474] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.474] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.475] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.475] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.475] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.475] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.475] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.476] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.476] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.476] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.476] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.476] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.476] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.477] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.477] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.477] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.477] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.478] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.478] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.478] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.478] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.478] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.478] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.479] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.479] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.479] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.479] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.480] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.480] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.480] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.480] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.480] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.480] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.480] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.480] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.481] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.481] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.481] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.481] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.482] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.482] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.482] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.482] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.482] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.482] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.483] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.483] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.483] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.483] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.484] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.484] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.484] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.484] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.484] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.485] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.485] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.485] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.485] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.485] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.485] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.485] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.486] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.486] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.486] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.486] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.486] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.486] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.487] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.487] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.487] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.487] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.488] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.488] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.488] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.488] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.489] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.489] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.489] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.490] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.490] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.490] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.490] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.490] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.490] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:41:04.490] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:41:04.490] <semantic> Event received: telemetry (0).
[Trace  - 10:41:04.490] <semantic> Event received: configFileDiag (0).
[Trace  - 10:41:04.491] <semantic> Response received: updateOpen (2). Request took 21895 ms. Success: true 
[Trace  - 10:41:04.491] <semantic> Sending request: projectInfo (3). Response expected: yes. Current queue length: 6
[Trace  - 10:41:04.491] <semantic> Event received: typingsInstallerPid (0).
[Trace  - 10:41:04.491] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.493] <semantic> Response received: projectInfo (3). Request took 21897 ms. Success: true 
[Trace  - 10:41:04.493] <semantic> Sending request: configurePlugin (4). Response expected: no. Current queue length: 5
[Trace  - 10:41:04.493] <semantic> Sending request: getSupportedCodeFixes (5). Response expected: yes. Current queue length: 4
[Trace  - 10:41:04.493] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 3
[Trace  - 10:41:04.494] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.498] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.499] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.500] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.501] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.502] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.503] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.504] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.505] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.506] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.507] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:41:04.508] <semantic> Response received: getSupportedCodeFixes (5). Request took 21816 ms. Success: true 
[Trace  - 10:41:04.508] <semantic> Sending request: getApplicableRefactors (16). Response expected: yes. Current queue length: 3
[Trace  - 10:41:04.535] <semantic> Response received: getApplicableRefactors (16). Request took 21815 ms. Success: true 
[Trace  - 10:41:04.536] <semantic> Sending request: configure (17). Response expected: yes. Current queue length: 2
[Trace  - 10:41:04.536] <semantic> Response received: configure (17). Request took 21806 ms. Success: true 
[Trace  - 10:41:04.536] <semantic> Sending request: encodedSemanticClassifications-full (18). Response expected: yes. Current queue length: 1
[Trace  - 10:41:04.551] <semantic> Response received: encodedSemanticClassifications-full (18). Request took 21739 ms. Success: true 
[Trace  - 10:41:04.551] <semantic> Sending request: geterr (21). Response expected: yes. Current queue length: 0
[Trace  - 10:41:04.553] <semantic> Event received: syntaxDiag (0).
[Trace  - 10:41:04.558] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 21
[Trace  - 10:41:04.558] <semantic> Sending request: quickinfo (22). Response expected: yes. Current queue length: 0
[Trace  - 10:41:04.710] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 22
[Trace  - 10:41:04.760] <semantic> Sending request: geterr (23). Response expected: yes. Current queue length: 0
[Trace  - 10:41:04.849] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 23
[Trace  - 10:41:04.849] <semantic> Sending request: quickinfo (24). Response expected: yes. Current queue length: 0
[Trace  - 10:41:05.35] <syntax> Sending request: getOutliningSpans (12). Response expected: yes. Current queue length: 0
[Trace  - 10:41:05.35] <syntax> Response received: getOutliningSpans (12). Request took 1 ms. Success: true 
[Trace  - 10:41:05.123] <semantic> Event received: semanticDiag (0).
[Trace  - 10:41:05.129] <semantic> Response received: quickinfo (24). Request took 280 ms. Success: true 
[Trace  - 10:41:05.129] <semantic> Sending request: geterr (25). Response expected: yes. Current queue length: 0
[Trace  - 10:41:05.131] <semantic> Event received: syntaxDiag (0).
[Trace  - 10:41:05.157] <semantic> Event received: semanticDiag (0).
[Trace  - 10:41:05.164] <semantic> Event received: suggestionDiag (0).
[Trace  - 10:41:05.165] <semantic> Async response received: requestCompleted (25). Request took 112 ms.

Other considerations

While it is expected that having a lot of projects reference result in a slow-down. This seems to be a bit excessive. Maybe the logic to determine which project a file belongs to can be improved?.

With this in mind, we are considering to change the approach we took and create "mini" solution style tsconfig, where instead of having a global/workspace level solution tsconfig, we add one in each project which will contain 2 project references.

Adding a solution tsconfig at the project level, ie https://github.com/alan-agius4/solution-style-tsconfig-perf/blob/master/projects/lib100/tsconfig.json.bak and perform the same task. We get a totally different result. We see that for updateOpen response it took 1203ms, similarly for projectInfo, getSupportedCodeFixes etc..

Project level solution style tsconfig

[Info  - 10:42:57.903] Starting TS Server 
[Info  - 10:42:57.903] Using tsserver from: /Applications/Visual Studio Code.app/Contents/Resources/app/extensions/node_modules/typescript/lib
[Info  - 10:42:57.905] <syntax> Forking...
[Info  - 10:42:57.908] <syntax> Starting...
[Info  - 10:42:57.909] <semantic> Forking...
[Info  - 10:42:57.911] <semantic> Starting...
[Trace  - 10:42:57.916] <syntax> Sending request: configure (0). Response expected: no. Current queue length: 0
[Trace  - 10:42:57.918] <semantic> Sending request: configure (0). Response expected: no. Current queue length: 0
[Trace  - 10:42:57.918] <semantic> Sending request: compilerOptionsForInferredProjects (1). Response expected: no. Current queue length: 0
[Trace  - 10:42:57.925] <syntax> Sending request: updateOpen (1). Response expected: yes. Current queue length: 0
[Trace  - 10:42:57.926] <semantic> Sending request: updateOpen (2). Response expected: yes. Current queue length: 0
[Trace  - 10:42:58.125] <syntax> Canceled request with sequence number 2
[Trace  - 10:42:58.126] <syntax> Canceled request with sequence number 4
[Trace  - 10:42:58.126] <semantic> Canceled request with sequence number 6
[Trace  - 10:42:58.126] <semantic> Canceled request with sequence number 7
[Trace  - 10:42:58.126] <semantic> Canceled request with sequence number 8
[Trace  - 10:42:58.127] <semantic> Canceled request with sequence number 9
[Trace  - 10:42:58.128] <syntax> Canceled request with sequence number 6
[Trace  - 10:42:58.128] <semantic> Canceled request with sequence number 11
[Trace  - 10:42:58.128] <syntax> Canceled request with sequence number 7
[Trace  - 10:42:58.128] <semantic> Canceled request with sequence number 12
[Trace  - 10:42:58.138] <semantic> Canceled request with sequence number 10
[Trace  - 10:42:58.138] <syntax> Canceled request with sequence number 8
[Trace  - 10:42:58.138] <semantic> Canceled request with sequence number 13
[Trace  - 10:42:58.140] <semantic> Canceled request with sequence number 14
[Trace  - 10:42:58.151] <syntax> Canceled request with sequence number 9
[Trace  - 10:42:58.151] <semantic> Canceled request with sequence number 16
[Trace  - 10:42:58.195] <syntax> Response received: updateOpen (1). Request took 270 ms. Success: true 
[Trace  - 10:42:58.195] <syntax> Sending request: configurePlugin (3). Response expected: no. Current queue length: 2
[Trace  - 10:42:58.195] <syntax> Sending request: navtree (5). Response expected: yes. Current queue length: 1
[Trace  - 10:42:58.204] <syntax> Response received: navtree (5). Request took 77 ms. Success: true 
[Trace  - 10:42:58.204] <syntax> Sending request: configure (10). Response expected: yes. Current queue length: 0
[Trace  - 10:42:58.205] <syntax> Response received: configure (10). Request took 57 ms. Success: true 
[Trace  - 10:42:58.321] <syntax> Sending request: getOutliningSpans (11). Response expected: yes. Current queue length: 0
[Trace  - 10:42:58.324] <syntax> Response received: getOutliningSpans (11). Request took 3 ms. Success: true 
[Trace  - 10:42:59.127] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:42:59.128] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:42:59.128] <semantic> Event received: telemetry (0).
[Trace  - 10:42:59.128] <semantic> Event received: projectLoadingStart (0).
[Trace  - 10:42:59.129] <semantic> Event received: projectLoadingFinish (0).
[Trace  - 10:42:59.129] <semantic> Event received: telemetry (0).
[Trace  - 10:42:59.129] <semantic> Event received: configFileDiag (0).
[Trace  - 10:42:59.129] <semantic> Response received: updateOpen (2). Request took 1203 ms. Success: true 
[Trace  - 10:42:59.129] <semantic> Sending request: projectInfo (3). Response expected: yes. Current queue length: 6
[Trace  - 10:42:59.129] <semantic> Event received: typingsInstallerPid (0).
[Trace  - 10:42:59.130] <semantic> Response received: projectInfo (3). Request took 1204 ms. Success: true 
[Trace  - 10:42:59.130] <semantic> Sending request: configurePlugin (4). Response expected: no. Current queue length: 5
[Trace  - 10:42:59.130] <semantic> Sending request: getSupportedCodeFixes (5). Response expected: yes. Current queue length: 4
[Trace  - 10:42:59.130] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 3
[Trace  - 10:42:59.130] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:42:59.130] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:42:59.131] <semantic> Response received: getSupportedCodeFixes (5). Request took 1013 ms. Success: true 
[Trace  - 10:42:59.131] <semantic> Sending request: getApplicableRefactors (15). Response expected: yes. Current queue length: 3
[Trace  - 10:42:59.171] <semantic> Response received: getApplicableRefactors (15). Request took 1031 ms. Success: true 
[Trace  - 10:42:59.171] <semantic> Sending request: configure (17). Response expected: yes. Current queue length: 2
[Trace  - 10:42:59.172] <semantic> Response received: configure (17). Request took 1024 ms. Success: true 
[Trace  - 10:42:59.172] <semantic> Sending request: encodedSemanticClassifications-full (18). Response expected: yes. Current queue length: 1
[Trace  - 10:42:59.187] <semantic> Response received: encodedSemanticClassifications-full (18). Request took 953 ms. Success: true 
[Trace  - 10:42:59.187] <semantic> Sending request: geterr (19). Response expected: yes. Current queue length: 0
[Trace  - 10:42:59.190] <semantic> Event received: syntaxDiag (0).
[Trace  - 10:42:59.336] <semantic> TypeScript Server: trying to cancel ongoing request with sequence number 19
[Trace  - 10:42:59.337] <semantic> Sending request: geterr (20). Response expected: yes. Current queue length: 0
[Trace  - 10:42:59.765] <semantic> Event received: semanticDiag (0).
[Trace  - 10:42:59.767] <semantic> Event received: syntaxDiag (0).
[Trace  - 10:42:59.792] <semantic> Event received: semanticDiag (0).
[Trace  - 10:42:59.803] <semantic> Event received: suggestionDiag (0).
[Trace  - 10:42:59.804] <semantic> Async response received: requestCompleted (20). Request took 467 ms.
[Trace  - 10:43:00.658] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 10:43:00.859] <semantic> Sending request: geterr (21). Response expected: yes. Current queue length: 0
[Trace  - 10:43:00.861] <semantic> Event received: syntaxDiag (0).
[Trace  - 10:43:00.879] <semantic> Event received: semanticDiag (0).
[Trace  - 10:43:00.885] <semantic> Event received: suggestionDiag (0).
[Trace  - 10:43:00.886] <semantic> Async response received: requestCompleted (21). Request took 27 ms.

Other information

This effects users outside of Google, as internally solution style tsconfig are not in use.

//cc @mprobst, @kyliau & @IgorMinar

Related Issues: None that I am aware of.

@RyanCavanaugh RyanCavanaugh added the Needs Investigation This issue needs a team member to investigate its status. label Jun 18, 2020
@dotansimha
Copy link

dotansimha commented Jun 21, 2020

I'm having a similar issue with a big monorepo, multiple tsconfig.json in each package, and a root tsconfig.json with references that points to the sub-config files (about ~100). Using latest Insiders version (it happened before as well). All other plugins are disabled.

When I do "go to declaration", or ctrl+click on an identifier from a different file, in a JS or TS file, CPU spiked to 100%, RAM is getting out of control, and TypeScript server logs is full with:

[Trace  - 11:10:03.550] <semantic> Canceled request with sequence number 10
[Trace  - 11:10:03.792] <semantic> Canceled request with sequence number 15
[Trace  - 11:10:03.841] <semantic> Canceled request with sequence number 19
[Trace  - 11:10:04.559] <semantic> Canceled request with sequence number 21
[Trace  - 11:10:11.41] <semantic> Canceled request with sequence number 22
[Trace  - 11:10:13.79] <semantic> Canceled request with sequence number 23
[Trace  - 11:10:14.753] <semantic> Canceled request with sequence number 24
[Trace  - 11:10:16.546] <semantic> Canceled request with sequence number 25
[Trace  - 11:10:17.698] <semantic> Canceled request with sequence number 26
[Trace  - 11:10:25.13] <semantic> Canceled request with sequence number 27
[Trace  - 11:10:27.354] <semantic> Canceled request with sequence number 28
[Trace  - 11:12:11.65] <semantic> Canceled request with sequence number 16
[Trace  - 11:12:11.65] <semantic> Canceled request with sequence number 17
[Trace  - 11:12:11.309] <semantic> Canceled request with sequence number 29
[Trace  - 11:12:11.309] <semantic> Canceled request with sequence number 18
[Trace  - 11:12:17.514] <semantic> Canceled request with sequence number 31
[Trace  - 11:12:18.881] <semantic> Canceled request with sequence number 32
[Trace  - 11:13:21.358] <semantic> Canceled request with sequence number 33
[Trace  - 11:13:27.121] <semantic> Canceled request with sequence number 30
[Trace  - 11:13:27.121] <semantic> Canceled request with sequence number 7

Sometimes it stops and go back to normal, but in most cases it's just shows Initializing JS/TS language features.

TS Version is 3.8.3. Also tested latest 3.9.5.

I tried to disable disableAutomaticTypeAcquisition and increase maxTsServerMemory, it did some improvement, but it's still crashing occasionally. Removing references from the root tsconfig seems to work better.

sheetalkamat added a commit that referenced this issue Jul 14, 2020
…low users to disable loading large solutions

Fixes #39144
@sheetalkamat sheetalkamat added Fix Available A PR has been opened for this issue and removed Needs Investigation This issue needs a team member to investigate its status. labels Jul 14, 2020
sheetalkamat added a commit that referenced this issue Jul 14, 2020
…low users to disable loading large solutions

Fixes #39144
sheetalkamat added a commit that referenced this issue Jul 14, 2020
…low users to disable loading large solutions

Fixes #39144
@bradennapier
Copy link

bradennapier commented Jul 18, 2020

Also running into this but our solution references isn't that large - like 6-7 of them. Every time code changes it takes a good 10-15 seconds before typescript types come back - vscode just shows loading for that period. Didn't have this issue before moving to incremental builds.

[Trace  - 22:00:50.481] <semantic> Response received: updateOpen (2). Request took 22100 ms. Success: true 
[Trace  - 22:00:50.481] <semantic> Sending request: configurePlugin (4). Response expected: no. Current queue length: 31
[Trace  - 22:00:50.481] <semantic> Sending request: getSupportedCodeFixes (6). Response expected: yes. Current queue length: 30
[Trace  - 22:00:50.481] <semantic> Event received: projectsUpdatedInBackground (0).
[Trace  - 22:00:50.482] <semantic> Response received: getSupportedCodeFixes (6). Request took 19775 ms. Success: true 
[Trace  - 22:00:50.482] <semantic> Sending request: configure (27). Response expected: yes. Current queue length: 29
[Trace  - 22:00:50.483] <semantic> Response received: configure (27). Request took 19459 ms. Success: true 
[Trace  - 22:00:50.483] <semantic> Sending request: configure (28). Response expected: yes. Current queue length: 28
[Trace  - 22:00:50.483] <semantic> Response received: configure (28). Request took 19458 ms. Success: true 
[Trace  - 22:00:50.988] <semantic> Sending request: configurePlugin (45). Response expected: no. Current queue length: 19
[Trace  - 22:00:50.988] <semantic> Sending request: encodedSemanticClassifications-full (56). Response expected: yes. Current queue length: 18
[Trace  - 22:00:51.7] <semantic> Response received: encodedSemanticClassifications-full (56). Request took 14897 ms. Success: true 
[Trace  - 22:00:51.8] <semantic> Sending request: encodedSemanticClassifications-full (57). Response expected: yes. Current queue length: 17
[Trace  - 22:00:51.20] <semantic> Response received: encodedSemanticClassifications-full (57). Request took 14910 ms. Success: true 
[Trace  - 22:00:51.20] <semantic> Sending request: encodedSemanticClassifications-full (68). Response expected: yes. Current queue length: 16
[Trace  - 22:00:51.26] <semantic> Response received: encodedSemanticClassifications-full (68). Request took 10892 ms. Success: true 
[Trace  - 22:00:51.26] <semantic> Sending request: encodedSemanticClassifications-full (69). Response expected: yes. Current queue length: 15
[Trace  - 22:00:51.32] <semantic> Response received: encodedSemanticClassifications-full (69). Request took 10898 ms. Success: true 
[Trace  - 22:00:51.32] <semantic> Sending request: getApplicableRefactors (71). Response expected: yes. Current queue length: 14
[Trace  - 22:00:51.99] <semantic> Response received: getApplicableRefactors (71). Request took 10408 ms. Success: true 
[Trace  - 22:00:51.99] <semantic> Sending request: projectInfo (85). Response expected: yes. Current queue length: 13
[Trace  - 22:07:32.991] <semantic> Response received: references (243). Request took 15160 ms. Success: true 
[Trace  - 22:07:32.991] <semantic> Sending request: references (244). Response expected: yes. Current queue length: 4
[Trace  - 22:07:32.991] <semantic> Event received: syntaxDiag (0).
[Trace  - 22:07:33.99] <semantic> Response received: references (244). Request took 15268 ms. Success: true 
[Trace  - 22:07:33.99] <semantic> Sending request: references (245). Response expected: yes. Current queue length: 3
[Trace  - 22:07:33.99] <semantic> Event received: semanticDiag (0).
[Trace  - 22:07:33.177] <semantic> Response received: references (245). Request took 15346 ms. Success: true 
[Trace  - 22:07:33.177] <semantic> Sending request: references (246). Response expected: yes. Current queue length: 2
[Trace  - 22:07:33.178] <semantic> Event received: suggestionDiag (0).
[Trace  - 22:07:33.234] <semantic> Response received: references (246). Request took 15403 ms. Success: true 
[Trace  - 22:07:33.234] <semantic> Sending request: references (247). Response expected: yes. Current queue length: 1
[Trace  - 22:07:33.236] <semantic> Event received: syntaxDiag (0).
[Trace  - 22:07:33.295] <semantic> Response received: references (247). Request took 15464 ms. Success: true 
[Trace  - 22:07:33.295] <semantic> Sending request: references (248). Response expected: yes. Current queue length: 0
[Trace  - 22:07:33.372] <semantic> Event received: semanticDiag (0).
[Trace  - 22:07:33.675] <semantic> Response received: references (248). Request took 15844 ms. Success: true 
[Trace  - 22:07:33.676] <semantic> Event received: suggestionDiag (0).
[Trace  - 22:07:33.676] <semantic> Async response received: requestCompleted (259). Request took 4863 ms.
[Trace  - 22:07:40.569] <semantic> Event received: projectLoadingStart (0).

Most of our references are like 5-6 files with a couple hundred lines. The entire project before could build in around 8-12 seconds and now it takes 20+ seconds on every edit instead.

@sheetalkamat
Copy link
Member

@bradennapier Please open a separate issue with repro steps and tsserver log so we can look at that one. This one is specifically to ensure that we provide a way to opt out of referenced project loading if your project is large so you want to skip that part.

sheetalkamat added a commit that referenced this issue Jul 21, 2020
…low users to disable loading large solutions (#39593)

* Use disableReferencedProjectLoad to stop loading child projects to allow users to disable loading large solutions
Fixes #39144

* Handle indirect references

* PR feedback
sheetalkamat added a commit that referenced this issue Jul 21, 2020
…n has some of its own files and project references (#39613)

* Use disableReferencedProjectLoad to stop loading child projects to allow users to disable loading large solutions
Fixes #39144

* Handle indirect references

* Support loading of child project if found project is not pure solution has some of its own files and project references
Fixes #38605

* Fix grammar
@bradennapier
Copy link

@sheetalkamat thanks - i think I figured out why it is happening and opened / commented on that instead. Thanks.

@DanielRosenwasser
Copy link
Member

For reference, anyone who runs into this should be able to set the disableReferencedProjectLoad option in TypeScript 4.0 using our nightly releases (npm install typescript@next)

@bradennapier
Copy link

bradennapier commented Aug 18, 2020

@DanielRosenwasser so definitely running into this now that I am testing again - and if i add that new value to every single tsconfig in my project that seems to fix it -- but it isn't directly clear what I am losing by setting that to true. Do I lose a bunch of language features?

What is most annoying I think, is that the type returns in about 1 second then moves to loading which takes 1-2 minutes just to tell me the same type again without this feature on.

If I do not have a top level tsconfig.json with references it doesn't seem to be an issue, but not sure what the effect of doing that with the editor features would be either.

@DanielRosenwasser
Copy link
Member

That's part of the tradeoff - if you have that setting on, TypeScript will avoid loading up entire projects for each set of references. I believe that means things like find-all-references within your project might not give results full results for projects that you depend on.

What is most annoying I think, is that the type returns in about 1 second then moves to loading which takes 1-2 minutes just to tell me the same type again without this feature on.

I'm not exactly sure what you mean here, but it might be expected given that for certain operations, the project needs to immediately load to answer a question.

@bradennapier
Copy link

bradennapier commented Aug 18, 2020

Essentially it loads properly and gives me the typing I want then it takes a minute after that initial load before I can see it again after the initial load. I cut it off too early, but when I move my cursor off then back on - it basically saying loading... and that will last for 1 minute before it will show me the data it showed me right away.

Diagnostics does show it returns immediately and gives me the desired result then this request takes a long time:

[Trace  - 22:50:53.907] <semantic> Response received: encodedSemanticClassifications-full (31). Request took 34196 ms. Success: true 

Then similar take another 30 seconds or so for references so its just over 60 seconds in all -- which makes sense since that is how long a full compile takes (even though I have dist compiled with all the tsbuildinfo files up to date)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Fix Available A PR has been opened for this issue
Projects
None yet
6 participants