Slow start of REPL when a pom file is present #187

DavyLandman · 2022-10-03T13:11:25Z

Describe the bug

The REPL takes 40s+ to show up in the terminal view, and then another 40s+ to be available for the user.

To Reproduce

Here is a project config:

slow-vscode.zip

open up the project in vscode
open the src/Hello.rsc
click "Run in new Rascal terminal"

It takes 40s before any UI action is happening, so a user is confused that nothing is happening.

Then the terminal starts up and takes a long while before it's ready for use.

Expected behavior

Fast opening of the terminal, fast loading of the repl.

Stack traces

No errors in developer tools or rascal output window.

REPL window:

INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.7/rascal-0.24.7.jar!/|
INFO: detected |lib://rascal-core| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
INFO: detected |lib://typepal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.7/rascal-0.24.7.jar!/|
INFO: detected |lib://rascal-core| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
INFO: detected |lib://typepal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.7/rascal-0.24.7.jar!/|
INFO: detected |lib://rascal-core| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
INFO: detected |lib://typepal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: detected |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
INFO: resolved |lib://typepal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: resolved |lib://rascal-core| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
Rascal Version: 0.24.6
INFO: resolved |lib://rascal-lsp| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
Rascal-lsp Version: 2.4.0
pathConfig(
  javaCompilerPath=[
    |file:///c:%5CUsers%5CDavy%5C.vscode%5Cextensions%5Cusethesource.rascalmpl-0.4.0%5Cassets%5Cjars%5Crascal-lsp.jar|,
    |file:///c:%5CUsers%5CDavy%5C.vscode%5Cextensions%5Cusethesource.rascalmpl-0.4.0%5Cassets%5Cjars%5Crascal.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Crascalmpl%5Crascal%5C0.24.7%5Crascal-0.24.7.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Crascalmpl%5Crascal-core%5C0.7.8%5Crascal-core-0.7.8.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Cio%5Cusethesource%5Cvallang%5C0.14.2%5Cvallang-0.14.2.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Cio%5Cusethesource%5Ccapsule%5C0.7.0%5Ccapsule-0.7.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capache%5Ccommons%5Ccommons-compress%5C1.21%5Ccommons-compress-1.21.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ctukaani%5Cxz%5C1.9%5Cxz-1.9.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgithub%5Cluben%5Czstd-jni%5C1.5.0-4%5Czstd-jni-1.5.0-4.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgithub%5Cben-manes%5Ccaffeine%5Ccaffeine%5C2.9.2%5Ccaffeine-2.9.2.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Cerrorprone%5Cerror_prone_annotations%5C2.5.1%5Cerror_prone_annotations-2.5.1.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ccheckerframework%5Cchecker-qual%5C3.18.0%5Cchecker-qual-3.18.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Crascalmpl%5Ctypepal%5C0.7.8%5Ctypepal-0.7.8.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Cjunit%5Cjunit%5C4.13.1%5Cjunit-4.13.1.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Chamcrest%5Chamcrest-core%5C1.3%5Chamcrest-core-1.3.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Clsp4j%5Corg.eclipse.lsp4j%5C0.15.0%5Corg.eclipse.lsp4j-0.15.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Clsp4j%5Corg.eclipse.lsp4j.generator%5C0.15.0%5Corg.eclipse.lsp4j.generator-0.15.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Cxtend%5Corg.eclipse.xtend.lib%5C2.24.0%5Corg.eclipse.xtend.lib-2.24.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Cxtext%5Corg.eclipse.xtext.xbase.lib%5C2.24.0%5Corg.eclipse.xtext.xbase.lib-2.24.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Cguava%5Cguava%5C27.1-jre%5Cguava-27.1-jre.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Cguava%5Cfailureaccess%5C1.0.1%5Cfailureaccess-1.0.1.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Cguava%5Clistenablefuture%5C9999.0-empty-to-avoid-conflict-with-guava%5Clistenablefuture-9999.0-empty-to-avoid-conflict-with-guava.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Ccode%5Cfindbugs%5Cjsr305%5C3.0.2%5Cjsr305-3.0.2.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Cj2objc%5Cj2objc-annotations%5C1.1%5Cj2objc-annotations-1.1.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ccodehaus%5Cmojo%5Canimal-sniffer-annotations%5C1.17%5Canimal-sniffer-annotations-1.17.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Cxtend%5Corg.eclipse.xtend.lib.macro%5C2.24.0%5Corg.eclipse.xtend.lib.macro-2.24.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Ceclipse%5Clsp4j%5Corg.eclipse.lsp4j.jsonrpc%5C0.15.0%5Corg.eclipse.lsp4j.jsonrpc-0.15.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Ccom%5Cgoogle%5Ccode%5Cgson%5Cgson%5C2.9.1%5Cgson-2.9.1.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capache%5Clogging%5Clog4j%5Clog4j-core%5C2.18.0%5Clog4j-core-2.18.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capache%5Clogging%5Clog4j%5Clog4j-api%5C2.18.0%5Clog4j-api-2.18.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capache%5Clogging%5Clog4j%5Clog4j-iostreams%5C2.18.0%5Clog4j-iostreams-2.18.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capache%5Clogging%5Clog4j%5Clog4j-jul%5C2.18.0%5Clog4j-jul-2.18.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Crascalmpl%5Crascal%5C0.24.2%5Crascal-0.24.2.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Cjunit%5Cjupiter%5Cjunit-jupiter-api%5C5.6.2%5Cjunit-jupiter-api-5.6.2.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Crascalmpl%5Crascal-lsp%5C2.4.0%5Crascal-lsp-2.4.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Copentest4j%5Copentest4j%5C1.2.0%5Copentest4j-1.2.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Capiguardian%5Capiguardian-api%5C1.1.0%5Capiguardian-api-1.1.0.jar|,
    |file:///C:%5CUsers%5CDavy%5C.m2%5Crepository%5Corg%5Cjunit%5Cplatform%5Cjunit-platform-commons%5C1.6.2%5Cjunit-platform-commons-1.6.2.jar|
  ],
  bin=|target://slow-vscode|,
  classloaders=[
    |lib://typepal|,
    |lib://rascal-core|,
    |target://rascal-lsp|,
    |system:///|,
    |file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.7/rascal-0.24.7.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar|,
    |file:///C:/Users/Davy/.m2/repository/io/usethesource/vallang/0.14.2/vallang-0.14.2.jar|,
    |file:///C:/Users/Davy/.m2/repository/io/usethesource/capsule/0.7.0/capsule-0.7.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apache/commons/commons-compress/1.21/commons-compress-1.21.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/tukaani/xz/1.9/xz-1.9.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/github/luben/zstd-jni/1.5.0-4/zstd-jni-1.5.0-4.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/github/ben-manes/caffeine/caffeine/2.9.2/caffeine-2.9.2.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/errorprone/error_prone_annotations/2.5.1/error_prone_annotations-2.5.1.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/checkerframework/checker-qual/3.18.0/checker-qual-3.18.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar|,
    |file:///C:/Users/Davy/.m2/repository/junit/junit/4.13.1/junit-4.13.1.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j/0.15.0/org.eclipse.lsp4j-0.15.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j.generator/0.15.0/org.eclipse.lsp4j.generator-0.15.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/xtend/org.eclipse.xtend.lib/2.24.0/org.eclipse.xtend.lib-2.24.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/xtext/org.eclipse.xtext.xbase.lib/2.24.0/org.eclipse.xtext.xbase.lib-2.24.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/guava/guava/27.1-jre/guava-27.1-jre.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/guava/failureaccess/1.0.1/failureaccess-1.0.1.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/guava/listenablefuture/9999.0-empty-to-avoid-conflict-with-guava/listenablefuture-9999.0-empty-to-avoid-conflict-with-guava.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/code/findbugs/jsr305/3.0.2/jsr305-3.0.2.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/j2objc/j2objc-annotations/1.1/j2objc-annotations-1.1.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/codehaus/mojo/animal-sniffer-annotations/1.17/animal-sniffer-annotations-1.17.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/xtend/org.eclipse.xtend.lib.macro/2.24.0/org.eclipse.xtend.lib.macro-2.24.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j.jsonrpc/0.15.0/org.eclipse.lsp4j.jsonrpc-0.15.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/com/google/code/gson/gson/2.9.1/gson-2.9.1.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-core/2.18.0/log4j-core-2.18.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-api/2.18.0/log4j-api-2.18.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-iostreams/2.18.0/log4j-iostreams-2.18.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-jul/2.18.0/log4j-jul-2.18.0.jar|,
    |target://slow-vscode|,
    |file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/junit/jupiter/junit-jupiter-api/5.6.2/junit-jupiter-api-5.6.2.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/opentest4j/opentest4j/1.2.0/opentest4j-1.2.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/apiguardian/apiguardian-api/1.1.0/apiguardian-api-1.1.0.jar|,
    |file:///C:/Users/Davy/.m2/repository/org/junit/platform/junit-platform-commons/1.6.2/junit-platform-commons-1.6.2.jar|
  ],
  libs=[|lib://rascal|],
  srcs=[
    |lib://typepal/src|,
    |lib://rascal-core/src/org/rascalmpl/core/library|,
    |project://rascal-lsp/src/main/rascal|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.7/rascal-0.24.7.jar!/src/org/rascalmpl/library|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/src/org/rascalmpl/core/library|,
    |jar+file:///C:/Users/Davy/.m2/repository/io/usethesource/vallang/0.14.2/vallang-0.14.2.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/io/usethesource/capsule/0.7.0/capsule-0.7.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apache/commons/commons-compress/1.21/commons-compress-1.21.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/tukaani/xz/1.9/xz-1.9.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/github/luben/zstd-jni/1.5.0-4/zstd-jni-1.5.0-4.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/github/ben-manes/caffeine/caffeine/2.9.2/caffeine-2.9.2.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/errorprone/error_prone_annotations/2.5.1/error_prone_annotations-2.5.1.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/checkerframework/checker-qual/3.18.0/checker-qual-3.18.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/junit/junit/4.13.1/junit-4.13.1.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j/0.15.0/org.eclipse.lsp4j-0.15.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j.generator/0.15.0/org.eclipse.lsp4j.generator-0.15.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/xtend/org.eclipse.xtend.lib/2.24.0/org.eclipse.xtend.lib-2.24.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/xtext/org.eclipse.xtext.xbase.lib/2.24.0/org.eclipse.xtext.xbase.lib-2.24.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/guava/guava/27.1-jre/guava-27.1-jre.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/guava/failureaccess/1.0.1/failureaccess-1.0.1.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/guava/listenablefuture/9999.0-empty-to-avoid-conflict-with-guava/listenablefuture-9999.0-empty-to-avoid-conflict-with-guava.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/code/findbugs/jsr305/3.0.2/jsr305-3.0.2.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/j2objc/j2objc-annotations/1.1/j2objc-annotations-1.1.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/codehaus/mojo/animal-sniffer-annotations/1.17/animal-sniffer-annotations-1.17.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/xtend/org.eclipse.xtend.lib.macro/2.24.0/org.eclipse.xtend.lib.macro-2.24.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/eclipse/lsp4j/org.eclipse.lsp4j.jsonrpc/0.15.0/org.eclipse.lsp4j.jsonrpc-0.15.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/com/google/code/gson/gson/2.9.1/gson-2.9.1.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-core/2.18.0/log4j-core-2.18.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-api/2.18.0/log4j-api-2.18.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-iostreams/2.18.0/log4j-iostreams-2.18.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apache/logging/log4j/log4j-jul/2.18.0/log4j-jul-2.18.0.jar!/src|,
    |file:///C:/Users/Davy/swat.engineering/temp/slow-vscode/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/src/org/rascalmpl/library|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/junit/jupiter/junit-jupiter-api/5.6.2/junit-jupiter-api-5.6.2.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/src/main/rascal|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/opentest4j/opentest4j/1.2.0/opentest4j-1.2.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/apiguardian/apiguardian-api/1.1.0/apiguardian-api-1.1.0.jar!/src|,
    |jar+file:///C:/Users/Davy/.m2/repository/org/junit/platform/junit-platform-commons/1.6.2/junit-platform-commons-1.6.2.jar!/src|
  ],
  courses=[])INFO: resolved |lib://rascal| at |jar+file:///C:/Users/Davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
rascal>import Hello;
ok
rascal>main()
Hi world
ok
rascal>

Desktop (please complete the following information):

Context: Rascal VS Code extension 0.4.0, Windows, Java 11
Rascal Version: 0.24.6

The text was updated successfully, but these errors were encountered:

DavyLandman · 2022-10-03T13:17:06Z

Some details of interest:

I can reproduce it on Linux, but it's quicker there. The performance degredation is primarily on the initial start (10s instead of 40s), after the repl starts, it's quick.

INFO: detected |lib://rascal-lsp| at |jar+file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar!/|
INFO: detected |lib://rascal| at |jar+file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar!/|
INFO: detected |lib://typepal| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: detected |lib://rascal| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
INFO: detected |lib://rascal-core| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar!/|
INFO: detected |lib://rascal| at |jar+file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar!/|
INFO: detected |lib://typepal| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/|
INFO: detected |lib://rascal| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
INFO: detected |lib://rascal-core| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/|
INFO: detected |lib://rascal-lsp| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
INFO: resolved |lib://rascal-lsp| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/|
Rascal Version: 0.24.6
Rascal-lsp Version: 2.4.0
pathConfig(
  javaCompilerPath=[
    |file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal-lsp.jar|,
    |file:///home/davy/.vscode/extensions/usethesource.rascalmpl-0.4.0/assets/jars/rascal.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar|,
    |file:///home/davy/.m2/repository/org/tukaani/xz/1.9/xz-1.9.jar|,
    |file:///home/davy/.m2/repository/org/checkerframework/checker-qual/3.18.0/checker-qual-3.18.0.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar|,
    |file:///home/davy/.m2/repository/org/apache/commons/commons-compress/1.21/commons-compress-1.21.jar|,
    |file:///home/davy/.m2/repository/com/google/errorprone/error_prone_annotations/2.5.1/error_prone_annotations-2.5.1.jar|,
    |file:///home/davy/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar|,
    |file:///home/davy/.m2/repository/org/junit/jupiter/junit-jupiter-api/5.6.2/junit-jupiter-api-5.6.2.jar|,
    |file:///home/davy/.m2/repository/io/usethesource/capsule/0.7.0/capsule-0.7.0.jar|,
    |file:///home/davy/.m2/repository/junit/junit/4.13.1/junit-4.13.1.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar|,
    |file:///home/davy/.m2/repository/org/opentest4j/opentest4j/1.2.0/opentest4j-1.2.0.jar|,
    |file:///home/davy/.m2/repository/io/usethesource/vallang/0.14.2/vallang-0.14.2.jar|,
    |file:///home/davy/.m2/repository/com/github/ben-manes/caffeine/caffeine/2.9.2/caffeine-2.9.2.jar|,
    |file:///home/davy/.m2/repository/org/apiguardian/apiguardian-api/1.1.0/apiguardian-api-1.1.0.jar|,
    |file:///home/davy/.m2/repository/org/junit/platform/junit-platform-commons/1.6.2/junit-platform-commons-1.6.2.jar|,
    |file:///home/davy/.m2/repository/com/github/luben/zstd-jni/1.5.0-4/zstd-jni-1.5.0-4.jar|
  ],
  bin=|target://slow-vscode|,
  classloaders=[
    |lib://rascal-lsp|,
    |target://slow-vscode|,
    |system:///|,
    |file:///home/davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar|,
    |file:///home/davy/.m2/repository/org/tukaani/xz/1.9/xz-1.9.jar|,
    |file:///home/davy/.m2/repository/org/checkerframework/checker-qual/3.18.0/checker-qual-3.18.0.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar|,
    |file:///home/davy/.m2/repository/org/apache/commons/commons-compress/1.21/commons-compress-1.21.jar|,
    |file:///home/davy/.m2/repository/com/google/errorprone/error_prone_annotations/2.5.1/error_prone_annotations-2.5.1.jar|,
    |file:///home/davy/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar|,
    |file:///home/davy/.m2/repository/org/junit/jupiter/junit-jupiter-api/5.6.2/junit-jupiter-api-5.6.2.jar|,
    |file:///home/davy/.m2/repository/io/usethesource/capsule/0.7.0/capsule-0.7.0.jar|,
    |file:///home/davy/.m2/repository/junit/junit/4.13.1/junit-4.13.1.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar|,
    |file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar|,
    |file:///home/davy/.m2/repository/org/opentest4j/opentest4j/1.2.0/opentest4j-1.2.0.jar|,
    |file:///home/davy/.m2/repository/io/usethesource/vallang/0.14.2/vallang-0.14.2.jar|,
    |file:///home/davy/.m2/repository/com/github/ben-manes/caffeine/caffeine/2.9.2/caffeine-2.9.2.jar|,
    |file:///home/davy/.m2/repository/org/apiguardian/apiguardian-api/1.1.0/apiguardian-api-1.1.0.jar|,
    |file:///home/davy/.m2/repository/org/junit/platform/junit-platform-commons/1.6.2/junit-platform-commons-1.6.2.jar|,
    |file:///home/davy/.m2/repository/com/github/luben/zstd-jni/1.5.0-4/zstd-jni-1.5.0-4.jar|
  ],
  libs=[|lib://rascal|],
  srcs=[
    |lib://rascal-lsp|,
    |file:///mnt/c/Users/Davy/swat.engineering/temp/slow-vscode/src|,
    |jar+file:///home/davy/.m2/repository/org/rascalmpl/typepal/0.7.8/typepal-0.7.8.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/tukaani/xz/1.9/xz-1.9.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/checkerframework/checker-qual/3.18.0/checker-qual-3.18.0.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/src/org/rascalmpl/library|,
    |jar+file:///home/davy/.m2/repository/org/apache/commons/commons-compress/1.21/commons-compress-1.21.jar!/src|,
    |jar+file:///home/davy/.m2/repository/com/google/errorprone/error_prone_annotations/2.5.1/error_prone_annotations-2.5.1.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/junit/jupiter/junit-jupiter-api/5.6.2/junit-jupiter-api-5.6.2.jar!/src|,
    |jar+file:///home/davy/.m2/repository/io/usethesource/capsule/0.7.0/capsule-0.7.0.jar!/src|,
    |jar+file:///home/davy/.m2/repository/junit/junit/4.13.1/junit-4.13.1.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-core/0.7.8/rascal-core-0.7.8.jar!/src/org/rascalmpl/core/library|,
    |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal-lsp/2.4.0/rascal-lsp-2.4.0.jar!/src/main/rascal|,
    |jar+file:///home/davy/.m2/repository/org/opentest4j/opentest4j/1.2.0/opentest4j-1.2.0.jar!/src|,
    |jar+file:///home/davy/.m2/repository/io/usethesource/vallang/0.14.2/vallang-0.14.2.jar!/src|,
    |jar+file:///home/davy/.m2/repository/com/github/ben-manes/caffeine/caffeine/2.9.2/caffeine-2.9.2.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/apiguardian/apiguardian-api/1.1.0/apiguardian-api-1.1.0.jar!/src|,
    |jar+file:///home/davy/.m2/repository/org/junit/platform/junit-platform-commons/1.6.2/junit-platform-commons-1.6.2.jar!/src|,
    |jar+file:///home/davy/.m2/repository/com/github/luben/zstd-jni/1.5.0-4/zstd-jni-1.5.0-4.jar!/src|
  ],
  courses=[])INFO: resolved |lib://rascal| at |jar+file:///home/davy/.m2/repository/org/rascalmpl/rascal/0.24.2/rascal-0.24.2.jar!/|
rascal>import Hello;
ok
rascal>main()
Hi world
ok

if you start a terminal without a rascal file open, it's very snappy.

jurgenvinju · 2022-10-03T13:31:22Z

I think I have some regrets about this code:

  try (BufferedReader processOutputReader = new BufferedReader(new InputStreamReader(process.getInputStream()))) {

                process.waitFor();

                return processOutputReader.lines()
                    .filter(line -> !line.startsWith(

in PathConfig.java, rascal project, line 615 in getPomXmlCompilerClasspath

Because while we are not reading from stdout, what is the guarantee that the process terminates and when?
It could be that the OS holds on to the mvn process until its output has been processed, and in that case this try-block will only terminate after a time-out that kills the process.

jurgenvinju · 2022-10-03T13:33:19Z

printing the mvn dependency:build-classpath output takes exactly 3 seconds on my machine, while executing the same code from within PathConfig takes 15 seconds.

DavyLandman · 2022-10-03T13:47:48Z

I've just done some rough profiling, at least on windows, we also loose half of the time in the installNecessaryMavenPlugins function.

DavyLandman · 2022-10-03T13:51:21Z

also, I think we might be looking at the slow start of maven. as the reported "total time" does not include time spend in starting maven:

❯ time mvn dependency:build-classpath
[INFO] Scanning for projects...
[INFO]
[INFO] ---------------------< org.rascalmpl:slow-vscode >----------------------
[INFO] Building slow-vscode 0.0.1-SNAPSHOT
[INFO] --------------------------------[ jar ]---------------------------------
[INFO]
[INFO] --- maven-dependency-plugin:2.8:build-classpath (default-cli) @ slow-vscode ---
[INFO] Dependencies classpath:
C:\Users\Davy\.m2\repository\org\rascalmpl\rascal-lsp\2.4.0\rascal-lsp-2.4.0.jar;C:\Users\Davy\.m2\repository\org\rascalmpl\rascal\0.24.2\rascal-0.24.2.jar;C:\Users\Davy\.m2\repository\org\rascalmpl\rascal-core\0.7.8\rascal-core-0.7.8.jar;C:\Users\Davy\.m2\repository\org\junit\jupiter\junit-jupiter-api\5.6.2\junit-jupiter-api-5.6.2.jar;C:\Users\Davy\.m2\repository\org\apiguardian\apiguardian-api\1.1.0\apiguardian-api-1.1.0.jar;C:\Users\Davy\.m2\repository\org\opentest4j\opentest4j\1.2.0\opentest4j-1.2.0.jar;C:\Users\Davy\.m2\repository\org\junit\platform\junit-platform-commons\1.6.2\junit-platform-commons-1.6.2.jar;C:\Users\Davy\.m2\repository\io\usethesource\vallang\0.14.2\vallang-0.14.2.jar;C:\Users\Davy\.m2\repository\io\usethesource\capsule\0.7.0\capsule-0.7.0.jar;C:\Users\Davy\.m2\repository\org\apache\commons\commons-compress\1.21\commons-compress-1.21.jar;C:\Users\Davy\.m2\repository\org\tukaani\xz\1.9\xz-1.9.jar;C:\Users\Davy\.m2\repository\com\github\luben\zstd-jni\1.5.0-4\zstd-jni-1.5.0-4.jar;C:\Users\Davy\.m2\repository\com\github\ben-manes\caffeine\caffeine\2.9.2\caffeine-2.9.2.jar;C:\Users\Davy\.m2\repository\com\google\errorprone\error_prone_annotations\2.5.1\error_prone_annotations-2.5.1.jar;C:\Users\Davy\.m2\repository\org\checkerframework\checker-qual\3.18.0\checker-qual-3.18.0.jar;C:\Users\Davy\.m2\repository\org\rascalmpl\typepal\0.7.8\typepal-0.7.8.jar;C:\Users\Davy\.m2\repository\junit\junit\4.13.1\junit-4.13.1.jar;C:\Users\Davy\.m2\repository\org\hamcrest\hamcrest-core\1.3\hamcrest-core-1.3.jar
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  2.044 s
[INFO] Finished at: 2022-10-03T15:50:21+02:00
[INFO] ------------------------------------------------------------------------
mvn dependency:build-classpath  0.12s user 0.37s system 9% cpu 5.168 total

so, 2s reported, but 5s actual.

Similarly for the dependency get install of the plugin:

❯ time mvn dependency:get -DgroupId=org.apache.maven.plugins -DartifactId=maven-dependency-plugin -Dversion=2.8
[INFO] Scanning for projects...
[INFO]
[INFO] ---------------------< org.rascalmpl:slow-vscode >----------------------
[INFO] Building slow-vscode 0.0.1-SNAPSHOT
[INFO] --------------------------------[ jar ]---------------------------------
[INFO]
[INFO] --- maven-dependency-plugin:2.8:get (default-cli) @ slow-vscode ---
[INFO] Resolving org.apache.maven.plugins:maven-dependency-plugin:jar:2.8 with transitive dependencies
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  3.208 s
[INFO] Finished at: 2022-10-03T15:53:42+02:00
[INFO] ------------------------------------------------------------------------
mvn dependency:get -DgroupId=org.apache.maven.plugins  -Dversion=2.8  0.18s user 0.26s system 6% cpu 6.391 total

that takes 6.3s to complete.

so on my machine, that's 11s for a single run of those two commands, and we are running them quite often. (before the REPL starts we also run it, since vscode asks for a project config)

jurgenvinju · 2022-10-03T15:18:17Z

installNecessaryMavenPlugins is interesting since that is not an "offline" one. Perhaps checking if they are installed first, would be faster..

jurgenvinju · 2022-10-03T15:28:17Z

I've interesting results here:

This is running mvn dependency:get -DgroupId=org.apache.maven.plugins -DartifactId=maven-dependency-plugin -Dversion=2.8 which is what installNecessaryMavenPlugins does (and nothing else).

real	0m1.978s
user	0m6.716s
sys	0m0.377s

So you see the prompt returns within 2 seconds, but CPU time spent is almost 7 seconds. This is probably due to using multiple cores.

If I run this with or without --offline the results remain the same, given that I've already installed the maven-dependency-plugin version 2.8 in my .m2 repository and all of its required dependencies. Of course if I remove those plugins then it is slower, but that is not the common use case that we are optimizing.

DavyLandman · 2022-10-03T15:41:18Z

Aren't we just looking at the infamous cold starts of JVM processes? So starting maven takes time?

I've snipped away one of the maven calls here: usethesource/rascal#1661

Also on my machine, counting with my hands, it takes longer than 2s.

DavyLandman · 2022-10-03T15:52:35Z

I've put a timer next to it.

In the bottom you see a timer running, it takes roughly 7 seconds to finish. (This effect might be more pronounced on windows)

jurgenvinju · 2022-10-04T08:04:12Z

Solution directions:

try again to find a Java API for maven that we can link to. That would not require additional JVM starting
re-implement a part of Maven functionality ourselves by reading the pom dependency declarations ourselves and finding jar files in the .m2 directory ourselves (requires adding Rascal configuration flag option for .m2 directory. -Drascal.local.repository? Not a happy camper...

jurgenvinju · 2022-10-04T08:04:20Z

Alternatives wanted!

DavyLandman · 2022-10-04T08:07:29Z

Could we try and encapsulate the maven-dependency-plugin specifically? So that we do not need all of maven to work, but just that plugin?

jurgenvinju · 2022-10-04T08:27:12Z

I've tried that for the JUnit plugin. I can load all the objects but I can not populate all the injected data. The core platform is what reads the pom and then populates the data model that is injected to the parameters of all the plugins. It's well designed but its also a tight knot.

jurgenvinju · 2022-10-04T12:02:46Z

@DavyLandman mentioned maven-embedder and way back when in 2018 this was not an option. But maybe now it is. It could also make other integrations with maven easy when we need them (for example for a component listing and installation UX in the repl).

DavyLandman · 2022-10-04T12:12:57Z

I've been stumbling through one issue after the other, but I think we're bitten by the dependency injection of maven.

https://stackoverflow.com/questions/43264732/maven-embedded-3-3-9-is-it-possible

Here a discussion is roughly saying: this won't work with shaded jars and other kinds of bundled jars. And that is what we are doing with rascal.

DavyLandman · 2022-10-04T15:01:46Z

okay, that pull request (usethesource/rascal#1661 ) contains the work to call maven inside of rascal, without starting a new jvm. on windows it reduces the call to build the path from 14s to less than 2s.

@jurgenvinju: can you get it to work together with our maven shading, as it currently fails when it's inside of a big jar.

jurgenvinju · 2022-10-05T08:00:42Z

I'll try :-) but not very soon. There's a backlog.

So that windows users now whats happening until #187 gets resolved

DavyLandman added the bug Something isn't working label Oct 3, 2022

DavyLandman added a commit that referenced this issue Oct 5, 2022

Add progressbar while starting rascal repl

22d1ac9

So that windows users now whats happening until #187 gets resolved

DavyLandman added enhancement New feature or request and removed bug Something isn't working labels Feb 6, 2024

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Slow start of REPL when a pom file is present #187

Slow start of REPL when a pom file is present #187

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 3, 2022

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 3, 2022

jurgenvinju commented Oct 3, 2022 •

edited

Loading

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

jurgenvinju commented Oct 5, 2022

Slow start of REPL when a pom file is present #187

Slow start of REPL when a pom file is present #187

Comments

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 • edited Loading

jurgenvinju commented Oct 3, 2022 • edited Loading

jurgenvinju commented Oct 3, 2022

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 • edited Loading

jurgenvinju commented Oct 3, 2022

jurgenvinju commented Oct 3, 2022 • edited Loading

DavyLandman commented Oct 3, 2022

DavyLandman commented Oct 3, 2022 • edited Loading

jurgenvinju commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

jurgenvinju commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

DavyLandman commented Oct 4, 2022

jurgenvinju commented Oct 5, 2022

DavyLandman commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 3, 2022 •

edited

Loading

DavyLandman commented Oct 3, 2022 •

edited

Loading

jurgenvinju commented Oct 3, 2022 •

edited

Loading

DavyLandman commented Oct 3, 2022 •

edited

Loading