Open Zeckie opened 1 year ago
@Zeckie this typically occurs because there was some issue in the Python configuration needed to start the language server.
The logs just say it wasn't possible to start it up... please make sure that you have the Language Server Python
properly configured pointing to the Python you want to use:
i.e.:
If you still can't get it to work, I'll need some more logging to be able to diagnose it better. In that case, please follow the steps from:
I have a few different projects I use with PyCharm, each having their own poetry venv (the main python installation does not have many packages installed). I had configured the Robot Framework (Project)
settings, and only for the projects I was using (some had nothing to do with Robot Framework). I had not configured the Global settings, but have configured them now to point to a venv with robotframework.
It now seems to be intermittently working - if I restart (making no changes to code, settings etc).
idea.log from one restart contains:
2022-12-05 20:33:39,109 [ 37327] INFO - #r.r.i.RobotFrameworkLanguage - Command:
[C:\Users\Zeckie\AppData\Local\pypoetry\Cache\virtualenvs\myproj-eDhtWMG6-py3.10\Scripts\python.exe, -u, C:\Users\Zeckie\AppData\Local\JetBrains\apps\PyCharm-C\ch-0\222.4459.20.plugins\robotframework-intellij\lib\robotframework_ls\__main__.py, -vv, --log-file=C:\Users\Zeckie\robot_logs\ls-myproj.log]
PYTHONPATH:
C:\Users\Zeckie\AppData\Local\JetBrains\apps\PyCharm-C\ch-0\222.4459.20.plugins\robotframework-intellij\lib
ROBOTFRAMEWORK_LS_USER_HOME:
null
2022-12-05 20:33:39,113 [ 37331] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Starting server process.
2022-12-05 20:33:39,150 [ 37368] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Server process started Process[pid=27380, exitValue="not exited"].
Stderr may be seen at:
C:\Users\Zeckie\.robotframework-ls\.intellij-rf-ls-2022-12-05-27380.log
2022-12-05 20:33:39,150 [ 37368] INFO - #r.l.i.InternalConnection - Setting up language server communication.
2022-12-05 20:33:39,205 [ 37423] INFO - #r.l.i.InternalConnection - Sending initialize message to language server.
2022-12-05 20:33:54,347 [ 52565] INFO - #r.l.i.InternalConnection - Initialize was not received from the language server in the expected timeout.
2022-12-05 20:33:54,348 [ 52566] INFO - #r.l.i.InternalConnection - null
java.util.concurrent.TimeoutException
at java.base/java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1960)
at java.base/java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2095)
at robocorp.lsp.intellij.InternalConnection.start(LanguageServerCommunication.java:192)
at robocorp.lsp.intellij.LanguageServerCommunication.startInternalConnection(LanguageServerCommunication.java:388)
at robocorp.lsp.intellij.LanguageServerCommunication.<init>(LanguageServerCommunication.java:374)
at robocorp.lsp.intellij.LanguageServerManager.start(LanguageServerManager.java:74)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:67)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:25)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.lambda$createEditor$2(EditorFactoryImpl.java:209)
at com.intellij.openapi.extensions.impl.ExtensionProcessingHelper.forEachExtensionSafe(ExtensionProcessingHelper.java:34)
at com.intellij.openapi.extensions.ExtensionPointName.forEachExtensionSafe(ExtensionPointName.java:46)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:209)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:162)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.createEditor(TextEditorImpl.java:251)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.<init>(TextEditorImpl.java:51)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorImpl.<init>(PsiAwareTextEditorImpl.java:40)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorProvider.createEditor(PsiAwareTextEditorProvider.java:31)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.createComposite(FileEditorManagerImpl.java:1087)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.openFileImpl4Edt(FileEditorManagerImpl.java:988)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$17(FileEditorManagerImpl.java:961)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.runBulkTabChange(FileEditorManagerImpl.java:1698)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$18(FileEditorManagerImpl.java:960)
at com.intellij.openapi.application.TransactionGuardImpl.runWithWritingAllowed(TransactionGuardImpl.java:209)
at com.intellij.openapi.application.TransactionGuardImpl.access$100(TransactionGuardImpl.java:21)
at com.intellij.openapi.application.TransactionGuardImpl$1.run(TransactionGuardImpl.java:191)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.openapi.application.impl.ApplicationImpl$3.run(ApplicationImpl.java:513)
at com.intellij.openapi.application.impl.LaterInvocator$1.run(LaterInvocator.java:95)
at com.intellij.openapi.application.impl.FlushQueue.doRun(FlushQueue.java:75)
at com.intellij.openapi.application.impl.FlushQueue.runNextEvent(FlushQueue.java:118)
at com.intellij.openapi.application.impl.FlushQueue.flushNow(FlushQueue.java:42)
at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:318)
at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:779)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:730)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:724)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:399)
at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:86)
at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:749)
at com.intellij.ide.IdeEventQueue.defaultDispatchEvent(IdeEventQueue.java:918)
at com.intellij.ide.IdeEventQueue._dispatchEvent(IdeEventQueue.java:766)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$6(IdeEventQueue.java:450)
at com.intellij.openapi.progress.impl.CoreProgressManager.computePrioritized(CoreProgressManager.java:791)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$7(IdeEventQueue.java:449)
at com.intellij.openapi.application.TransactionGuardImpl.performActivity(TransactionGuardImpl.java:105)
at com.intellij.ide.IdeEventQueue.performActivity(IdeEventQueue.java:624)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$8(IdeEventQueue.java:447)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:493)
at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:207)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:128)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:117)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:113)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:105)
at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:92)
2022-12-05 20:33:54,351 [ 52569] SEVERE - #r.l.i.InternalConnection - null
java.util.concurrent.TimeoutException
at java.base/java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1960)
at java.base/java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2095)
at robocorp.lsp.intellij.InternalConnection.start(LanguageServerCommunication.java:192)
at robocorp.lsp.intellij.LanguageServerCommunication.startInternalConnection(LanguageServerCommunication.java:388)
at robocorp.lsp.intellij.LanguageServerCommunication.<init>(LanguageServerCommunication.java:374)
at robocorp.lsp.intellij.LanguageServerManager.start(LanguageServerManager.java:74)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:67)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:25)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.lambda$createEditor$2(EditorFactoryImpl.java:209)
at com.intellij.openapi.extensions.impl.ExtensionProcessingHelper.forEachExtensionSafe(ExtensionProcessingHelper.java:34)
at com.intellij.openapi.extensions.ExtensionPointName.forEachExtensionSafe(ExtensionPointName.java:46)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:209)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:162)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.createEditor(TextEditorImpl.java:251)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.<init>(TextEditorImpl.java:51)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorImpl.<init>(PsiAwareTextEditorImpl.java:40)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorProvider.createEditor(PsiAwareTextEditorProvider.java:31)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.createComposite(FileEditorManagerImpl.java:1087)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.openFileImpl4Edt(FileEditorManagerImpl.java:988)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$17(FileEditorManagerImpl.java:961)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.runBulkTabChange(FileEditorManagerImpl.java:1698)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$18(FileEditorManagerImpl.java:960)
at com.intellij.openapi.application.TransactionGuardImpl.runWithWritingAllowed(TransactionGuardImpl.java:209)
at com.intellij.openapi.application.TransactionGuardImpl.access$100(TransactionGuardImpl.java:21)
at com.intellij.openapi.application.TransactionGuardImpl$1.run(TransactionGuardImpl.java:191)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.openapi.application.impl.ApplicationImpl$3.run(ApplicationImpl.java:513)
at com.intellij.openapi.application.impl.LaterInvocator$1.run(LaterInvocator.java:95)
at com.intellij.openapi.application.impl.FlushQueue.doRun(FlushQueue.java:75)
at com.intellij.openapi.application.impl.FlushQueue.runNextEvent(FlushQueue.java:118)
at com.intellij.openapi.application.impl.FlushQueue.flushNow(FlushQueue.java:42)
at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:318)
at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:779)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:730)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:724)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:399)
at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:86)
at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:749)
at com.intellij.ide.IdeEventQueue.defaultDispatchEvent(IdeEventQueue.java:918)
at com.intellij.ide.IdeEventQueue._dispatchEvent(IdeEventQueue.java:766)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$6(IdeEventQueue.java:450)
at com.intellij.openapi.progress.impl.CoreProgressManager.computePrioritized(CoreProgressManager.java:791)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$7(IdeEventQueue.java:449)
at com.intellij.openapi.application.TransactionGuardImpl.performActivity(TransactionGuardImpl.java:105)
at com.intellij.ide.IdeEventQueue.performActivity(IdeEventQueue.java:624)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$8(IdeEventQueue.java:447)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:493)
at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:207)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:128)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:117)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:113)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:105)
at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:92)
2022-12-05 20:33:54,353 [ 52571] SEVERE - #r.l.i.InternalConnection - PyCharm 2022.2.4 Build #PC-222.4459.20
2022-12-05 20:33:54,354 [ 52572] SEVERE - #r.l.i.InternalConnection - JDK: 17.0.5; VM: OpenJDK 64-Bit Server VM; Vendor: JetBrains s.r.o.
2022-12-05 20:33:54,354 [ 52572] SEVERE - #r.l.i.InternalConnection - OS: Windows 10
2022-12-05 20:33:54,354 [ 52572] SEVERE - #r.l.i.InternalConnection - Plugin to blame: Robot Framework Language Server version: 1.5.0
2022-12-05 20:33:54,357 [ 52575] SEVERE - #r.l.i.LanguageServerCommunication - Expected state to be initialized. Current state is crashed. Last message: Exception: java.util.concurrent.TimeoutExceptionwhile initializing. Marking as crashed.
java.lang.Throwable: Expected state to be initialized. Current state is crashed. Last message: Exception: java.util.concurrent.TimeoutExceptionwhile initializing. Marking as crashed.
at com.intellij.openapi.diagnostic.Logger.error(Logger.java:184)
at robocorp.lsp.intellij.EditorUtils.logError(EditorUtils.java:210)
at robocorp.lsp.intellij.LanguageServerCommunication.startInternalConnection(LanguageServerCommunication.java:397)
at robocorp.lsp.intellij.LanguageServerCommunication.<init>(LanguageServerCommunication.java:374)
at robocorp.lsp.intellij.LanguageServerManager.start(LanguageServerManager.java:74)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:67)
at robocorp.lsp.intellij.LanguageServerEditorListener.editorCreated(LanguageServerEditorListener.java:25)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.lambda$createEditor$2(EditorFactoryImpl.java:209)
at com.intellij.openapi.extensions.impl.ExtensionProcessingHelper.forEachExtensionSafe(ExtensionProcessingHelper.java:34)
at com.intellij.openapi.extensions.ExtensionPointName.forEachExtensionSafe(ExtensionPointName.java:46)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:209)
at com.intellij.openapi.editor.impl.EditorFactoryImpl.createEditor(EditorFactoryImpl.java:162)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.createEditor(TextEditorImpl.java:251)
at com.intellij.openapi.fileEditor.impl.text.TextEditorImpl.<init>(TextEditorImpl.java:51)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorImpl.<init>(PsiAwareTextEditorImpl.java:40)
at com.intellij.openapi.fileEditor.impl.text.PsiAwareTextEditorProvider.createEditor(PsiAwareTextEditorProvider.java:31)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.createComposite(FileEditorManagerImpl.java:1087)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.openFileImpl4Edt(FileEditorManagerImpl.java:988)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$17(FileEditorManagerImpl.java:961)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.runBulkTabChange(FileEditorManagerImpl.java:1698)
at com.intellij.openapi.fileEditor.impl.FileEditorManagerImpl.lambda$openFileImpl4$18(FileEditorManagerImpl.java:960)
at com.intellij.openapi.application.TransactionGuardImpl.runWithWritingAllowed(TransactionGuardImpl.java:209)
at com.intellij.openapi.application.TransactionGuardImpl.access$100(TransactionGuardImpl.java:21)
at com.intellij.openapi.application.TransactionGuardImpl$1.run(TransactionGuardImpl.java:191)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.openapi.application.impl.ApplicationImpl$3.run(ApplicationImpl.java:513)
at com.intellij.openapi.application.impl.LaterInvocator$1.run(LaterInvocator.java:95)
at com.intellij.openapi.application.impl.FlushQueue.doRun(FlushQueue.java:75)
at com.intellij.openapi.application.impl.FlushQueue.runNextEvent(FlushQueue.java:118)
at com.intellij.openapi.application.impl.FlushQueue.flushNow(FlushQueue.java:42)
at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:318)
at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:779)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:730)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:724)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:399)
at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:86)
at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:749)
at com.intellij.ide.IdeEventQueue.defaultDispatchEvent(IdeEventQueue.java:918)
at com.intellij.ide.IdeEventQueue._dispatchEvent(IdeEventQueue.java:766)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$6(IdeEventQueue.java:450)
at com.intellij.openapi.progress.impl.CoreProgressManager.computePrioritized(CoreProgressManager.java:791)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$7(IdeEventQueue.java:449)
at com.intellij.openapi.application.TransactionGuardImpl.performActivity(TransactionGuardImpl.java:105)
at com.intellij.ide.IdeEventQueue.performActivity(IdeEventQueue.java:624)
at com.intellij.ide.IdeEventQueue.lambda$dispatchEvent$8(IdeEventQueue.java:447)
at com.intellij.openapi.application.impl.ApplicationImpl.runIntendedWriteActionOnCurrentThread(ApplicationImpl.java:881)
at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:493)
at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:207)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:128)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:117)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:113)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:105)
at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:92)
2022-12-05 20:33:54,358 [ 52576] SEVERE - #r.l.i.LanguageServerCommunication - PyCharm 2022.2.4 Build #PC-222.4459.20
2022-12-05 20:33:54,359 [ 52577] SEVERE - #r.l.i.LanguageServerCommunication - JDK: 17.0.5; VM: OpenJDK 64-Bit Server VM; Vendor: JetBrains s.r.o.
2022-12-05 20:33:54,359 [ 52577] SEVERE - #r.l.i.LanguageServerCommunication - OS: Windows 10
2022-12-05 20:33:54,359 [ 52577] SEVERE - #r.l.i.LanguageServerCommunication - Plugin to blame: Robot Framework Language Server version: 1.5.0
2022-12-05 20:33:54,359 [ 52577] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Starting server process.
2022-12-05 20:33:54,392 [ 52610] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Server process started Process[pid=41552, exitValue="not exited"].
Stderr may be seen at:
C:\Users\Zeckie\.robotframework-ls\.intellij-rf-ls-2022-12-05-41552.log
ls-myproj.lsp.3180.log
contained:
lsp: 2022-12-05 20:33:48 UTC pid: 3180 - MainThread - INFO - robotframework_ls.__main__
Arguments: ['-vv', '--log-file=C:\\Users\\Zeckie\\robot_logs\\ls-myproj.log']
lsp: 2022-12-05 20:33:48 UTC pid: 3180 - MainThread - INFO - robotframework_ls.__main__
Python: 3.10.6 (tags/v3.10.6:9c7b4bd, Aug 1 2022, 21:53:49) [MSC v.1932 64 bit (AMD64)] - lsp: 1.5.0 (<module 'robotframework_ls' from 'C:\\Users\\Zeckie\\AppData\\Local\\JetBrains\\apps\\PyCharm-C\\ch-0\\222.4459.20.plugins\\robotframework-intellij\\lib\\robotframework_ls\\__init__.py'>) - platform: win32 - sys.prefix: C:\Users\Zeckie\AppData\Local\pypoetry\Cache\virtualenvs\myproj-eDhtWMG6-py3.10 - sys.executable: C:\Users\Zeckie\AppData\Local\pypoetry\Cache\virtualenvs\myproj-eDhtWMG6-py3.10\Scripts\python.exe
lsp: 2022-12-05 20:33:48 UTC pid: 3180 - MainThread - INFO - robotframework_ls.__main__
CPUs: 6
lsp: 2022-12-05 20:33:48 UTC pid: 3180 - MainThread - INFO - robocorp_ls_core.python_ls
Starting RobotFrameworkLanguageServer IO language server. pid: 3180
lsp: 2022-12-05 20:33:49 UTC pid: 3180 - MainThread - DEBUG - robotframework_ls.robotframework_ls_impl
Cache dir: C:\Users\Zeckie\.robotframework-ls\.cache
lsp: 2022-12-05 20:33:49 UTC pid: 3180 - MainThread - INFO - robotframework_ls.robotframework_ls_impl
Using watch implementation: watchdog (customize with ROBOTFRAMEWORK_LS_WATCH_IMPL environment variable)
lsp: 2022-12-05 20:33:49 UTC pid: 3180 - MainThread - INFO - robocorp_ls_core.remote_fs_observer_impl
Initializing Remote FS Observer with the following args: ['C:\\Users\\Zeckie\\AppData\\Local\\pypoetry\\Cache\\virtualenvs\\myproj-eDhtWMG6-py3.10\\Scripts\\python.exe', '-u', 'C:\\Users\\Zeckie\\AppData\\Local\\JetBrains\\apps\\PyCharm-C\\ch-0\\222.4459.20.plugins\\robotframework-intellij\\lib\\robotframework_ls\\vendored\\robocorp_ls_core\\remote_fs_observer__main__.py', '--log-file=C:\\Users\\Zeckie\\robot_logs\\ls-myproj.log', '-vv']
lsp: 2022-12-05 20:33:55 UTC pid: 3180 - MainThread - DEBUG - robocorp_ls_core.jsonrpc.streams
Writing: {'command': 'initialize', 'backend': 'watchdog', 'extensions': ('.py', '.libspec', 'robot', '.resource'), 'parent_pid': 3180}
lsp: 2022-12-05 20:33:55 UTC pid: 3180 - _JsonRpcStreamReaderThread (closed: False) - DEBUG - robocorp_ls_core.jsonrpc.streams
Read: {"command": "ack_initialize"}
I noticed that the process ids mentioned in the logs (such as 29956, 41552, 27380) are for python processes that are still running (python.exe from the venv, running PyCharm-C\ch-0\222.4459.20.plugins\robotframework-intellij\lib\robotframework_ls\__main__.py
), and each of those has a child python.exe (the python installation running the same script), each of those has children (running ...\robocorp_ls_core\remote_fs_observer__main__.py
). So while the plugin thinks they have crashed, they are at least still running.
The logfiles mentioned (in .robotframework-ls
) are all 0 bytes (there are some old logs there too that contain some ModuleNotFoundError
errors for my project's code)
idea.log from one time it worked:
2022-12-05 21:45:09,361 [ 13541] WARN - #c.i.d.PerformanceWatcherImpl - UI was frozen for 6198ms, details saved to C:\Users\Zeckie\AppData\Local\JetBrains\PyCharmCE2022.2\log\threadDumps-freeze-20221205-214508-PC-222.4459.20-6sec
2022-12-05 21:45:14,045 [ 18225] INFO - #r.r.i.RobotFrameworkLanguage - Command:
[C:\Users\Zeckie\AppData\Local\pypoetry\Cache\virtualenvs\myproj-eDhtWMG6-py3.10\Scripts\python.exe, -u, C:\Users\Zeckie\AppData\Local\JetBrains\apps\PyCharm-C\ch-0\222.4459.20.plugins\robotframework-intellij\lib\robotframework_ls\__main__.py, -vv, --log-file=C:\Users\Zeckie\robot_logs\ls-myproj.log]
PYTHONPATH:
C:\Users\Zeckie\AppData\Local\JetBrains\apps\PyCharm-C\ch-0\222.4459.20.plugins\robotframework-intellij\lib
ROBOTFRAMEWORK_LS_USER_HOME:
null
2022-12-05 21:45:14,050 [ 18230] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Starting server process.
2022-12-05 21:45:14,088 [ 18268] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Server process started Process[pid=39408, exitValue="not exited"].
Stderr may be seen at:
C:\Users\Zeckie\.robotframework-ls\.intellij-rf-ls-2022-12-05-39408.log
2022-12-05 21:45:14,088 [ 18268] INFO - #r.l.i.InternalConnection - Setting up language server communication.
2022-12-05 21:45:14,138 [ 18318] INFO - #r.l.i.InternalConnection - Sending initialize message to language server.
2022-12-05 21:45:27,993 [ 32173] INFO - #r.l.i.InternalConnection - Initialize properly received from the language server.
2022-12-05 21:45:28,020 [ 32200] WARN - #c.i.d.PerformanceWatcherImpl - UI was frozen for 18641ms, details saved to C:\Users\Zeckie\AppData\Local\JetBrains\PyCharmCE2022.2\log\threadDumps-freeze-20221205-214514-PC-222.4459.20-18sec
2022-12-05 21:45:29,393 [ 33573] INFO - #c.i.w.i.i.WorkspaceModelImpl - Load workspace model from cache in 115 ms
2022-12-05 21:45:29,422 [ 33602] INFO - #c.i.o.v.i.p.NewMappings - VCS Root: [Git] - [C:/Users/Zeckie/git/anotherproj]
2022-12-05 21:45:29,436 [ 33616] INFO - #c.i.p.c.ProjectCodeStyleSettingsManager - Initialized from default code style settings.
2022-12-05 21:45:29,492 [ 33672] INFO - #c.i.o.w.StatusBar - Factory is not registered as `com.intellij.statusBarWidgetFactory` extension: com.intellij.openapi.vcs.changes.committed.IncomingChangesIndicatorFactory
It looks like this time, the first attempt timed out, but the second one succeeded:
Stderr may be seen at:
C:\Users\Zeckie\.robotframework-ls\.intellij-rf-ls-2022-12-05-15888.log
2022-12-05 22:09:31,979 [ 32606] INFO - #r.l.i.InternalConnection - Setting up language server communication.
2022-12-05 22:09:32,040 [ 32667] INFO - #r.l.i.InternalConnection - Sending initialize message to language server.
2022-12-05 22:09:47,217 [ 47844] INFO - #r.l.i.InternalConnection - Initialize was not received from the language server in the expected timeout.
2022-12-05 22:09:47,218 [ 47845] INFO - #r.l.i.InternalConnection - null
...
2022-12-05 22:09:47,232 [ 47859] SEVERE - #r.l.i.LanguageServerCommunication - PyCharm 2022.2.4 Build #PC-222.4459.20
2022-12-05 22:09:47,233 [ 47860] SEVERE - #r.l.i.LanguageServerCommunication - JDK: 17.0.5; VM: OpenJDK 64-Bit Server VM; Vendor: JetBrains s.r.o.
2022-12-05 22:09:47,233 [ 47860] SEVERE - #r.l.i.LanguageServerCommunication - OS: Windows 10
2022-12-05 22:09:47,233 [ 47860] SEVERE - #r.l.i.LanguageServerCommunication - Plugin to blame: Robot Framework Language Server version: 1.5.0
2022-12-05 22:09:47,233 [ 47860] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Starting server process.
2022-12-05 22:09:47,268 [ 47895] INFO - #r.l.i.LanguageServerDefinition$LanguageServerStreams - Server process started Process[pid=39056, exitValue="not exited"].
Stderr may be seen at:
C:\Users\Zeckie\.robotframework-ls\.intellij-rf-ls-2022-12-05-39056.log
2022-12-05 22:09:47,268 [ 47895] INFO - #r.l.i.InternalConnection - Setting up language server communication.
2022-12-05 22:09:47,271 [ 47898] INFO - #r.l.i.InternalConnection - Sending initialize message to language server.
2022-12-05 22:09:57,807 [ 58434] INFO - #r.l.i.InternalConnection - Initialize properly received from the language server.
2022-12-05 22:09:57,833 [ 58460] WARN - #c.i.d.PerformanceWatcherImpl - UI was frozen for 34275ms, details saved to C:\Users\Zeckie\AppData\Local\JetBrains\PyCharmCE2022.2\log\threadDumps-freeze-20221205-220928-PC-222.4459.20-34sec
2022-12-05 22:09:58,741 [ 59368] INFO - #c.i.o.e.s.p.m.ExternalProjectsDataStorage - Load external projects data in 3 millis (read time: 2)
Is there any way to increase the timeout to try to avoid the error Initialize was not received from the language server in the expected timeout.
?
Describe the bug The PyCharm LSP plugin reports that the language server has crashed,
when a specific robot file is openwhen pycharm is started.To Reproduce Steps to reproduce the behavior:
with contentsTest Cases first second Foo [Tags] bar aaa bbb
Keywords My Template [Arguments] ${foo} ${bar} No Operation
robotframework 6.0.1 robotframework-pythonlibcore 4.0.0 robotframework-robocop 2.6.0 robotframework-tidy 3.3.2