Bug Report: KM Engine Log Is Making Voluminous Excessive Log Entries

[JMichaelTX]

Running Keyboard Maestro 9.0.5 on macOS 10.14.6 (Mojave)

I'm developing a Macro that makes use of Search Action using Regex.
Even though I am not executing this macro, nor are the Search Actions showing in the KM Editor, I am getting these erroneous log entries:

tail -r  -n 50 ~/"Library/Logs/Keyboard Maestro/Engine.log"
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:15 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:14 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:14 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)
2020-05-24 17:53:14 Search Regular Expression failed to match (?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)

Here is the Search Action:

Search using Regular Expression Action (v9.0.5)

Search using Regular Expression.kmactions (1.0 KB)

RegEx:
(?i)^(\d{4}-\d{2}-\d{2} \d{2}:\d{2}:\d{2})\h+(?!Failed)(.+) failed (?:with|to|in) (.+) In macro “(.+)” \(while executing (.+)\)

I have both terminate and notify turned OFF:

There should NOT be any log entries whatsoever for these, especially when I am NOT even running the Macro.

Please fix this very annoying bug that is severely distracting my ability to analyze and process log entries.

Thanks.

[peternlewis]

The error is only reported when the action is being run, either implicitly by the editor, or explicitly in the macro when the macro is run.

Since the editor only evaluates such things once per second, and the logs show multiple entries per second, I would presume the action is being run by a macro, presumably in some sort of loop.

@JMichaelTX

Even though I am not executing this macro, nor are the Search Actions showing in the KM Editor, I am getting these erroneous log entries:

Given the above, I would double check that the macro is not running, since the error cannot be produced unless the macro is running.

I have both terminate and notify turned OFF:

Action errors are reported in the log file even if notification are off.