See below what else I tried but none of this helped.
So I finally reluctantly restarted OH. Didn’t clear the cache.
And guess what, this time it works.
Rules reappeared in PaperUI and they all work incl. triggering from UI, removing and adding .py’s.
So, conclusion: I don’t know what it was. Nothing helped except for the OH restart but then it worked.
openhab> bundle:list|grep -i auto
237 x Active x 80 x 2.5.0 x openHAB Core :: Bundles :: Automation
238 x Active x 80 x 2.5.0 x openHAB Core :: Bundles :: Automation Media Modules
239 x Active x 80 x 2.5.0 x openHAB Core :: Bundles :: Automation Script Modules
240 x Active x 80 x 2.5.0 x openHAB Core :: Bundles :: Automation Script RuleSup
241 x Active x 80 x 2.5.0 x openHAB Core :: Bundles :: Automation REST API
Restarting 237,238,239 results in:
2020-01-15 22:00:37.184 [DEBUG] [.AutomationResourceBundlesEventQueue] - Process bundle event 2, for automation bundle 'org.openhab.core.automation'
2020-01-15 22:00:37.213 [DEBUG] [vider.AbstractResourceBundleProvider] - Parse rules from bundle 'org.openhab.core.automation'
2020-01-15 22:00:37.227 [DEBUG] [.AutomationResourceBundlesEventQueue] - Process bundle event 2, for automation bundle 'org.openhab.core.automation.module.script.rulesupport'
2020-01-15 22:00:37.230 [DEBUG] [vider.AbstractResourceBundleProvider] - Parse rules from bundle 'org.openhab.core.automation.module.script.rulesupport'
2020-01-15 22:00:37.763 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added MediaModuleHandlerFactory
2020-01-15 22:00:37.767 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedCustomModuleHandlerFactory
2020-01-15 22:00:37.770 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedPrivateModuleHandlerFactory
2020-01-15 22:00:37.773 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptModuleHandlerFactory
2020-01-15 22:00:37.776 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added CoreModuleHandlerFactory
2020-01-15 22:00:37.778 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added EphemerisModuleHandlerFactory
2020-01-15 22:00:37.781 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added TimerModuleHandlerFactory
2020-01-15 22:00:37.784 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added AnnotatedActionModuleTypeProvider
2020-01-15 22:00:37.869 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added AnnotatedThingActionModuleTypeProvider
2020-01-15 22:02:31.458 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added MediaModuleHandlerFactory
2020-01-15 22:02:35.577 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed GenericScriptEngineFactory
2020-01-15 22:02:35.590 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed NashornScriptEngineFactory
2020-01-15 22:02:35.621 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed JythonScriptEngineFactory
2020-01-15 22:02:35.813 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for jython (2.7.0): supports python (2.7) with file extensions [py], names [python, jython], and mimetypes [text/python, application/python, text/x-python, application/x-python]
2020-01-15 22:02:35.991 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a generic ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:02:36.029 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:02:36.034 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed JythonScriptEngineFactory
2020-01-15 22:02:36.037 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed GenericScriptEngineFactory
2020-01-15 22:02:36.040 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed NashornScriptEngineFactory
2020-01-15 22:02:36.045 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for jython (2.7.0): supports python (2.7) with file extensions [py], names [python, jython], and mimetypes [text/python, application/python, text/x-python, application/x-python]
2020-01-15 22:02:36.085 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a generic ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:02:36.118 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:02:36.263 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptModuleHandlerFactory
2020-01-15 22:03:01.234 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'javascript/core/000_startup_delay.js'
2020-01-15 22:03:01.870 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'js' with identifier: file:/etc/openhab2/automation/jsr223/javascript/core/000_startup_delay.js
2020-01-15 22:03:02.120 [INFO ] [jsr223.javascript.core.startup_delay] - Checking for initialized context
2020-01-15 22:03:02.224 [INFO ] [jsr223.javascript.core.startup_delay] - Context initialized... waiting 30s before allowing scripts to load
2020-01-15 22:03:32.305 [INFO ] [jsr223.javascript.core.startup_delay] - Complete
2020-01-15 22:03:32.308 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'python/core/000_startup_delay.py'
2020-01-15 22:03:32.311 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'py' with identifier: file:/etc/openhab2/automation/jsr223/python/core/000_startup_delay.py
After that, it’s now at least recognizing new files. Creating a .py results in:
2020-01-15 22:06:17.899 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'python/personal/X.py'
2020-01-15 22:06:17.902 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'py' with identifier: file:/etc/openhab2/automation/jsr223/python/personal/X.py
but it's not running
Restarting 240,241 results in:
2020-01-15 22:08:33.361 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedCustomModuleHandlerFactory
2020-01-15 22:08:33.379 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedPrivateModuleHandlerFactory
2020-01-15 22:08:58.436 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'javascript/core/000_startup_delay.js'
2020-01-15 22:08:58.868 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'js' with identifier: file:/etc/openhab2/automation/jsr223/javascript/core/000_startup_delay.js
2020-01-15 22:08:58.952 [INFO ] [jsr223.javascript.core.startup_delay] - Checking for initialized context
2020-01-15 22:08:59.019 [INFO ] [jsr223.javascript.core.startup_delay] - Context initialized... waiting 30s before allowing scripts to load
2020-01-15 22:09:29.063 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'python/core/000_startup_delay.py'
2020-01-15 22:09:29.068 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'py' with identifier: file:/etc/openhab2/automation/jsr223/python/core/000_startup_delay.py
Uninstalling NGRE gave:
2020-01-15 22:25:19.242 [INFO ] [internal.service.FeaturesServiceImpl] - Removing features: openhab-misc-ruleengine/[2.5.1,2.5.1]
2020-01-15 22:25:37.831 [INFO ] [internal.service.FeaturesServiceImpl] - Changes to perform:
2020-01-15 22:25:37.834 [INFO ] [internal.service.FeaturesServiceImpl] - Region: root
2020-01-15 22:25:37.836 [INFO ] [internal.service.FeaturesServiceImpl] - Bundles to uninstall:
2020-01-15 22:25:37.838 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation/2.5.0
2020-01-15 22:25:37.839 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:25:37.842 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:25:37.844 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:25:37.846 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.rest/2.5.0
2020-01-15 22:25:37.848 [INFO ] [internal.service.FeaturesServiceImpl] - Stopping bundles:
2020-01-15 22:25:37.853 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.config.serial/2.5.0
2020-01-15 22:25:37.875 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.rest/2.5.0
2020-01-15 22:25:37.916 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.zwave/2.5.1
2020-01-15 22:25:54.571 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.telegram/2.5.1
2020-01-15 22:25:54.600 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:25:54.649 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:25:54.656 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed GenericScriptEngineFactory
2020-01-15 22:25:54.667 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed NashornScriptEngineFactory
2020-01-15 22:25:54.684 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed JythonScriptEngineFactory
2020-01-15 22:25:54.718 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:25:54.743 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation/2.5.0
2020-01-15 22:25:54.920 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.mail/2.5.1
2020-01-15 22:25:54.974 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.hue/2.5.1
2020-01-15 22:25:55.208 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx.rfc2217/2.5.0
2020-01-15 22:25:55.222 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial/2.5.0
2020-01-15 22:25:55.258 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx/2.5.0
2020-01-15 22:25:55.270 [INFO ] [internal.service.FeaturesServiceImpl] - Uninstalling bundles:
2020-01-15 22:25:55.273 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation/2.5.0
2020-01-15 22:25:55.286 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:25:55.316 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:25:55.326 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:25:55.346 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.rest/2.5.0
2020-01-15 22:25:55.427 [INFO ] [internal.service.FeaturesServiceImpl] - Refreshing bundles:
2020-01-15 22:25:55.429 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.hue/2.5.1 (Wired to org.openhab.core.automation/2.5.0 which is being refreshed)
2020-01-15 22:25:55.431 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.mail/2.5.1 (Wired to org.openhab.core.automation/2.5.0 which is being refreshed)
2020-01-15 22:25:55.432 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.telegram/2.5.1 (Wired to org.openhab.core.automation/2.5.0 which is being refreshed)
2020-01-15 22:25:55.434 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.zwave/2.5.1 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.binding.zwave/2.5.1] osgi.wiring.package; filter:="(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)"))
2020-01-15 22:25:55.435 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation/2.5.0 (Bundle will be uninstalled)
2020-01-15 22:25:55.437 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.media/2.5.0 (Bundle will be uninstalled)
2020-01-15 22:25:55.438 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script/2.5.0 (Bundle will be uninstalled)
2020-01-15 22:25:55.440 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script.rulesupport/2.5.0 (Bundle will be uninstalled)
2020-01-15 22:25:55.441 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.rest/2.5.0 (Bundle will be uninstalled)
2020-01-15 22:25:55.443 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.config.serial/2.5.0 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.core.config.serial/2.5.0] osgi.wiring.package; filter:="(&(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)(version>=2.5.0)(!(version>=3.0.0)))"))
2020-01-15 22:25:55.444 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial/2.5.0 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.core.io.transport.serial/2.5.0] osgi.wiring.package; filter:="(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)"))
2020-01-15 22:25:55.445 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx/2.5.0 (Wired to org.openhab.core.io.transport.serial/2.5.0 which is being refreshed)
2020-01-15 22:25:55.447 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx.rfc2217/2.5.0 (Wired to org.openhab.core.io.transport.serial/2.5.0 which is being refreshed)
2020-01-15 22:25:58.661 [INFO ] [internal.service.FeaturesServiceImpl] - Done.
2020-01-15 22:26:02.840 [WARN ] [org.apache.felix.fileinstall ] - Error while starting bundle: file:/usr/share/openhab2/addons/org.openhab.core.automation.module.script.scriptenginefactory.jython-2.5.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.openhab.core.automation.module.script.scriptenginefactory.jython [266]
Unresolved requirement: Import-Package: org.openhab.core.automation.module.script; version="[2.5.0,3.0.0)"
at org.eclipse.osgi.container.Module.start(Module.java:444) ~[org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) ~[org.eclipse.osgi-3.12.100.jar:?]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [bundleFile:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [bundleFile:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startAllBundles(DirectoryWatcher.java:1221) [bundleFile:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:515) [bundleFile:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [bundleFile:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [bundleFile:3.6.4]
2020-01-15 22:26:15.520 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
Re-Installing NGRE resulted in:
2020-01-15 22:35:05.734 [INFO ] [internal.service.FeaturesServiceImpl] - Adding features: openhab-misc-ruleengine/[2.5.1,2.5.1]
2020-01-15 22:35:37.380 [INFO ] [internal.service.FeaturesServiceImpl] - Changes to perform:
2020-01-15 22:35:37.382 [INFO ] [internal.service.FeaturesServiceImpl] - Region: root
2020-01-15 22:35:37.384 [INFO ] [internal.service.FeaturesServiceImpl] - Bundles to install:
2020-01-15 22:35:37.386 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation/2.5.0
2020-01-15 22:35:37.388 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:35:37.390 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:35:37.392 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:35:37.394 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.rest/2.5.0
2020-01-15 22:35:37.397 [INFO ] [internal.service.FeaturesServiceImpl] - Stopping bundles:
2020-01-15 22:35:37.400 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.config.serial/2.5.0
2020-01-15 22:35:37.426 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.zwave/2.5.1
2020-01-15 22:36:01.420 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.telegram/2.5.1
2020-01-15 22:36:01.451 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.mail/2.5.1
2020-01-15 22:36:01.516 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.hue/2.5.1
2020-01-15 22:36:01.705 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx.rfc2217/2.5.0
2020-01-15 22:36:01.719 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial/2.5.0
2020-01-15 22:36:01.747 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx/2.5.0
2020-01-15 22:36:01.780 [INFO ] [internal.service.FeaturesServiceImpl] - Installing bundles:
2020-01-15 22:36:01.783 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation/2.5.0
2020-01-15 22:36:01.866 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:36:01.887 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:36:01.919 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:36:01.946 [INFO ] [internal.service.FeaturesServiceImpl] - mvn:org.openhab.core.bundles/org.openhab.core.automation.rest/2.5.0
2020-01-15 22:36:02.036 [INFO ] [internal.service.FeaturesServiceImpl] - Refreshing bundles:
2020-01-15 22:36:02.039 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.hue/2.5.1 (Should be wired to: org.openhab.core.automation/2.5.0 (through [org.openhab.binding.hue/2.5.1] osgi.wiring.package; resolution:=optional; filter:="(osgi.wiring.package=org.openhab.core.automation.annotation)"))
2020-01-15 22:36:02.041 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.mail/2.5.1 (Should be wired to: org.openhab.core.automation/2.5.0 (through [org.openhab.binding.mail/2.5.1] osgi.wiring.package; resolution:=optional; filter:="(osgi.wiring.package=org.openhab.core.automation.annotation)"))
2020-01-15 22:36:02.044 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.telegram/2.5.1 (Should be wired to: org.openhab.core.automation/2.5.0 (through [org.openhab.binding.telegram/2.5.1] osgi.wiring.package; resolution:=optional; filter:="(osgi.wiring.package=org.openhab.core.automation.annotation)"))
2020-01-15 22:36:02.046 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.binding.zwave/2.5.1 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.binding.zwave/2.5.1] osgi.wiring.package; filter:="(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)"))
2020-01-15 22:36:02.048 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.config.serial/2.5.0 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.core.config.serial/2.5.0] osgi.wiring.package; filter:="(&(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)(version>=2.5.0)(!(version>=3.0.0)))"))
2020-01-15 22:36:02.050 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial/2.5.0 (Should be wired to: org.openhab.core.io.transport.serial/2.5.0 (through [org.openhab.core.io.transport.serial/2.5.0] osgi.wiring.package; filter:="(osgi.wiring.package=org.eclipse.smarthome.io.transport.serial)"))
2020-01-15 22:36:02.053 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx/2.5.0 (Wired to org.openhab.core.io.transport.serial/2.5.0 which is being refreshed)
2020-01-15 22:36:02.055 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.io.transport.serial.rxtx.rfc2217/2.5.0 (Wired to org.openhab.core.io.transport.serial/2.5.0 which is being refreshed)
2020-01-15 22:36:07.527 [INFO ] [internal.service.FeaturesServiceImpl] - Starting bundles:
2020-01-15 22:36:07.547 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script.rulesupport/2.5.0
2020-01-15 22:36:07.694 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.script/2.5.0
2020-01-15 22:36:07.835 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for jython (2.7.0): supports python (2.7) with file extensions [py], names [python, jython], and mimetypes [text/python, application/python, text/x-python, application/x-python]
2020-01-15 22:36:08.068 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a generic ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:36:08.132 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:36:08.142 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed JythonScriptEngineFactory
2020-01-15 22:36:08.147 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed GenericScriptEngineFactory
2020-01-15 22:36:08.152 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Removed NashornScriptEngineFactory
2020-01-15 22:36:08.181 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for jython (2.7.0): supports python (2.7) with file extensions [py], names [python, jython], and mimetypes [text/python, application/python, text/x-python, application/x-python]
2020-01-15 22:36:08.266 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a generic ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:36:08.340 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for Oracle Nashorn (1.8.0_222): supports ECMAScript (ECMA - 262 Edition 5.1) with file extensions [js], names [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], and mimetypes [application/javascript, application/ecmascript, text/javascript, text/ecmascript]
2020-01-15 22:36:08.932 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation/2.5.0
2020-01-15 22:36:11.643 [DEBUG] [.AutomationResourceBundlesEventQueue] - Process bundle event 2, for automation bundle 'org.openhab.core.automation'
2020-01-15 22:36:11.659 [DEBUG] [.AutomationResourceBundlesEventQueue] - Process bundle event 2, for automation bundle 'org.openhab.core.automation.module.script.rulesupport'
2020-01-15 22:36:11.662 [DEBUG] [vider.AbstractResourceBundleProvider] - Parse rules from bundle 'org.openhab.core.automation'
2020-01-15 22:36:11.682 [DEBUG] [vider.AbstractResourceBundleProvider] - Parse rules from bundle 'org.openhab.core.automation.module.script.rulesupport'
2020-01-15 22:36:12.450 [INFO ] [me.storage.json.internal.JsonStorage] - Json storage file at '/var/lib/openhab2/jsondb/backup/1576456114079--automation_rules_disabled.json' is used (backup 1).
2020-01-15 22:36:12.463 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedCustomModuleHandlerFactory
2020-01-15 22:36:12.468 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptedPrivateModuleHandlerFactory
2020-01-15 22:36:12.473 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added ScriptModuleHandlerFactory
2020-01-15 22:36:12.478 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added CoreModuleHandlerFactory
2020-01-15 22:36:12.482 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added EphemerisModuleHandlerFactory
2020-01-15 22:36:12.487 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added TimerModuleHandlerFactory
2020-01-15 22:36:12.491 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added AnnotatedActionModuleTypeProvider
2020-01-15 22:36:12.784 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added AnnotatedThingActionModuleTypeProvider
2020-01-15 22:36:12.794 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.module.media/2.5.0
2020-01-15 22:36:12.869 [DEBUG] [e.automation.internal.RuleEngineImpl] - ModuleHandlerFactory added MediaModuleHandlerFactory
2020-01-15 22:36:12.902 [INFO ] [internal.service.FeaturesServiceImpl] - org.openhab.core.automation.rest/2.5.0
2020-01-15 22:36:13.024 [INFO ] [internal.service.FeaturesServiceImpl] - Done.
2020-01-15 22:36:26.872 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2020-01-15 22:36:33.267 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-01-15 22:36:33.269 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-01-15 22:36:33.485 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'javascript/core/000_startup_delay.js'
2020-01-15 22:36:34.188 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'js' with identifier: file:/etc/openhab2/automation/jsr223/javascript/core/000_startup_delay.js
2020-01-15 22:36:34.305 [INFO ] [jsr223.javascript.core.startup_delay] - Checking for initialized context
2020-01-15 22:36:34.410 [INFO ] [jsr223.javascript.core.startup_delay] - Context initialized... waiting 30s before allowing scripts to load
2020-01-15 22:37:05.553 [INFO ] [jsr223.javascript.core.startup_delay] - Complete
2020-01-15 22:37:05.560 [INFO ] [me.core.service.AbstractWatchService] - Loading script 'python/core/000_startup_delay.py'
2020-01-15 22:37:05.565 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Added ScriptEngine for language 'py' with identifier: file:/etc/openhab2/automation/jsr223/python/core/000_startup_delay.py
Another restart of Jython ScriptEngineFactory bundle gave:
2020-01-15 22:40:07.424 [DEBUG] [ipt.internal.ScriptEngineManagerImpl] - Initialized a custom ScriptEngineFactory for jython (2.7.0): supports python (2.7) with file extensions [py], names [python, jython], and mimetypes [text/python, application/python, text/x-python, application/x-python]