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

When picking a Boolean value via the dropdown doesn't recalculate correctly. #6221

Closed
jdunkerley opened this issue Apr 6, 2023 · 35 comments · Fixed by #6548
Closed

When picking a Boolean value via the dropdown doesn't recalculate correctly. #6221

jdunkerley opened this issue Apr 6, 2023 · 35 comments · Fixed by #6548
Assignees
Labels
--bug Type: bug -compiler d-intermediate Difficulty: some prior knowledge required p-high Should be completed in the next sprint

Comments

@jdunkerley
Copy link
Member

2023-04-06_16-52-53.mp4

When I choose a Boolean value from a dropdown it doesn't appear to recalculate correctly.
When I then change the value it then recalculates correctly. And when I change back to the first value it calculates fine.

@github-project-automation github-project-automation bot moved this to ❓New in Issues Board Apr 6, 2023
@4e6 4e6 added --bug Type: bug -gui -viz labels Apr 7, 2023
@4e6 4e6 assigned 4e6 and farmaazon and unassigned 4e6 Apr 7, 2023
@4e6 4e6 removed the triage label Apr 7, 2023
@farmaazon
Copy link
Contributor

  1. To be checked if it's fixed by Fix: when default visualization is displayed, its entry is not present in the chooser list. #6209
  2. If not, check if the engine is sending us the update.

@farmaazon farmaazon added d-intermediate Difficulty: some prior knowledge required p-high Should be completed in the next sprint labels Apr 17, 2023
@farmaazon farmaazon moved this from ❓New to 📤 Backlog in Issues Board Apr 17, 2023
@sylwiabr sylwiabr added this to the Design Partners milestone Apr 17, 2023
@sylwiabr
Copy link
Member

it's still valid in the package from 9125568 commit. This issue appear just when user is choosing the value from the drop-down for the first time.

@sylwiabr sylwiabr assigned vitvakatu and unassigned farmaazon Apr 21, 2023
@MichaelMauderer MichaelMauderer moved this from 📤 Backlog to 🔧 Implementation in Issues Board Apr 21, 2023
@MichaelMauderer
Copy link
Contributor

@sylwiabr @jdunkerley: Do you have a full minimal example of this? I just want to double-check that I am looking at the right behaviour, as it seems, the API used in the original video has been changed.

@MichaelMauderer
Copy link
Contributor

My reproduction with the node "A B X".words seems to indicate an issue on the engine side. After selecting a boolean from the widget, the widget disappears and the engine no longer evaluates the graph. The error message that appears in the dev console is:

[[warn] [2023-04-21T14:46:02.574Z] [enso] Execution of function main failed (class org.enso.interpreter.runtime.callable.atom.BoxingAtom cannot be cast to class java.lang.Boolean (org.enso.interpreter.runtime.callable.atom.BoxingAtom is in unnamed module of loader com.oracle.graalvm.locator.GraalVMLocator$GuestLangToolsLoader @4fe875be; java.lang.Boolean is in module java.base of loader 'bootstrap')).](<Execution failed in context eeee848a-fd67-41dc-8fd6-2148ec9e8be6. Error: Execution of function main failed (class org.enso.interpreter.runtime.callable.atom.BoxingAtom cannot be cast to class java.lang.Boolean (org.enso.interpreter.runtime.callable.atom.BoxingAtom is in unnamed module of loader com.oracle.graalvm.locator.GraalVMLocator$GuestLangToolsLoader @30b2b76f; java.lang.Boolean is in module java.base of loader 'bootstrap'))..>)
Peek.2023-04-23.14-41.mp4

@JaroslavTulach @4e6

@JaroslavTulach
Copy link
Member

JaroslavTulach commented Apr 24, 2023

[[warn] ...BoxingAtom cannot be cast to class java.lang.Boolean

Yes, that message indicates the problem is in some builtin. Too bad we don't have a JVM stacktrace. It'd be better if such errors didn't get "swallowed". Is there an option to use in the IDE to run the engine in more verbose mode, @4e6? Or did you copy just the message and not the stacktrace, Michael?

Michael, what's your final Enso code? Simple:

from Standard.Base import all

main = "A B C".words Boolean.True

runs OK for me from CLI and produces ['A', ' ', 'B', ' ', 'C']. Do you get the same error when you run your code from CLI or when you restart the IDE?

@sylwiabr
Copy link
Member

@MichaelMauderer The API is still the same. I can not unfortunately reproduce it with dev console opened.
Zrzut ekranu 2023-04-24 o 08 22 21
Zrzut ekranu 2023-04-24 o 08 23 58

@JaroslavTulach this is happening just in IDE I think.

@sylwiabr
Copy link
Member

sylwiabr commented Apr 24, 2023

Nagranie.z.ekranu.2023-04-24.o.08.25.30.mov

Here is the video with console opened @MichaelMauderer (starts around 55s - sorry for not cutting it)

@JaroslavTulach
Copy link
Member

Another thought: I am surprised the IDE uses Boolean.True and not just True. There should be little difference since

got fixed, but if there is from Standard.Base import all, then simple True shall be available and certainly more readable than Boolean.True.

@MichaelMauderer
Copy link
Contributor

@JaroslavTulach Sorry this was a false alarm. My dev build was stuck with an old engine (which is also why I saw a different API for replace). The IDE still uses Boolean.True/Boolean.False though.

I can repro this now, and it seems the IDE is not sending a message to the backend. So investigating this on the IDE side now.

@JaroslavTulach
Copy link
Member

this was a false alarm. My dev build was stuck with an old engine

Probably it was an older engine than 2023.1.1-nightly.2023.3.28 when #6090 got fixed. Good to know this is not an issue.

@MichaelMauderer
Copy link
Contributor

So, I've traced what happens from the IDE side, and it seems this is an engine issue after all.

When looking at the following project

image

from Standard.Base import all
from Standard.Base.Data.Boolean import Boolean
import Standard.Visualization

main = 
    text2 = "  B   B   B   "
    text1 = "B"
    text3 = "@"
    operator2 = text2.replace text1 text3 (Case_Sensitivity.Insensitive)

when I choose True from the dropdown for only_first, the IDE sends a text edit to the engine that looks like this:

{
  "jsonrpc": "2.0",
  "id": 19,
  "method": "text/applyEdit",
  "params": {
    "edit": {
      "edits": [
        {
          "range": {
            "end": {
              "character": 4663,
              "line": 16
            },
            "start": {
              "character": 4326,
              "line": 16
            }
          },
          "text": "74c31314-e1ed-4ce2-b425-7d25d18b6ef7\"],[{\"index\":{\"value\":251},\"size\":{\"value\":7}},\"c4a1412c-50dc-4b1a-bdd2-0533e2dc5a65\"],[{\"index\":{\"value\":258},\"size\":{\"value\":1}},\"b6137c2e-dc95-4929-8271-b312636e69a1\"],[{\"index\":{\"value\":259},\"size\":{\"value\":4}},\"eeee2098-f25d-4453-ac5a-7bb9a82e8a3d\"],[{\"index\":{\"value\":251},\"size\":{\"value\":12}},\"a5b1358a-c151-4b33-a1a6-f1f89ddcb929\"],[{\"index\":{\"value\":194},\"size\":{\"value\":69}},\"a8d000c2-2494-44e0-91ad-b1c83a14a396\"],[{\"index\":{\"value\":182},\"size\":{\"value\":81}},\"a29e59ad-010f-46be-ae5a-f2d7fae91bc0\"],[{\"index\":{\"value\":116},\"size\":{\"value\":147}},\"8d3c24b0-8bbd-44a0-a527-0dc646a1a513\"],[{\"index\":{\"value\":109},\"size\":{\"value\":154}},\"9f904285-40a4-49a2-8a84-c2b824e12867\"],[{\"index\":{\"value\":0},\"size\":{\"value\":266"
        },
        {
          "range": {
            "end": {
              "character": 72,
              "line": 9
            },
            "start": {
              "character": 72,
              "line": 9
            }
          },
          "text": " Boolean.True"
        }
      ],
      "newVersion": "db09b1b2004180e39729ee5ec307bb79feb2aa4a7986570c6b161656",
      "oldVersion": "c2f4e894a32141c8a7ed26fb4594ad9de62ab9932bd815c284180cc5",
      "path": {
        "rootId": "43625384-ee22-4e5f-a3a0-a3a22087926e",
        "segments": [
          "src",
          "Main.enso"
        ]
      }
    },
    "execute": true
  }
}

Which looks fine to me.

But after execution ends, we only get an expression update that looks like this

{
  "jsonrpc": "2.0",
  "method": "executionContext/expressionUpdates",
  "params": {
    "contextId": "522248c3-09a3-45cb-a79b-5e29132800ed",
    "updates": [
      {
        "expressionId": "7140479b-b697-4418-ae59-ad36b25fe8ee",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 372677
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "a647c160-59e7-43b7-810b-40b7a95b687e",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 245446
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "678ba098-e18a-4440-a82b-cc51b6f9a602",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 243812
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "a29e59ad-010f-46be-ae5a-f2d7fae91bc0",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 278809
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "8d3c24b0-8bbd-44a0-a527-0dc646a1a513",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 4070250
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      }
    ]
  }
}

Which does not seem quite right as everything in there is Nothing.

Also, when I then remove the Boolean.True via dropdown, the IDE sends the following edit to the engine

{
  "jsonrpc": "2.0",
  "id": 17,
  "method": "text/applyEdit",
  "params": {
    "edit": {
      "edits": [
        {
          "range": {
            "end": {
              "character": 5041,
              "line": 16
            },
            "start": {
              "character": 4326,
              "line": 16
            }
          },
          "text": "a8d000c2-2494-44e0-91ad-b1c83a14a396\"],[{\"index\":{\"value\":182},\"size\":{\"value\":68}},\"a29e59ad-010f-46be-ae5a-f2d7fae91bc0\"],[{\"index\":{\"value\":116},\"size\":{\"value\":134}},\"3205bba4-575f-45d3-a6b7-8fbfca891309\"],[{\"index\":{\"value\":109},\"size\":{\"value\":141}},\"9f904285-40a4-49a2-8a84-c2b824e12867\"],[{\"index\":{\"value\":0},\"size\":{\"value\":253}},\"0d710679-2158-495c-9bb8-5529efc0cc23"
        },
        {
          "range": {
            "end": {
              "character": 85,
              "line": 9
            },
            "start": {
              "character": 72,
              "line": 9
            }
          },
          "text": ""
        }
      ],
      "newVersion": "c2f4e894a32141c8a7ed26fb4594ad9de62ab9932bd815c284180cc5",
      "oldVersion": "2c0ec4f8dd119551f2cae295c286ecd0c8e991f7749a816b08444743",
      "path": {
        "rootId": "43625384-ee22-4e5f-a3a0-a3a22087926e",
        "segments": [
          "src",
          "Main.enso"
        ]
      }
    },
    "execute": true
  }
}

which then results in the following expression update

{
  "jsonrpc": "2.0",
  "method": "executionContext/expressionUpdates",
  "params": {
    "contextId": "522248c3-09a3-45cb-a79b-5e29132800ed",
    "updates": [
      {
        "expressionId": "9f904285-40a4-49a2-8a84-c2b824e12867",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "a29e59ad-010f-46be-ae5a-f2d7fae91bc0",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "0691e0c9-bba2-43bb-b5e3-04ad53cfe2f9",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "9a52a7b6-6efd-48c4-88dc-8e9ee6664602",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "a8d000c2-2494-44e0-91ad-b1c83a14a396",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "9033b653-c992-461f-9407-6226c5d84be3",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "c860fadd-17a9-4856-ac5d-5531ba04379d",
        "type": null,
        "methodPointer": null,
        "profilingInfo": [],
        "fromCache": true,
        "payload": {
          "type": "Pending",
          "message": null,
          "progress": null
        }
      },
      {
        "expressionId": "7140479b-b697-4418-ae59-ad36b25fe8ee",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 294759
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "a647c160-59e7-43b7-810b-40b7a95b687e",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 173789
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "678ba098-e18a-4440-a82b-cc51b6f9a602",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 183017
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "e9ee4d72-6cbb-4f52-a929-f4061aa56966",
        "type": "Standard.Builtins.Main.Unresolved_Symbol",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 3266
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "481e6db2-7e1a-4535-9ceb-ffa1935997ea",
        "type": "Standard.Base.Data.Text.Text",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 78439
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "6038638a-c32b-48de-97ee-313c18c8e2df",
        "type": "Standard.Base.Data.Text.Text",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 117082
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "b923f692-1b33-4cd6-8c1f-bc0d1b346a12",
        "type": "Standard.Base.Data.Text.Text",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 51989
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "38c97975-c992-47e0-8eeb-218b2b8c4701",
        "type": "Standard.Builtins.Main.Unresolved_Symbol",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 4478
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "dbc2c858-3cfd-47b0-8c9c-f36041ec19a3",
        "type": "Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 4048
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "05b4147b-2e78-4ae6-81a1-7c65f5588ca2",
        "type": "Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity",
        "methodPointer": {
          "module": "Standard.Base.Data.Text.Case_Sensitivity",
          "definedOnType": "Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity",
          "name": "Insensitive"
        },
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 11988137
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "a8d000c2-2494-44e0-91ad-b1c83a14a396",
        "type": "Standard.Base.Data.Text.Text",
        "methodPointer": {
          "module": "Standard.Base.Data.Text.Extensions",
          "definedOnType": "Standard.Base.Data.Text.Text",
          "name": "replace"
        },
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 41558841
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "a29e59ad-010f-46be-ae5a-f2d7fae91bc0",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 47208055
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      },
      {
        "expressionId": "3205bba4-575f-45d3-a6b7-8fbfca891309",
        "type": "Standard.Base.Nothing.Nothing",
        "methodPointer": null,
        "profilingInfo": [
          {
            "ExecutionTime": {
              "nanoTime": 51503232
            }
          }
        ],
        "fromCache": false,
        "payload": {
          "type": "Value",
          "warnings": null
        }
      }
    ]
  }
}

Which looks more sensible as it actually contains type information.

Unfortunately, I'm not sure how to debug from here, as it appears that something is going when applying the edit on the engine side. Any ideas @JaroslavTulach @4e6 ?

@enso-bot
Copy link

enso-bot bot commented Apr 27, 2023

Michael Mauderer reports a new 🔴 DELAY for yesterday (2023-04-26):

Summary: There is 2 days delay in implementation of the When picking a Boolean value via the dropdown doesn't recalculate correctly. (#6221) task.
It will cause 0 days delay for the delivery of this weekly plan.

Delay Cause: Delayed due to work on other tasks (integration of Execution Environment and Triage)

@enso-bot
Copy link

enso-bot bot commented Apr 27, 2023

Michael Mauderer reports a new STANDUP for yesterday (2023-04-26):

Progress: Updated PR for #6179, created followup integration PR 6434. Worked on Triage and reproducing issues. Found that #6347 does not resolve this issue. It should be finished by 2023-04-28.

Next Day: Next day I will be working on the #6221 task. Finalize previous PRs and get back to investigating this issue.

@enso-bot
Copy link

enso-bot bot commented Apr 27, 2023

Michael Mauderer reports a new STANDUP for today (2023-04-27):

Progress: Worked on Triage. Continued investigation and traced the issue to (probably) the engine. Updated the task and requested help from the engine team. It should be finished by 2023-04-28.

Next Day: Next day I will be working on the #6221 task. Either continue investigation or choose next task if this has to go to the engine team.

@MichaelMauderer
Copy link
Contributor

MichaelMauderer commented Apr 28, 2023

This is the verbose log from the LS console when choosing the True value from the widget, when before nothing was selected. @4e6 @JaroslavTulach

debug] [2023-04-28T10:34:15.967Z] [org.enso.projectmanager.infrastructure.languageserver.LanguageServerSupervisor] now supervising Actor[akka://project-manager/user/language-server-registry/language-server-controller-306aec02-8554-4da8-86a7-0c8681f1bc5a/supervisor-language-server-306aec02-8554-4da8-86a7-0c8681f1bc5a/heartbeat-6b078c05-9a4e-473f-9122-f9598014778d#-1154945066]
[debug] [2023-04-28T10:34:15.967Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] started (org.enso.projectmanager.infrastructure.http.FanOutReceiver@2df0ab1f)
[debug] [2023-04-28T10:34:15.97Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Heartbeat connection initialized [Socket(127.0.0.1,49467)].
[debug] [2023-04-28T10:34:15.971Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] started (org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession@15329fbe)
[debug] [2023-04-28T10:34:15.971Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] now watched by Actor[akka://project-manager/system/Materializers/StreamSupervisor-0/$$Bc#-19996995]
[debug] [2023-04-28T10:34:15.977Z] [org.enso.jsonrpc.MessageHandlerSupervisor] started (org.enso.jsonrpc.MessageHandlerSupervisor@226c3963)
[debug] [2023-04-28T10:34:15.977Z] [org.enso.languageserver.protocol.json.JsonConnectionController] started (org.enso.languageserver.protocol.json.JsonConnectionController@535f3640)
[debug] [2023-04-28T10:34:15.977Z] [org.enso.jsonrpc.MessageHandler] started (org.enso.jsonrpc.MessageHandler@62cf2a03)
[debug] [2023-04-28T10:34:15.978Z] [org.enso.jsonrpc.MessageHandlerSupervisor] now supervising Actor[akka://language-server/user/message-handler-supervisor-3360b37d-d848-4cca-a9ff-95a18bad21e2/message-handler-d9dd51f7-6609-4658-94fb-16ae37d8e9d3#374782907]
[debug] [2023-04-28T10:34:15.98Z] [org.enso.jsonrpc.MessageHandlerSupervisor] now watched by Actor[akka://language-server/system/Materializers/StreamSupervisor-0/$$Tb#-243416273]
[debug] [2023-04-28T10:34:15.982Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Sending ping message to Socket(127.0.0.1,49467).
[trace] [2023-04-28T10:34:15.984Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: 
{ 
   "jsonrpc": "2.0",
   "method": "heartbeat/ping",
   "id": "5cb59688-1f68-4cae-886a-8609a9b29eca",
   "params": null
}
.
[debug] [2023-04-28T10:34:15.984Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-d9dd51f7-6609-4658-94fb-16ae37d8e9d3/$a#1729831693]
[debug] [2023-04-28T10:34:15.984Z] [org.enso.languageserver.requesthandler.monitoring.PingHandler] started (org.enso.languageserver.requesthandler.monitoring.PingHandler@e6fd5e0)
[debug] [2023-04-28T10:34:15.985Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Received correct pong message from Socket(127.0.0.1,49467).
[trace] [2023-04-28T10:34:15.984Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":"5cb59688-1f68-4cae-886a-8609a9b29eca","result":null}.
[debug] [2023-04-28T10:34:15.984Z] [org.enso.languageserver.requesthandler.monitoring.PingHandler] stopped
[debug] [2023-04-28T10:34:15.987Z] [org.enso.languageserver.protocol.json.JsonConnectionController] stopped
[debug] [2023-04-28T10:34:15.987Z] [org.enso.jsonrpc.MessageHandler] stopped
[debug] [2023-04-28T10:34:15.987Z] [org.enso.jsonrpc.MessageHandlerSupervisor] no longer watched by Actor[akka://language-server/system/Materializers/StreamSupervisor-0/$$Tb#-243416273]
[trace] [2023-04-28T10:34:16.8Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: {"jsonrpc":"2.0","id":20,"method":"text/applyEdit","params":{"edit":{"edits":[{"range":{"end":{"character":4663,"line":16},"start":{"character":4326,"line":16}},"text":"784e7fd6-c07c-4eeb-92cb-d696e07edbc8\"],[{\"index\":{\"value\":251},\"size\":{\"value\":7}},\"411db538-670d-49a7-81c4-e6d172db12fa\"],[{\"index\":{\"value\":258},\"size\":{\"value\":1}},\"a83f6213-5b8a-45ae-9034-b6d09cd1bbbc\"],[{\"index\":{\"value\":259},\"size\":{\"value\":4}},\"623c3592-3a03-4b39-8baf-f43140b8c59c\"],[{\"index\":{\"value\":251},\"size\":{\"value\":12}},\"b0a4429d-57d5-4e70-91da-1105c39403e6\"],[{\"index\":{\"value\":194},\"size\":{\"value\":69}},\"a8d000c2-2494-44e0-91ad-b1c83a14a396\"],[{\"index\":{\"value\":182},\"size\":{\"value\":81}},\"a29e59ad-010f-46be-ae5a-f2d7fae91bc0\"],[{\"index\":{\"value\":116},\"size\":{\"value\":147}},\"0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27\"],[{\"index\":{\"value\":109},\"size\":{\"value\":154}},\"9f904285-40a4-49a2-8a84-c2b824e12867\"],[{\"index\":{\"value\":0},\"size\":{\"value\":266"},{"range":{"end":{"character":72,"line":9},"start":{"character":72,"line":9}},"text":" Boolean.True"}],"newVersion":"20d155336daf651c7276b79c1fd324b614f89277f03b95f8ac7c0537","oldVersion":"455821208119e6ec832f2dfe3157f00a276bcfacfdd370a4c59d084f","path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]}},"execute":true}}.
[trace] [2023-04-28T10:34:16.801Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled Request(ApplyEdit,Number(20),Params(FileEdit(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso)),List(TextEdit(Range(Position(16,4326),Position(16,4663)),784e7fd6-c07c-4eeb-92cb-d696e07edbc8"],[{"index":{"value":251},"size":{"value":7}},"411db538-670d-49a7-81c4-e6d172db12fa"],[{"index":{"value":258},"size":{"value":1}},"a83f6213-5b8a-45ae-9034-b6d09cd1bbbc"],[{"index":{"value":259},"size":{"value":4}},"623c3592-3a03-4b39-8baf-f43140b8c59c"],[{"index":{"value":251},"size":{"value":12}},"b0a4429d-57d5-4e70-91da-1105c39403e6"],[{"index":{"value":194},"size":{"value":69}},"a8d000c2-2494-44e0-91ad-b1c83a14a396"],[{"index":{"value":182},"size":{"value":81}},"a29e59ad-010f-46be-ae5a-f2d7fae91bc0"],[{"index":{"value":116},"size":{"value":147}},"0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27"],[{"index":{"value":109},"size":{"value":154}},"9f904285-40a4-49a2-8a84-c2b824e12867"],[{"index":{"value":0},"size":{"value":266), TextEdit(Range(Position(9,72),Position(9,72)), Boolean.True)),455821208119e6ec832f2dfe3157f00a276bcfacfdd370a4c59d084f,20d155336daf651c7276b79c1fd324b614f89277f03b95f8ac7c0537),Some(true))) from Actor[akka://language-server/user/message-handler-supervisor-038d1851-4e45-4f12-a32a-a612e5cc34cf/message-handler-6a53da52-7eb1-4841-87ab-2c70e9199256#2139308755]
[debug] [2023-04-28T10:34:16.801Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-6a53da52-7eb1-4841-87ab-2c70e9199256/request-handler-ApplyEdit-f0c03e54-6a0f-4a22-9736-fc9f5099220e#-619780956]
[debug] [2023-04-28T10:34:16.801Z] [org.enso.languageserver.requesthandler.text.ApplyEditHandler] started (org.enso.languageserver.requesthandler.text.ApplyEditHandler@43aa4ae8)
[trace] [2023-04-28T10:34:16.803Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled Request(None,EditFileNotification(/home/michael/enso/projects/Namwd/src/Main.enso,List(TextEdit(Range(Position(16,4326),Position(16,4663)),784e7fd6-c07c-4eeb-92cb-d696e07edbc8"],[{"index":{"value":251},"size":{"value":7}},"411db538-670d-49a7-81c4-e6d172db12fa"],[{"index":{"value":258},"size":{"value":1}},"a83f6213-5b8a-45ae-9034-b6d09cd1bbbc"],[{"index":{"value":259},"size":{"value":4}},"623c3592-3a03-4b39-8baf-f43140b8c59c"],[{"index":{"value":251},"size":{"value":12}},"b0a4429d-57d5-4e70-91da-1105c39403e6"],[{"index":{"value":194},"size":{"value":69}},"a8d000c2-2494-44e0-91ad-b1c83a14a396"],[{"index":{"value":182},"size":{"value":81}},"a29e59ad-010f-46be-ae5a-f2d7fae91bc0"],[{"index":{"value":116},"size":{"value":147}},"0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27"],[{"index":{"value":109},"size":{"value":154}},"9f904285-40a4-49a2-8a84-c2b824e12867"],[{"index":{"value":0},"size":{"value":266), TextEdit(Range(Position(9,72),Position(9,72)), Boolean.True)),true)) from Actor[akka://language-server/user/buffer-registry/collaborative-buffer-89f5f818-a4db-4f4c-b9de-54e2b648e590#-734177692]
[debug] [2023-04-28T10:34:16.803Z] [org.enso.languageserver.requesthandler.text.ApplyEditHandler] stopped
[trace] [2023-04-28T10:34:16.803Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":20,"result":null}.
[debug] [2023-04-28T10:34:16.805Z] [enso] Executing command: EditFileCmd...
[debug] [2023-04-28T10:34:16.805Z] [enso] Submitting job: org.enso.interpreter.instrument.job.EnsureCompiledJob@103649aa...
[debug] [2023-04-28T10:34:16.806Z] [enso] Executing job: org.enso.interpreter.instrument.job.EnsureCompiledJob@103649aa...
[debug] [2023-04-28T10:34:16.806Z] [enso] Submitting job: org.enso.interpreter.instrument.job.ExecuteJob@7d6535e6...
[debug] [2023-04-28T10:34:16.806Z] [enso] Command EditFileCmd finished.
[trace] [2023-04-28T10:34:16.809Z] [enso] Compiling local.Namd.Main.
[debug] [2023-04-28T10:34:16.81Z] [enso.org.enso.compiler.Compiler] Parsing module [local.Namd.Main].
[trace] [2023-04-28T10:34:16.81Z] [enso.org.enso.compiler.ModuleCache] One or more digests did not match for the cache for [local.Namd.Main].
[trace] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.ModuleCache] Invalidated the cache at [***/Main.meta].
[trace] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.ModuleCache] Invalidated the cache at [***/Main.ir].
[trace] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.ModuleCache] Could not load the cache metadata at [***/Main.meta].
[trace] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.ModuleCache] Unable to load a cache [local.Namd.Main]
[debug] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.SerializationManager] Unable to load a cache for module [local.Namd.Main].
[debug] [2023-04-28T10:34:16.811Z] [enso.org.enso.compiler.Compiler] Loading module [local.Namd.Main] from source.
[trace] [2023-04-28T10:34:16.816Z] [enso.org.enso.compiler.ImportExportCache] Could not load the cache metadata at [***/Namd.bindings.meta].
[trace] [2023-04-28T10:34:16.817Z] [enso.org.enso.compiler.ImportExportCache] Could not load the cache metadata at [***/Namd.bindings.meta].
[trace] [2023-04-28T10:34:16.817Z] [enso.org.enso.compiler.ImportExportCache] Unable to load a cache [local.Namd]
[trace] [2023-04-28T10:34:16.817Z] [enso.org.enso.compiler.SerializationManager] Unable to load bindings for library [local.Namd].
[debug] [2023-04-28T10:34:16.84Z] [enso.org.enso.compiler.Compiler] Generating code for module [local.Namd.Main].
[debug] [2023-04-28T10:34:16.84Z] [enso] Executing command: SerializeModuleCommand...
[debug] [2023-04-28T10:34:16.841Z] [enso] Submitting job: org.enso.interpreter.instrument.job.SerializeModuleJob@17d590a9...
[debug] [2023-04-28T10:34:16.841Z] [enso] Executing job: org.enso.interpreter.instrument.job.SerializeModuleJob@17d590a9...
[debug] [2023-04-28T10:34:16.841Z] [enso] Command SerializeModuleCommand finished.
[debug] [2023-04-28T10:34:16.846Z] [enso] Submitting job: AnalyzeModuleJob(local.Namd.Main, ...)...
[trace] [2023-04-28T10:34:16.847Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExecutionUpdate(10c1f90c-70cc-4f9b-b435-047420407611,List(Diagnostic(Warning(),Some(Unused variable operator2.),Some(/home/michael/enso/projects/Namwd/src/Main.enso),Some(Range(Position(9,4),Position(9,13))),Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),Vector()))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.847Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExecutionUpdate(contextId=10c1f90c-70cc-4f9b-b435-047420407611,diagnostics=List(Diagnostic(kind=Warning(),message=Some(***),file=Some(***/Main.enso),location=Some(Range(Position(9,4),Position(9,13))),expressionId=Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),stack=Vector()))) from Actor[akka://language-server/deadLetters]
[debug] [2023-04-28T10:34:16.848Z] [enso] Job org.enso.interpreter.instrument.job.EnsureCompiledJob@103649aa finished in 42 ms.
[debug] [2023-04-28T10:34:16.848Z] [enso.org.enso.compiler.SerializationManager] Requesting serialization for module [local.Namd.Main].
[debug] [2023-04-28T10:34:16.848Z] [enso] Executing job: org.enso.interpreter.instrument.job.ExecuteJob@7d6535e6...
[trace] [2023-04-28T10:34:16.848Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExecutionDiagnosticNotification(10c1f90c-70cc-4f9b-b435-047420407611,List(ExecutionDiagnostic(Warning,Some(Unused variable operator2.),Some(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso))),Some(Range(Position(9,4),Position(9,13))),Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),Vector()))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:34:16.848Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/executionStatus","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611","diagnostics":[{"kind":"Warning","message":"Unused variable operator2.","path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]},"location":{"start":{"line":9,"character":4},"end":{"line":9,"character":13}},"expressionId":"f1a6e471-4274-493c-9ba5-52564b86aa1b","stack":[]}]}}.
[debug] [2023-04-28T10:34:16.85Z] [enso.org.enso.compiler.SerializationManager] Running serialization for module [local.Namd.Main].
[trace] [2023-04-28T10:34:16.862Z] [enso.org.enso.compiler.ModuleCache] Written cache data [local.Namd.Main] to [***/Namd].
[debug] [2023-04-28T10:34:16.862Z] [enso] Job org.enso.interpreter.instrument.job.SerializeModuleJob@17d590a9 finished in 21 ms.
[trace] [2023-04-28T10:34:16.863Z] [enso] Run program 10c1f90c-70cc-4f9b-b435-047420407611
[debug] [2023-04-28T10:34:16.863Z] [enso] Executing job: AnalyzeModuleJob(local.Namd.Main, ...)...
[trace] [2023-04-28T10:34:16.863Z] [enso] Analyzing indexed module local.Namd.Main
[debug] [2023-04-28T10:34:16.867Z] [enso] Job AnalyzeModuleJob(local.Namd.Main, ...) finished in 4 ms.
[trace] [2023-04-28T10:34:16.868Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,SuggestionsDatabaseModuleUpdateNotification(local.Namd.Main,Vector(),Vector(),Root(Vector(Node(SuggestionUpdate(Method(Some(448992c0-f650-4404-ad72-637553a70477),local.Namd.Main,main,List(),local.Namd.Main,Standard.Base.Any.Any,true,None,None),Modify(Some(Some(0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27)),None,None,None,None,None)),Vector(Node(SuggestionUpdate(Local(Some(d5cb58c5-45c7-4223-bdc9-5477fdd6e6e0),local.Namd.Main,text2,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,72)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(0236ab58-fc0d-4af1-b2e1-8835b801980d),local.Namd.Main,text1,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,72)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(641c7f6d-15f5-4375-b698-a0926818dd3e),local.Namd.Main,text3,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,72)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(a8d000c2-2494-44e0-91ad-b1c83a14a396),local.Namd.Main,operator2,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,72)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector())))))))) from Actor[akka://language-server/deadLetters]
[debug] [2023-04-28T10:34:16.868Z] [org.enso.languageserver.search.SuggestionsHandler] Got module update [local.Namd.Main].
[trace] [2023-04-28T10:34:16.869Z] [enso] ON_CACHED_VALUE d5cb58c5-45c7-4223-bdc9-5477fdd6e6e0
[trace] [2023-04-28T10:34:16.869Z] [enso] ON_COMPUTED 7140479b-b697-4418-ae59-ad36b25fe8ee
[trace] [2023-04-28T10:34:16.87Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(384262)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.87Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(384262)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.87Z] [enso] ON_CACHED_VALUE 0236ab58-fc0d-4af1-b2e1-8835b801980d
[trace] [2023-04-28T10:34:16.87Z] [enso] ON_COMPUTED a647c160-59e7-43b7-810b-40b7a95b687e
[trace] [2023-04-28T10:34:16.871Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(285627)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.871Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(285627)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.871Z] [enso] ON_CACHED_VALUE 641c7f6d-15f5-4375-b698-a0926818dd3e
[trace] [2023-04-28T10:34:16.871Z] [enso] ON_COMPUTED 678ba098-e18a-4440-a82b-cc51b6f9a602
[trace] [2023-04-28T10:34:16.872Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(275438)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.872Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(275438)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.872Z] [enso] ON_CACHED_VALUE a8d000c2-2494-44e0-91ad-b1c83a14a396
[trace] [2023-04-28T10:34:16.872Z] [enso] ON_COMPUTED a29e59ad-010f-46be-ae5a-f2d7fae91bc0
[trace] [2023-04-28T10:34:16.873Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(268846)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.873Z] [enso] ON_COMPUTED 0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27
[trace] [2023-04-28T10:34:16.873Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(268846)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.873Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(4094051)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.873Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(4094051)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.874Z] [enso] ON_CACHED_CALL a8d000c2-2494-44e0-91ad-b1c83a14a396
[trace] [2023-04-28T10:34:16.874Z] [enso] ON_CACHED_CALL 05b4147b-2e78-4ae6-81a1-7c65f5588ca2
[trace] [2023-04-28T10:34:16.874Z] [enso] Execution finished: Right(())
[debug] [2023-04-28T10:34:16.875Z] [enso] Job org.enso.interpreter.instrument.job.ExecuteJob@7d6535e6 finished in 27 ms.
[trace] [2023-04-28T10:34:16.875Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExecutionComplete(10c1f90c-70cc-4f9b-b435-047420407611))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.875Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExecutionComplete(10c1f90c-70cc-4f9b-b435-047420407611) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:34:16.875Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExecutionCompleteNotification(10c1f90c-70cc-4f9b-b435-047420407611) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:34:16.875Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/executionComplete","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611"}}.
[debug] [2023-04-28T10:34:16.932Z] [org.enso.languageserver.search.SuggestionsHandler] Complete module update [local.Namd.Main].
[debug] [2023-04-28T10:34:16.932Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing)))].
[trace] [2023-04-28T10:34:16.932Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled SuggestionsDatabaseUpdateNotification(36,List(Modify(2,Some(FieldUpdate(Set,Some(0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27))),None,None,None,None,None,None,None), Modify(3,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(4,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(5,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(6,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:34:16.933Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"search/suggestionsDatabaseUpdates","params":{"updates":[{"type":"Modify","id":2,"externalId":{"tag":"Set","value":"0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27"}},{"type":"Modify","id":3,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":4,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":5,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":6,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}}],"currentVersion":36}}.
[debug] [2023-04-28T10:34:16.939Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing)))].
[debug] [2023-04-28T10:34:16.946Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing)))].
[debug] [2023-04-28T10:34:16.952Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing)))].
[debug] [2023-04-28T10:34:16.957Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27,Some(Standard.Base.Nothing.Nothing)))].
[trace] [2023-04-28T10:34:16.962Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled SuggestionsDatabaseUpdateNotification(37,List(Modify(2,None,None,None,None,Some(FieldUpdate(Set,Some(Standard.Base.Nothing.Nothing))),None,None,None))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:34:16.962Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"search/suggestionsDatabaseUpdates","params":{"updates":[{"type":"Modify","id":2,"returnType":{"tag":"Set","value":"Standard.Base.Nothing.Nothing"}}],"currentVersion":37}}.
[trace] [2023-04-28T10:34:17.758Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExpressionUpdatesNotification(10c1f90c-70cc-4f9b-b435-047420407611,Vector(ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(384262)),false,Value(None)), ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(285627)),false,Value(None)), ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(275438)),false,Value(None)), ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(268846)),false,Value(None)), ExpressionUpdate(0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(4094051)),false,Value(None)))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:34:17.758Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/expressionUpdates","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611","updates":[{"expressionId":"7140479b-b697-4418-ae59-ad36b25fe8ee","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":384262}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"a647c160-59e7-43b7-810b-40b7a95b687e","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":285627}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"678ba098-e18a-4440-a82b-cc51b6f9a602","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":275438}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"a29e59ad-010f-46be-ae5a-f2d7fae91bc0","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":268846}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"0c0cdfd2-8ecd-4d17-80e3-4e0a6f9c2a27","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":4094051}}],"fromCache":false,"payload":{"type":"Value","warnings":null}}]}}.
[debug] [2023-04-28T10:34:19.227Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] no longer watched by Actor[akka://project-manager/system/Materializers/StreamSupervisor-0/$$Bc#-19996995]
[debug] [2023-04-28T10:34:19.227Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] stopped
^A[trace] [2023-04-28T10:34:22.82Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled FileAutoSaved(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso))) from Actor[akka://language-server/user/buffer-registry/collaborative-buffer-89f5f818-a4db-4f4c-b9de-54e2b648e590#-734177692]
[trace] [2023-04-28T10:34:22.821Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"text/autoSave","params":{"path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]}}}.
[trace] [2023-04-28T10:34:22.83Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: {"jsonrpc":"2.0","id":21,"method":"vcs/status","params":{"root":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[]}}}.
[trace] [2023-04-28T10:34:22.83Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled Request(StatusVcs,Number(21),Params(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector()))) from Actor[akka://language-server/user/message-handler-supervisor-038d1851-4e45-4f12-a32a-a612e5cc34cf/message-handler-6a53da52-7eb1-4841-87ab-2c70e9199256#2139308755]
[debug] [2023-04-28T10:34:22.831Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-6a53da52-7eb1-4841-87ab-2c70e9199256/request-handler-StatusVcs-bed3e90a-cda6-4acd-99f5-6bc149bbf417#1069765990]
[debug] [2023-04-28T10:34:22.831Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] started (org.enso.languageserver.requesthandler.vcs.StatusVcsHandler@1ef9a26f)
[debug] [2023-04-28T10:34:22.844Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] stopped
[trace] [2023-04-28T10:34:22.844Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":21,"result":{"dirty":true,"changed":[{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]},{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[".enso"]}],"lastSave":{"commitId":"16aa0b750d4da0bf08216edbc713e07879ca42c2","message":"2023-04-24T14:22:32.288282Z"}}}.

@MichaelMauderer
Copy link
Contributor

And this is the log when switching from “False” to “True” (which results in the correct evaluation)

[trace] [2023-04-28T10:43:43.51Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled FileAutoSaved(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso))) from Actor[akka://language-server/user/buffer-registry/collaborative-buffer-89f5f818-a4db-4f4c-b9de-54e2b648e590#-734177692]
[trace] [2023-04-28T10:43:43.511Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"text/autoSave","params":{"path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]}}}.
[trace] [2023-04-28T10:43:43.535Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: {"jsonrpc":"2.0","id":29,"method":"vcs/status","params":{"root":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[]}}}.
[trace] [2023-04-28T10:43:43.536Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled Request(StatusVcs,Number(29),Params(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector()))) from Actor[akka://language-server/user/message-handler-supervisor-038d1851-4e45-4f12-a32a-a612e5cc34cf/message-handler-6a53da52-7eb1-4841-87ab-2c70e9199256#2139308755]
[debug] [2023-04-28T10:43:43.536Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-6a53da52-7eb1-4841-87ab-2c70e9199256/request-handler-StatusVcs-ee3ce797-36da-4489-be37-50bfffebeafb#-1407187538]
[debug] [2023-04-28T10:43:43.536Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] started (org.enso.languageserver.requesthandler.vcs.StatusVcsHandler@33dc7059)
[debug] [2023-04-28T10:43:43.545Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] stopped
[trace] [2023-04-28T10:43:43.545Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":29,"result":{"dirty":true,"changed":[{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]},{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[".enso"]}],"lastSave":{"commitId":"16aa0b750d4da0bf08216edbc713e07879ca42c2","message":"2023-04-24T14:22:32.288282Z"}}}.
[trace] [2023-04-28T10:43:45.029Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: {"jsonrpc":"2.0","id":30,"method":"text/applyEdit","params":{"edit":{"edits":[{"range":{"end":{"character":5085,"line":16},"start":{"character":4410,"line":16}},"text":"1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4\"],[{\"index\":{\"value\":258},\"size\":{\"value\":1}},\"4cd6d2a4-aa52-4fc2-837f-f203a4da5f48\"],[{\"index\":{\"value\":259},\"size\":{\"value\":4}},\"4df6ac87-16b6-417a-9217-840a328c0da5\"],[{\"index\":{\"value\":251},\"size\":{\"value\":12}},\"67ac7090-3f3f-46cc-8436-7c28a65cfba3\"],[{\"index\":{\"value\":194},\"size\":{\"value\":69}},\"a8d000c2-2494-44e0-91ad-b1c83a14a396\"],[{\"index\":{\"value\":182},\"size\":{\"value\":81}},\"a29e59ad-010f-46be-ae5a-f2d7fae91bc0\"],[{\"index\":{\"value\":116},\"size\":{\"value\":147}},\"82431656-f471-4640-b8aa-0ebf6a127127\"],[{\"index\":{\"value\":109},\"size\":{\"value\":154}},\"9f904285-40a4-49a2-8a84-c2b824e12867\"],[{\"index\":{\"value\":0},\"size\":{\"value\":266"},{"range":{"end":{"character":85,"line":9},"start":{"character":81,"line":9}},"text":"Tru"}],"newVersion":"796f1f73e1b06db7c0879be0a9d53caa518ac2efef6a6fa37b81a408","oldVersion":"d04c47f414b530c34f058bd359a85d377b36cb39a5a0a0398f367b7c","path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]}},"execute":true}}.
[trace] [2023-04-28T10:43:45.031Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled Request(ApplyEdit,Number(30),Params(FileEdit(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso)),List(TextEdit(Range(Position(16,4410),Position(16,5085)),1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4"],[{"index":{"value":258},"size":{"value":1}},"4cd6d2a4-aa52-4fc2-837f-f203a4da5f48"],[{"index":{"value":259},"size":{"value":4}},"4df6ac87-16b6-417a-9217-840a328c0da5"],[{"index":{"value":251},"size":{"value":12}},"67ac7090-3f3f-46cc-8436-7c28a65cfba3"],[{"index":{"value":194},"size":{"value":69}},"a8d000c2-2494-44e0-91ad-b1c83a14a396"],[{"index":{"value":182},"size":{"value":81}},"a29e59ad-010f-46be-ae5a-f2d7fae91bc0"],[{"index":{"value":116},"size":{"value":147}},"82431656-f471-4640-b8aa-0ebf6a127127"],[{"index":{"value":109},"size":{"value":154}},"9f904285-40a4-49a2-8a84-c2b824e12867"],[{"index":{"value":0},"size":{"value":266), TextEdit(Range(Position(9,81),Position(9,85)),Tru)),d04c47f414b530c34f058bd359a85d377b36cb39a5a0a0398f367b7c,796f1f73e1b06db7c0879be0a9d53caa518ac2efef6a6fa37b81a408),Some(true))) from Actor[akka://language-server/user/message-handler-supervisor-038d1851-4e45-4f12-a32a-a612e5cc34cf/message-handler-6a53da52-7eb1-4841-87ab-2c70e9199256#2139308755]
[debug] [2023-04-28T10:43:45.031Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-6a53da52-7eb1-4841-87ab-2c70e9199256/request-handler-ApplyEdit-fdcf52c5-4141-47a5-997e-d04b30d97979#1384243635]
[debug] [2023-04-28T10:43:45.031Z] [org.enso.languageserver.requesthandler.text.ApplyEditHandler] started (org.enso.languageserver.requesthandler.text.ApplyEditHandler@3bc3fa65)
[trace] [2023-04-28T10:43:45.033Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled Request(None,EditFileNotification(/home/michael/enso/projects/Namwd/src/Main.enso,List(TextEdit(Range(Position(16,4410),Position(16,5085)),1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4"],[{"index":{"value":258},"size":{"value":1}},"4cd6d2a4-aa52-4fc2-837f-f203a4da5f48"],[{"index":{"value":259},"size":{"value":4}},"4df6ac87-16b6-417a-9217-840a328c0da5"],[{"index":{"value":251},"size":{"value":12}},"67ac7090-3f3f-46cc-8436-7c28a65cfba3"],[{"index":{"value":194},"size":{"value":69}},"a8d000c2-2494-44e0-91ad-b1c83a14a396"],[{"index":{"value":182},"size":{"value":81}},"a29e59ad-010f-46be-ae5a-f2d7fae91bc0"],[{"index":{"value":116},"size":{"value":147}},"82431656-f471-4640-b8aa-0ebf6a127127"],[{"index":{"value":109},"size":{"value":154}},"9f904285-40a4-49a2-8a84-c2b824e12867"],[{"index":{"value":0},"size":{"value":266), TextEdit(Range(Position(9,81),Position(9,85)),Tru)),true)) from Actor[akka://language-server/user/buffer-registry/collaborative-buffer-89f5f818-a4db-4f4c-b9de-54e2b648e590#-734177692]
[trace] [2023-04-28T10:43:45.033Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":30,"result":null}.
[debug] [2023-04-28T10:43:45.033Z] [org.enso.languageserver.requesthandler.text.ApplyEditHandler] stopped
[debug] [2023-04-28T10:43:45.034Z] [enso] Executing command: EditFileCmd...
[debug] [2023-04-28T10:43:45.034Z] [enso] Submitting job: org.enso.interpreter.instrument.job.EnsureCompiledJob@47919ca0...
[debug] [2023-04-28T10:43:45.034Z] [enso] Executing job: org.enso.interpreter.instrument.job.EnsureCompiledJob@47919ca0...
[debug] [2023-04-28T10:43:45.035Z] [enso] Submitting job: org.enso.interpreter.instrument.job.ExecuteJob@3a64844...
[debug] [2023-04-28T10:43:45.035Z] [enso] Command EditFileCmd finished.
[trace] [2023-04-28T10:43:45.038Z] [enso] Compiling local.Namd.Main.
[debug] [2023-04-28T10:43:45.038Z] [enso.org.enso.compiler.Compiler] Parsing module [local.Namd.Main].
[trace] [2023-04-28T10:43:45.038Z] [enso.org.enso.compiler.ModuleCache] One or more digests did not match for the cache for [local.Namd.Main].
[trace] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.ModuleCache] Invalidated the cache at [***/Main.meta].
[trace] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.ModuleCache] Invalidated the cache at [***/Main.ir].
[trace] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.ModuleCache] Could not load the cache metadata at [***/Main.meta].
[trace] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.ModuleCache] Unable to load a cache [local.Namd.Main]
[debug] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.SerializationManager] Unable to load a cache for module [local.Namd.Main].
[debug] [2023-04-28T10:43:45.039Z] [enso.org.enso.compiler.Compiler] Loading module [local.Namd.Main] from source.
[trace] [2023-04-28T10:43:45.042Z] [enso.org.enso.compiler.ImportExportCache] Could not load the cache metadata at [***/Namd.bindings.meta].
[trace] [2023-04-28T10:43:45.042Z] [enso.org.enso.compiler.ImportExportCache] Could not load the cache metadata at [***/Namd.bindings.meta].
[trace] [2023-04-28T10:43:45.042Z] [enso.org.enso.compiler.ImportExportCache] Unable to load a cache [local.Namd]
[trace] [2023-04-28T10:43:45.042Z] [enso.org.enso.compiler.SerializationManager] Unable to load bindings for library [local.Namd].
[debug] [2023-04-28T10:43:45.054Z] [enso.org.enso.compiler.Compiler] Generating code for module [local.Namd.Main].
[debug] [2023-04-28T10:43:45.055Z] [enso] Executing command: SerializeModuleCommand...
[debug] [2023-04-28T10:43:45.055Z] [enso] Submitting job: org.enso.interpreter.instrument.job.SerializeModuleJob@278921a3...
[debug] [2023-04-28T10:43:45.055Z] [enso] Executing job: org.enso.interpreter.instrument.job.SerializeModuleJob@278921a3...
[debug] [2023-04-28T10:43:45.055Z] [enso] Command SerializeModuleCommand finished.
[debug] [2023-04-28T10:43:45.057Z] [enso] Submitting job: AnalyzeModuleJob(local.Namd.Main, ...)...
[trace] [2023-04-28T10:43:45.058Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,HashSet(ExpressionUpdate(9f904285-40a4-49a2-8a84-c2b824e12867,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(8f0fc0a2-6a6c-4425-93d2-02c257c4a718,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(c6c3bf31-1cb3-46b8-89f7-805c978fd522,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(4c06c832-230a-4dbd-ab4b-7949bad72330,None,None,Vector(),true,Pending(None,None)))))) from Actor[akka://language-server/deadLetters]
[debug] [2023-04-28T10:43:45.058Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [HashSet((a29e59ad-010f-46be-ae5a-f2d7fae91bc0,None), (9f904285-40a4-49a2-8a84-c2b824e12867,None), (4c06c832-230a-4dbd-ab4b-7949bad72330,None), (8f0fc0a2-6a6c-4425-93d2-02c257c4a718,None), (a8d000c2-2494-44e0-91ad-b1c83a14a396,None), (c6c3bf31-1cb3-46b8-89f7-805c978fd522,None))].
[trace] [2023-04-28T10:43:45.058Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,HashSet(ExpressionUpdate(9f904285-40a4-49a2-8a84-c2b824e12867,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(8f0fc0a2-6a6c-4425-93d2-02c257c4a718,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(c6c3bf31-1cb3-46b8-89f7-805c978fd522,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(4c06c832-230a-4dbd-ab4b-7949bad72330,None,None,Vector(),true,Pending(None,None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.058Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExecutionUpdate(10c1f90c-70cc-4f9b-b435-047420407611,List(Diagnostic(Warning(),Some(Unused variable operator2.),Some(/home/michael/enso/projects/Namwd/src/Main.enso),Some(Range(Position(9,4),Position(9,13))),Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),Vector()))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.058Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExecutionUpdate(contextId=10c1f90c-70cc-4f9b-b435-047420407611,diagnostics=List(Diagnostic(kind=Warning(),message=Some(***),file=Some(***/Main.enso),location=Some(Range(Position(9,4),Position(9,13))),expressionId=Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),stack=Vector()))) from Actor[akka://language-server/deadLetters]
[debug] [2023-04-28T10:43:45.059Z] [enso] Job org.enso.interpreter.instrument.job.EnsureCompiledJob@47919ca0 finished in 25 ms.
[debug] [2023-04-28T10:43:45.059Z] [enso.org.enso.compiler.SerializationManager] Requesting serialization for module [local.Namd.Main].
[debug] [2023-04-28T10:43:45.059Z] [enso] Executing job: org.enso.interpreter.instrument.job.ExecuteJob@3a64844...
[trace] [2023-04-28T10:43:45.059Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExecutionDiagnosticNotification(10c1f90c-70cc-4f9b-b435-047420407611,List(ExecutionDiagnostic(Warning,Some(Unused variable operator2.),Some(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso))),Some(Range(Position(9,4),Position(9,13))),Some(f1a6e471-4274-493c-9ba5-52564b86aa1b),Vector()))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.059Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/executionStatus","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611","diagnostics":[{"kind":"Warning","message":"Unused variable operator2.","path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]},"location":{"start":{"line":9,"character":4},"end":{"line":9,"character":13}},"expressionId":"f1a6e471-4274-493c-9ba5-52564b86aa1b","stack":[]}]}}.
[debug] [2023-04-28T10:43:45.06Z] [enso.org.enso.compiler.SerializationManager] Running serialization for module [local.Namd.Main].
[trace] [2023-04-28T10:43:45.068Z] [enso.org.enso.compiler.ModuleCache] Written cache data [local.Namd.Main] to [***/Namd].
[debug] [2023-04-28T10:43:45.069Z] [enso] Job org.enso.interpreter.instrument.job.SerializeModuleJob@278921a3 finished in 14 ms.
[trace] [2023-04-28T10:43:45.069Z] [enso] Run program 10c1f90c-70cc-4f9b-b435-047420407611
[debug] [2023-04-28T10:43:45.069Z] [enso] Executing job: AnalyzeModuleJob(local.Namd.Main, ...)...
[trace] [2023-04-28T10:43:45.069Z] [enso] Analyzing indexed module local.Namd.Main
[debug] [2023-04-28T10:43:45.071Z] [enso] Job AnalyzeModuleJob(local.Namd.Main, ...) finished in 2 ms.
[trace] [2023-04-28T10:43:45.072Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,SuggestionsDatabaseModuleUpdateNotification(local.Namd.Main,Vector(),Vector(),Root(Vector(Node(SuggestionUpdate(Method(Some(8f0fc0a2-6a6c-4425-93d2-02c257c4a718),local.Namd.Main,main,List(),local.Namd.Main,Standard.Base.Any.Any,true,None,None),Modify(Some(Some(82431656-f471-4640-b8aa-0ebf6a127127)),None,None,None,None,None)),Vector(Node(SuggestionUpdate(Local(Some(d5cb58c5-45c7-4223-bdc9-5477fdd6e6e0),local.Namd.Main,text2,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,86)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(0236ab58-fc0d-4af1-b2e1-8835b801980d),local.Namd.Main,text1,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,86)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(641c7f6d-15f5-4375-b698-a0926818dd3e),local.Namd.Main,text3,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,86)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector()), Node(SuggestionUpdate(Local(Some(a8d000c2-2494-44e0-91ad-b1c83a14a396),local.Namd.Main,operator2,Standard.Base.Any.Any,Scope(Position(5,7),Position(9,86)),None),Modify(None,None,None,None,Some(Scope(Position(5,7),Position(9,85))),None)),Vector())))))))) from Actor[akka://language-server/deadLetters]
[debug] [2023-04-28T10:43:45.072Z] [org.enso.languageserver.search.SuggestionsHandler] Got module update [local.Namd.Main].
[trace] [2023-04-28T10:43:45.072Z] [enso] ON_CACHED_VALUE d5cb58c5-45c7-4223-bdc9-5477fdd6e6e0
[trace] [2023-04-28T10:43:45.072Z] [enso] ON_COMPUTED 7140479b-b697-4418-ae59-ad36b25fe8ee
[trace] [2023-04-28T10:43:45.073Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(241253)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.073Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(241253)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.073Z] [enso] ON_CACHED_VALUE 0236ab58-fc0d-4af1-b2e1-8835b801980d
[trace] [2023-04-28T10:43:45.073Z] [enso] ON_COMPUTED a647c160-59e7-43b7-810b-40b7a95b687e
[trace] [2023-04-28T10:43:45.074Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(218510)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.074Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(218510)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.074Z] [enso] ON_CACHED_VALUE 641c7f6d-15f5-4375-b698-a0926818dd3e
[trace] [2023-04-28T10:43:45.074Z] [enso] ON_COMPUTED 678ba098-e18a-4440-a82b-cc51b6f9a602
[trace] [2023-04-28T10:43:45.074Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(107542)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.074Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(107542)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.074Z] [enso] ON_COMPUTED e9ee4d72-6cbb-4f52-a929-f4061aa56966
[trace] [2023-04-28T10:43:45.075Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(e9ee4d72-6cbb-4f52-a929-f4061aa56966,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3036)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.075Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(e9ee4d72-6cbb-4f52-a929-f4061aa56966,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3036)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.075Z] [enso] ON_COMPUTED 481e6db2-7e1a-4535-9ceb-ffa1935997ea
[debug] [2023-04-28T10:43:45.075Z] [enso] Executing visualisation 481e6db2-7e1a-4535-9ceb-ffa1935997ea
[trace] [2023-04-28T10:43:45.075Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(481e6db2-7e1a-4535-9ceb-ffa1935997ea,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(75232)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.075Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(481e6db2-7e1a-4535-9ceb-ffa1935997ea,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(75232)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.078Z] [enso] Visualisation computed 481e6db2-7e1a-4535-9ceb-ffa1935997ea.
[trace] [2023-04-28T10:43:45.079Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,VisualisationUpdate(VisualisationContext(4b72dc3d-c380-499f-846d-6e898a2fa2c4,10c1f90c-70cc-4f9b-b435-047420407611,481e6db2-7e1a-4535-9ceb-ffa1935997ea),[B@620d18c4))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.079Z] [org.enso.languageserver.runtime.ContextRegistry] received handled VisualisationUpdate(visualisationContext=VisualisationContext(4b72dc3d-c380-499f-846d-6e898a2fa2c4,10c1f90c-70cc-4f9b-b435-047420407611,481e6db2-7e1a-4535-9ceb-ffa1935997ea),data=***) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.079Z] [enso] ON_COMPUTED 6038638a-c32b-48de-97ee-313c18c8e2df
[trace] [2023-04-28T10:43:45.08Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(6038638a-c32b-48de-97ee-313c18c8e2df,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(89939)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.08Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(6038638a-c32b-48de-97ee-313c18c8e2df,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(89939)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.08Z] [enso] ON_COMPUTED b923f692-1b33-4cd6-8c1f-bc0d1b346a12
[trace] [2023-04-28T10:43:45.081Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(b923f692-1b33-4cd6-8c1f-bc0d1b346a12,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(90720)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.081Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(b923f692-1b33-4cd6-8c1f-bc0d1b346a12,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(90720)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.082Z] [enso] ON_COMPUTED 38c97975-c992-47e0-8eeb-218b2b8c4701
[trace] [2023-04-28T10:43:45.082Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(38c97975-c992-47e0-8eeb-218b2b8c4701,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3777)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.082Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(38c97975-c992-47e0-8eeb-218b2b8c4701,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3777)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.083Z] [enso] ON_COMPUTED dbc2c858-3cfd-47b0-8c9c-f36041ec19a3
[debug] [2023-04-28T10:43:45.083Z] [enso] Executing visualisation dbc2c858-3cfd-47b0-8c9c-f36041ec19a3
[trace] [2023-04-28T10:43:45.083Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(dbc2c858-3cfd-47b0-8c9c-f36041ec19a3,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type),None,Vector(ExecutionTime(4198)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.083Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(dbc2c858-3cfd-47b0-8c9c-f36041ec19a3,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type),None,Vector(ExecutionTime(4198)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.086Z] [enso] Visualisation computed dbc2c858-3cfd-47b0-8c9c-f36041ec19a3.
[trace] [2023-04-28T10:43:45.086Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,VisualisationUpdate(VisualisationContext(4d02fa62-c7a0-42bd-b3fa-80ef4a40cee7,10c1f90c-70cc-4f9b-b435-047420407611,dbc2c858-3cfd-47b0-8c9c-f36041ec19a3),[B@7b95c21b))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.086Z] [org.enso.languageserver.runtime.ContextRegistry] received handled VisualisationUpdate(visualisationContext=VisualisationContext(4d02fa62-c7a0-42bd-b3fa-80ef4a40cee7,10c1f90c-70cc-4f9b-b435-047420407611,dbc2c858-3cfd-47b0-8c9c-f36041ec19a3),data=***) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.087Z] [enso] ON_COMPUTED 05b4147b-2e78-4ae6-81a1-7c65f5588ca2
[trace] [2023-04-28T10:43:45.087Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(05b4147b-2e78-4ae6-81a1-7c65f5588ca2,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity),Some(MethodPointer(Standard.Base.Data.Text.Case_Sensitivity,Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity,Insensitive)),Vector(ExecutionTime(4969585)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.087Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(05b4147b-2e78-4ae6-81a1-7c65f5588ca2,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity),Some(MethodPointer(Standard.Base.Data.Text.Case_Sensitivity,Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity,Insensitive)),Vector(ExecutionTime(4969585)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.088Z] [enso] ON_COMPUTED 4df6ac87-16b6-417a-9217-840a328c0da5
[trace] [2023-04-28T10:43:45.088Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(4df6ac87-16b6-417a-9217-840a328c0da5,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(2184)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.088Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(4df6ac87-16b6-417a-9217-840a328c0da5,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(2184)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.088Z] [enso] ON_COMPUTED 1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4
[trace] [2023-04-28T10:43:45.089Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4,Some(Standard.Base.Data.Boolean.Boolean.type),None,Vector(ExecutionTime(2524)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.089Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4,Some(Standard.Base.Data.Boolean.Boolean.type),None,Vector(ExecutionTime(2524)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.089Z] [enso] ON_COMPUTED 67ac7090-3f3f-46cc-8436-7c28a65cfba3
[trace] [2023-04-28T10:43:45.089Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(67ac7090-3f3f-46cc-8436-7c28a65cfba3,Some(Standard.Base.Data.Boolean.Boolean),Some(MethodPointer(Standard.Builtins.Main,Standard.Base.Data.Boolean.Boolean,True)),Vector(ExecutionTime(1236631)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.09Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(67ac7090-3f3f-46cc-8436-7c28a65cfba3,Some(Standard.Base.Data.Boolean.Boolean),Some(MethodPointer(Standard.Builtins.Main,Standard.Base.Data.Boolean.Boolean,True)),Vector(ExecutionTime(1236631)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.091Z] [enso] ON_COMPUTED a8d000c2-2494-44e0-91ad-b1c83a14a396
[debug] [2023-04-28T10:43:45.091Z] [enso] Executing visualisation a8d000c2-2494-44e0-91ad-b1c83a14a396
[trace] [2023-04-28T10:43:45.091Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,Some(Standard.Base.Data.Text.Text),Some(MethodPointer(Standard.Base.Data.Text.Extensions,Standard.Base.Data.Text.Text,replace)),Vector(ExecutionTime(16129906)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.091Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,Some(Standard.Base.Data.Text.Text),Some(MethodPointer(Standard.Base.Data.Text.Extensions,Standard.Base.Data.Text.Text,replace)),Vector(ExecutionTime(16129906)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.093Z] [enso] Visualisation computed a8d000c2-2494-44e0-91ad-b1c83a14a396.
[trace] [2023-04-28T10:43:45.093Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,VisualisationUpdate(VisualisationContext(f58b780c-87f1-4446-9fab-d31a36d4fc71,10c1f90c-70cc-4f9b-b435-047420407611,a8d000c2-2494-44e0-91ad-b1c83a14a396),[B@73731ea7))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.093Z] [org.enso.languageserver.runtime.ContextRegistry] received handled VisualisationUpdate(visualisationContext=VisualisationContext(f58b780c-87f1-4446-9fab-d31a36d4fc71,10c1f90c-70cc-4f9b-b435-047420407611,a8d000c2-2494-44e0-91ad-b1c83a14a396),data=***) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.093Z] [enso] ON_COMPUTED a29e59ad-010f-46be-ae5a-f2d7fae91bc0
[trace] [2023-04-28T10:43:45.093Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(18684248)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.094Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(18684248)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.094Z] [enso] ON_COMPUTED 82431656-f471-4640-b8aa-0ebf6a127127
[trace] [2023-04-28T10:43:45.094Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(82431656-f471-4640-b8aa-0ebf6a127127,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(21491596)),false,Value(None)))))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.094Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExpressionUpdates(10c1f90c-70cc-4f9b-b435-047420407611,Set(ExpressionUpdate(82431656-f471-4640-b8aa-0ebf6a127127,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(21491596)),false,Value(None)))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.095Z] [enso] ON_CACHED_CALL da068482-37f0-41f7-bf3a-6ea624a99953
[trace] [2023-04-28T10:43:45.095Z] [enso] ON_CACHED_CALL d06d9ff5-06ac-4d95-af5b-314db608bb13
[trace] [2023-04-28T10:43:45.095Z] [enso] ON_CACHED_CALL c6c3bf31-1cb3-46b8-89f7-805c978fd522
[trace] [2023-04-28T10:43:45.095Z] [enso] Execution finished: Right(())
[debug] [2023-04-28T10:43:45.095Z] [enso] Job org.enso.interpreter.instrument.job.ExecuteJob@3a64844 finished in 36 ms.
[trace] [2023-04-28T10:43:45.095Z] [org.enso.languageserver.runtime.RuntimeConnector] received handled MessageFromRuntime(Response(None,ExecutionComplete(10c1f90c-70cc-4f9b-b435-047420407611))) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.095Z] [org.enso.languageserver.runtime.ContextRegistry] received handled ExecutionComplete(10c1f90c-70cc-4f9b-b435-047420407611) from Actor[akka://language-server/deadLetters]
[trace] [2023-04-28T10:43:45.095Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExecutionCompleteNotification(10c1f90c-70cc-4f9b-b435-047420407611) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.096Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/executionComplete","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611"}}.
[debug] [2023-04-28T10:43:45.118Z] [org.enso.languageserver.search.SuggestionsHandler] Complete module update [local.Namd.Main].
[debug] [2023-04-28T10:43:45.118Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing)))].
[trace] [2023-04-28T10:43:45.118Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled SuggestionsDatabaseUpdateNotification(76,List(Modify(2,Some(FieldUpdate(Set,Some(82431656-f471-4640-b8aa-0ebf6a127127))),None,None,None,None,None,None,None), Modify(3,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(4,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(5,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None), Modify(6,None,None,None,None,None,None,Some(FieldUpdate(Set,Some(Scope(Position(5,7),Position(9,85))))),None))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.119Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"search/suggestionsDatabaseUpdates","params":{"updates":[{"type":"Modify","id":2,"externalId":{"tag":"Set","value":"82431656-f471-4640-b8aa-0ebf6a127127"}},{"type":"Modify","id":3,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":4,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":5,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}},{"type":"Modify","id":6,"scope":{"tag":"Set","value":{"start":{"line":5,"character":7},"end":{"line":9,"character":85}}}}],"currentVersion":76}}.
[debug] [2023-04-28T10:43:45.123Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing)))].
[debug] [2023-04-28T10:43:45.128Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing)))].
[debug] [2023-04-28T10:43:45.133Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((e9ee4d72-6cbb-4f52-a929-f4061aa56966,Some(Standard.Builtins.Main.Unresolved_Symbol)))].
[debug] [2023-04-28T10:43:45.137Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((481e6db2-7e1a-4535-9ceb-ffa1935997ea,Some(Standard.Base.Data.Text.Text)))].
[debug] [2023-04-28T10:43:45.142Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((6038638a-c32b-48de-97ee-313c18c8e2df,Some(Standard.Base.Data.Text.Text)))].
[debug] [2023-04-28T10:43:45.145Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((b923f692-1b33-4cd6-8c1f-bc0d1b346a12,Some(Standard.Base.Data.Text.Text)))].
[debug] [2023-04-28T10:43:45.148Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((38c97975-c992-47e0-8eeb-218b2b8c4701,Some(Standard.Builtins.Main.Unresolved_Symbol)))].
[debug] [2023-04-28T10:43:45.15Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((dbc2c858-3cfd-47b0-8c9c-f36041ec19a3,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type)))].
[debug] [2023-04-28T10:43:45.154Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((05b4147b-2e78-4ae6-81a1-7c65f5588ca2,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity)))].
[debug] [2023-04-28T10:43:45.158Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((4df6ac87-16b6-417a-9217-840a328c0da5,Some(Standard.Builtins.Main.Unresolved_Symbol)))].
[debug] [2023-04-28T10:43:45.162Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4,Some(Standard.Base.Data.Boolean.Boolean.type)))].
[debug] [2023-04-28T10:43:45.166Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((67ac7090-3f3f-46cc-8436-7c28a65cfba3,Some(Standard.Base.Data.Boolean.Boolean)))].
[debug] [2023-04-28T10:43:45.171Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((a8d000c2-2494-44e0-91ad-b1c83a14a396,Some(Standard.Base.Data.Text.Text)))].
[debug] [2023-04-28T10:43:45.177Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing)))].
[trace] [2023-04-28T10:43:45.177Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled SuggestionsDatabaseUpdateNotification(77,List(Modify(6,None,None,None,None,Some(FieldUpdate(Set,Some(Standard.Base.Data.Text.Text))),None,None,None))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.178Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"search/suggestionsDatabaseUpdates","params":{"updates":[{"type":"Modify","id":6,"returnType":{"tag":"Set","value":"Standard.Base.Data.Text.Text"}}],"currentVersion":77}}.
[debug] [2023-04-28T10:43:45.182Z] [org.enso.languageserver.search.SuggestionsHandler] Received expression updates [Set((82431656-f471-4640-b8aa-0ebf6a127127,Some(Standard.Base.Nothing.Nothing)))].
[trace] [2023-04-28T10:43:45.188Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled SuggestionsDatabaseUpdateNotification(78,List(Modify(2,None,None,None,None,Some(FieldUpdate(Set,Some(Standard.Base.Nothing.Nothing))),None,None,None))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.188Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"search/suggestionsDatabaseUpdates","params":{"updates":[{"type":"Modify","id":2,"returnType":{"tag":"Set","value":"Standard.Base.Nothing.Nothing"}}],"currentVersion":78}}.
[trace] [2023-04-28T10:43:45.897Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled ExpressionUpdatesNotification(10c1f90c-70cc-4f9b-b435-047420407611,Vector(ExpressionUpdate(9f904285-40a4-49a2-8a84-c2b824e12867,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(8f0fc0a2-6a6c-4425-93d2-02c257c4a718,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(c6c3bf31-1cb3-46b8-89f7-805c978fd522,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(4c06c832-230a-4dbd-ab4b-7949bad72330,None,None,Vector(),true,Pending(None,None)), ExpressionUpdate(7140479b-b697-4418-ae59-ad36b25fe8ee,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(241253)),false,Value(None)), ExpressionUpdate(a647c160-59e7-43b7-810b-40b7a95b687e,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(218510)),false,Value(None)), ExpressionUpdate(678ba098-e18a-4440-a82b-cc51b6f9a602,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(107542)),false,Value(None)), ExpressionUpdate(e9ee4d72-6cbb-4f52-a929-f4061aa56966,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3036)),false,Value(None)), ExpressionUpdate(481e6db2-7e1a-4535-9ceb-ffa1935997ea,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(75232)),false,Value(None)), ExpressionUpdate(6038638a-c32b-48de-97ee-313c18c8e2df,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(89939)),false,Value(None)), ExpressionUpdate(b923f692-1b33-4cd6-8c1f-bc0d1b346a12,Some(Standard.Base.Data.Text.Text),None,Vector(ExecutionTime(90720)),false,Value(None)), ExpressionUpdate(38c97975-c992-47e0-8eeb-218b2b8c4701,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(3777)),false,Value(None)), ExpressionUpdate(dbc2c858-3cfd-47b0-8c9c-f36041ec19a3,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type),None,Vector(ExecutionTime(4198)),false,Value(None)), ExpressionUpdate(05b4147b-2e78-4ae6-81a1-7c65f5588ca2,Some(Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity),Some(MethodPointer(Standard.Base.Data.Text.Case_Sensitivity,Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity,Insensitive)),Vector(ExecutionTime(4969585)),false,Value(None)), ExpressionUpdate(4df6ac87-16b6-417a-9217-840a328c0da5,Some(Standard.Builtins.Main.Unresolved_Symbol),None,Vector(ExecutionTime(2184)),false,Value(None)), ExpressionUpdate(1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4,Some(Standard.Base.Data.Boolean.Boolean.type),None,Vector(ExecutionTime(2524)),false,Value(None)), ExpressionUpdate(67ac7090-3f3f-46cc-8436-7c28a65cfba3,Some(Standard.Base.Data.Boolean.Boolean),Some(MethodPointer(Standard.Builtins.Main,Standard.Base.Data.Boolean.Boolean,True)),Vector(ExecutionTime(1236631)),false,Value(None)), ExpressionUpdate(a8d000c2-2494-44e0-91ad-b1c83a14a396,Some(Standard.Base.Data.Text.Text),Some(MethodPointer(Standard.Base.Data.Text.Extensions,Standard.Base.Data.Text.Text,replace)),Vector(ExecutionTime(16129906)),false,Value(None)), ExpressionUpdate(a29e59ad-010f-46be-ae5a-f2d7fae91bc0,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(18684248)),false,Value(None)), ExpressionUpdate(82431656-f471-4640-b8aa-0ebf6a127127,Some(Standard.Base.Nothing.Nothing),None,Vector(ExecutionTime(21491596)),false,Value(None)))) from Actor[akka://language-server/user/session-router#-1360238726]
[trace] [2023-04-28T10:43:45.898Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"executionContext/expressionUpdates","params":{"contextId":"10c1f90c-70cc-4f9b-b435-047420407611","updates":[{"expressionId":"9f904285-40a4-49a2-8a84-c2b824e12867","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"a29e59ad-010f-46be-ae5a-f2d7fae91bc0","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"a8d000c2-2494-44e0-91ad-b1c83a14a396","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"8f0fc0a2-6a6c-4425-93d2-02c257c4a718","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"c6c3bf31-1cb3-46b8-89f7-805c978fd522","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"4c06c832-230a-4dbd-ab4b-7949bad72330","type":null,"methodPointer":null,"profilingInfo":[],"fromCache":true,"payload":{"type":"Pending","message":null,"progress":null}},{"expressionId":"7140479b-b697-4418-ae59-ad36b25fe8ee","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":241253}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"a647c160-59e7-43b7-810b-40b7a95b687e","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":218510}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"678ba098-e18a-4440-a82b-cc51b6f9a602","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":107542}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"e9ee4d72-6cbb-4f52-a929-f4061aa56966","type":"Standard.Builtins.Main.Unresolved_Symbol","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":3036}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"481e6db2-7e1a-4535-9ceb-ffa1935997ea","type":"Standard.Base.Data.Text.Text","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":75232}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"6038638a-c32b-48de-97ee-313c18c8e2df","type":"Standard.Base.Data.Text.Text","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":89939}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"b923f692-1b33-4cd6-8c1f-bc0d1b346a12","type":"Standard.Base.Data.Text.Text","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":90720}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"38c97975-c992-47e0-8eeb-218b2b8c4701","type":"Standard.Builtins.Main.Unresolved_Symbol","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":3777}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"dbc2c858-3cfd-47b0-8c9c-f36041ec19a3","type":"Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity.type","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":4198}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"05b4147b-2e78-4ae6-81a1-7c65f5588ca2","type":"Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity","methodPointer":{"module":"Standard.Base.Data.Text.Case_Sensitivity","definedOnType":"Standard.Base.Data.Text.Case_Sensitivity.Case_Sensitivity","name":"Insensitive"},"profilingInfo":[{"ExecutionTime":{"nanoTime":4969585}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"4df6ac87-16b6-417a-9217-840a328c0da5","type":"Standard.Builtins.Main.Unresolved_Symbol","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":2184}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"1fd1720e-58f6-4ba2-928d-0bfb4a51d2a4","type":"Standard.Base.Data.Boolean.Boolean.type","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":2524}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"67ac7090-3f3f-46cc-8436-7c28a65cfba3","type":"Standard.Base.Data.Boolean.Boolean","methodPointer":{"module":"Standard.Builtins.Main","definedOnType":"Standard.Base.Data.Boolean.Boolean","name":"True"},"profilingInfo":[{"ExecutionTime":{"nanoTime":1236631}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"a8d000c2-2494-44e0-91ad-b1c83a14a396","type":"Standard.Base.Data.Text.Text","methodPointer":{"module":"Standard.Base.Data.Text.Extensions","definedOnType":"Standard.Base.Data.Text.Text","name":"replace"},"profilingInfo":[{"ExecutionTime":{"nanoTime":16129906}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"a29e59ad-010f-46be-ae5a-f2d7fae91bc0","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":18684248}}],"fromCache":false,"payload":{"type":"Value","warnings":null}},{"expressionId":"82431656-f471-4640-b8aa-0ebf6a127127","type":"Standard.Base.Nothing.Nothing","methodPointer":null,"profilingInfo":[{"ExecutionTime":{"nanoTime":21491596}}],"fromCache":false,"payload":{"type":"Value","warnings":null}}]}}.
[debug] [2023-04-28T10:43:45.968Z] [org.enso.projectmanager.infrastructure.languageserver.LanguageServerSupervisor] now supervising Actor[akka://project-manager/user/language-server-registry/language-server-controller-306aec02-8554-4da8-86a7-0c8681f1bc5a/supervisor-language-server-306aec02-8554-4da8-86a7-0c8681f1bc5a/heartbeat-e5cce8d5-3394-4bae-825a-f9b2fca68885#-880025034]
[debug] [2023-04-28T10:43:45.968Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] started (org.enso.projectmanager.infrastructure.http.FanOutReceiver@5456e201)
[debug] [2023-04-28T10:43:45.971Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Heartbeat connection initialized [Socket(127.0.0.1,49467)].
[debug] [2023-04-28T10:43:45.971Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] started (org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession@4ab0a493)
[debug] [2023-04-28T10:43:45.971Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] now watched by Actor[akka://project-manager/system/Materializers/StreamSupervisor-0/$$ne#1529743927]
[debug] [2023-04-28T10:43:45.976Z] [org.enso.jsonrpc.MessageHandlerSupervisor] started (org.enso.jsonrpc.MessageHandlerSupervisor@14985abd)
[debug] [2023-04-28T10:43:45.977Z] [org.enso.jsonrpc.MessageHandler] started (org.enso.jsonrpc.MessageHandler@39d055eb)
[debug] [2023-04-28T10:43:45.977Z] [org.enso.languageserver.protocol.json.JsonConnectionController] started (org.enso.languageserver.protocol.json.JsonConnectionController@1eea004a)
[debug] [2023-04-28T10:43:45.984Z] [org.enso.jsonrpc.MessageHandlerSupervisor] now supervising Actor[akka://language-server/user/message-handler-supervisor-a5bf4c7f-2420-4eae-aaa6-09e459a8b7a1/message-handler-bafc4f53-df2e-4d10-9662-14dc3a9d524f#1834671724]
[debug] [2023-04-28T10:43:45.989Z] [org.enso.jsonrpc.MessageHandlerSupervisor] now watched by Actor[akka://language-server/system/Materializers/StreamSupervisor-0/$$Fd#2104703007]
[debug] [2023-04-28T10:43:45.99Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Sending ping message to Socket(127.0.0.1,49467).
[trace] [2023-04-28T10:43:45.992Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: 
{ 
   "jsonrpc": "2.0",
   "method": "heartbeat/ping",
   "id": "da332cfe-c6f9-41b1-b194-6dfe038ac8f4",
   "params": null
}
.
[debug] [2023-04-28T10:43:45.992Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-bafc4f53-df2e-4d10-9662-14dc3a9d524f/$a#-1527119543]
[debug] [2023-04-28T10:43:45.992Z] [org.enso.languageserver.requesthandler.monitoring.PingHandler] started (org.enso.languageserver.requesthandler.monitoring.PingHandler@57ec4a63)
[debug] [2023-04-28T10:43:45.993Z] [org.enso.languageserver.requesthandler.monitoring.PingHandler] stopped
[trace] [2023-04-28T10:43:45.993Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":"da332cfe-c6f9-41b1-b194-6dfe038ac8f4","result":null}.
[debug] [2023-04-28T10:43:45.993Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] Received correct pong message from Socket(127.0.0.1,49467).
[debug] [2023-04-28T10:43:45.995Z] [org.enso.jsonrpc.MessageHandler] stopped
[debug] [2023-04-28T10:43:45.995Z] [org.enso.jsonrpc.MessageHandlerSupervisor] no longer watched by Actor[akka://language-server/system/Materializers/StreamSupervisor-0/$$Fd#2104703007]
[debug] [2023-04-28T10:43:45.995Z] [org.enso.languageserver.protocol.json.JsonConnectionController] stopped
^A[trace] [2023-04-28T10:43:51.05Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled FileAutoSaved(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector(src, Main.enso))) from Actor[akka://language-server/user/buffer-registry/collaborative-buffer-89f5f818-a4db-4f4c-b9de-54e2b648e590#-734177692]
[trace] [2023-04-28T10:43:51.051Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","method":"text/autoSave","params":{"path":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]}}}.
[trace] [2023-04-28T10:43:51.068Z] [org.enso.jsonrpc.JsonRpcServer] Received text message: {"jsonrpc":"2.0","id":31,"method":"vcs/status","params":{"root":{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[]}}}.
[trace] [2023-04-28T10:43:51.069Z] [org.enso.languageserver.protocol.json.JsonConnectionController] received handled Request(StatusVcs,Number(31),Params(Path(1c89091f-eb5a-4419-bfda-2b54f1f432c2,Vector()))) from Actor[akka://language-server/user/message-handler-supervisor-038d1851-4e45-4f12-a32a-a612e5cc34cf/message-handler-6a53da52-7eb1-4841-87ab-2c70e9199256#2139308755]
[debug] [2023-04-28T10:43:51.069Z] [org.enso.languageserver.protocol.json.JsonConnectionController] now supervising Actor[akka://language-server/user/json-connection-controller-6a53da52-7eb1-4841-87ab-2c70e9199256/request-handler-StatusVcs-d0840817-c15d-4827-893b-565fc1f32870#-16659101]
[debug] [2023-04-28T10:43:51.069Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] started (org.enso.languageserver.requesthandler.vcs.StatusVcsHandler@7395f26e)
[debug] [2023-04-28T10:43:51.077Z] [org.enso.languageserver.requesthandler.vcs.StatusVcsHandler] stopped
[trace] [2023-04-28T10:43:51.078Z] [org.enso.jsonrpc.JsonRpcServer] Sent text message {"jsonrpc":"2.0","id":31,"result":{"dirty":true,"changed":[{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":["src","Main.enso"]},{"rootId":"1c89091f-eb5a-4419-bfda-2b54f1f432c2","segments":[".enso"]}],"lastSave":{"commitId":"16aa0b750d4da0bf08216edbc713e07879ca42c2","message":"2023-04-24T14:22:32.288282Z"}}}.
[debug] [2023-04-28T10:43:52.247Z] [org.enso.projectmanager.infrastructure.languageserver.HeartbeatSession] stopped
[debug] [2023-04-28T10:43:52.247Z] [org.enso.projectmanager.infrastructure.http.FanOutReceiver] no longer watc

@vitvakatu
Copy link
Contributor

@JaroslavTulach returning to triage as it seems to be an engine issue.

@MichaelMauderer
Copy link
Contributor

I don't think it needs to be triage, just re-distributed.

@JaroslavTulach
Copy link
Member

JaroslavTulach commented Apr 28, 2023

Thanks for the reproducer. I'll start from it early next week. Tuesday follow up: I can reproduce the problem:

  • Changing the only_first argument from default value to Boolean.True doesn't deliver a visualization update because the value of the whole replace is taken from cache.
  • if one sets use_regex=Boolean.False first, then everything works fine

I believe this will have something to do with start/end offset of the expression. When the edit adds something at the end of the replace expression, the cache isn't invalidated. When there is a trailing use_regexp... and the edit is before, the cache gets invalidated. Such an extra text has to be on the same line. Following workaround fixes the behavior:

enso$ git diff
diff --git engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
index 5445e48fec..ebaa6d8fe3 100644
--- engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
+++ engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
@@ -402,10 +402,10 @@ object ChangesetBuilder {
     * @return true if the node and edit locations are intersecting
     */
   private def intersect(edit: Location, node: Location): Boolean = {
-    inside(node.start, edit) ||
-    inside(node.end, edit) ||
-    inside(edit.start, node) ||
-    inside(edit.end, node)
+    inside(node.start - 1, edit) ||
+    inside(node.end + 1, edit) ||
+    inside(edit.start - 1, node) ||
+    inside(edit.end + 1, node)
   }
 
   /** Check if the character position index is inside the location.

Conclusion: Probably caused by "off-by-one" error introduced when "switching to the new parser" - an adjustment of possitions like in CodeLocationsTest - I believe previously the end of line was part of the text2.replace ... invocation. These days the end of line isn't included - e.g. when adding text to the end of line, it is not treated as a change to the text2.replace ... invocation. CCing @kazcw, @4e6

@farmaazon farmaazon removed the -viz label May 2, 2023
@enso-bot
Copy link

enso-bot bot commented May 3, 2023

Jaroslav Tulach reports a new 🔴 DELAY for yesterday (2023-05-02):

Summary: There is 9 days delay in implementation of the When picking a Boolean value via the dropdown doesn't recalculate correctly. (#6221) task.
It will cause 0 days delay for the delivery of this weekly plan.

I've just got assigned to the issue

Delay Cause: Restarting work after transfer of the issue

Possible solutions: Transfer the issue sooner than the original estimate "time outs"

@enso-bot
Copy link

enso-bot bot commented May 3, 2023

Jaroslav Tulach reports a new STANDUP for yesterday (2023-05-02):

Progress: - investigation of Boolean default argument value: #6221 (comment)
- discussing if_then_else variable redefinition: #6211 (comment)
- reliable shutdown merged: #6468
- review: #6493 (review)
- review: #6482 (review)
- building engine, project manager and IDE
- creation of component groups issue #6497
- debuggging IrToTruffle
- debugging fix for VSCode & Scala: apache/netbeans#5910
- demo meetings It should be finished by 2023-05-07.

Next Day: Fixing boolean default argument & other bugfixes

@JaroslavTulach
Copy link
Member

JaroslavTulach commented May 3, 2023

Probably caused by "off-by-one" error

I am not sure anymore it is "off-by-one" error. We are talking about IDE requesting an edit to

 operator2 = text2.replace text1 text3 (Case_Sensitivity.Insensitive)

line trying to change into

 operator2 = text2.replace text1 text3 (Case_Sensitivity.Insensitive) Boolean.True

the edit is happening after the closing ) however nobody "listens" on whole expression assigned to operator2. The closest section being observed represents Insensitive - and that is clearly not changing. The IDE is observing at this particular line:

  • operator2
  • text2
  • replace
  • text1
  • text3
  • Case_Sensitivity
  • Insensitive

I guess the engine should somehow figure out that by adding one more parameter to the line, the value of operator2 is supposed to be changed, but I believe it doesn't find that out. @4e6?

@4e6
Copy link
Contributor

4e6 commented May 3, 2023

Even if the edit does not affect other function arguments, the engine should detect that the Boolean.True expression has changed (appeared), and invalidate all the transitive dependencies, including the operator2 value.

@JaroslavTulach
Copy link
Member

JaroslavTulach commented May 3, 2023

I see there is a RuntimeCache entry for text2.replace text1 text3 (Case_Sensitivity.Insensitive) Boolean.True - but it hasn't been iterated over in ChangesetBuilder.invalidated for some reason... clearly the UUID is present in "metadata" section, but it is not in the tree used by ChangesetBuilder.

@4e6
Copy link
Contributor

4e6 commented May 3, 2023

My guess would be that the UUID is updated after the edit. But I don't think it's the issue. Instead, we should make sure that in the scenario,
given

foo a=1 b=1 = a + b

main = foo 1

when
changing foo 1 to foo 1 2,

then
engine invalidates dependencies of the 2 expression.

Right now we use the ChngesetBuilder to get all the expressions that were affected by the change in the existing IR (before applying the change). In this case, it's not enough. We should also detect all the new expressions that appeared after the edit (after applying the change), and invalidate their dependencies as well.

@4e6
Copy link
Contributor

4e6 commented May 3, 2023

You may wrap the first expression in the brackets to reproduce the issue, i.e.

changing foo (1) to foo (1) 2

@enso-bot
Copy link

enso-bot bot commented May 4, 2023

Jaroslav Tulach reports a new STANDUP for yesterday (2023-05-03):

Progress: - debugging Boolean: #6221 (comment)
- yesterday's book club: https://discord.com/channels/401396655599124480/1102897461837709375/1103211677371088916
- today's book club with dates: https://discord.com/channels/401396655599124480/1103250750055919616/1103285118182895666
- analyzing IRPasses, reported #6527
- discussions, standups, retro It should be finished by 2023-05-07.

Next Day: Fixing boolean default argument & other bugfixes

Discord
Discord is the easiest way to communicate over voice, video, and text. Chat, hang out, and stay close with your friends and communities.
Discord
Discord is the easiest way to communicate over voice, video, and text. Chat, hang out, and stay close with your friends and communities.

@JaroslavTulach
Copy link
Member

This is the correct fix:

diff --git engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
index 5445e48fec..1783f1772f 100644
--- engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
+++ engine/runtime/src/main/scala/org/enso/compiler/context/ChangesetBuilder.scala
@@ -341,9 +341,7 @@ object ChangesetBuilder {
       if (input.isEmpty) acc
       else {
         val ir = input.dequeue()
-        if (ir.children.isEmpty) {
-          Node.fromIr(ir).foreach(acc.add)
-        }
+        Node.fromIr(ir).foreach(acc.add)
         go(input ++= ir.children, acc)
       }
     go(mutable.Queue(ir), mutable.TreeSet())

the UUID for text2.replace text1 text3 (Case_Sensitivity.Insensitive) expression is in the metadata, is in the generated IR, but the node in IR.Application.Prefix has children - as such the current version of ChangesetBuilder skips the creation of the Node for that expression. That means it forgets to invalidate cache for that UUID later.

@enso-bot
Copy link

enso-bot bot commented May 5, 2023

Jaroslav Tulach reports a new STANDUP for yesterday (2023-05-04):

Progress: - defaulted Boolean dropdown: #6548 (comment)
- review of #6536 (review) It should be finished by 2023-05-07.

Next Day: Polishing boolean default argument & other bugfixes

@github-project-automation github-project-automation bot moved this from 🔧 Implementation to 🟢 Accepted in Issues Board May 5, 2023
@enso-bot
Copy link

enso-bot bot commented May 7, 2023

Jaroslav Tulach reports a new STANDUP for the last Friday (2023-05-05):

Progress: - defaulted Boolean dropdown integrated: #6548
- addressing review #6548 (comment)
- yesterday's book club
- arguing about import/exports: #6553 (comment)
- stacktrace at compilation error problem: #6573
- stacktrace at compilation error merged: #6574
- fixing bugs in code snippets: #6574 (comment)
- review limit warnings: #6577 (review)
- ascription test for constructors: e4a3cd4 It should be finished by 2023-05-07.

Next Day: Bugfixing

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
--bug Type: bug -compiler d-intermediate Difficulty: some prior knowledge required p-high Should be completed in the next sprint
Projects
Archived in project
Development

Successfully merging a pull request may close this issue.

7 participants