{"action":"created","issue":{"url":"https://api.github.com/repos/eclipse/smarthome/issues/740","labels_url":"https://api.github.com/repos/eclipse/smarthome/issues/740/labels{/name}","comments_url":"https://api.github.com/repos/eclipse/smarthome/issues/740/comments","events_url":"https://api.github.com/repos/eclipse/smarthome/issues/740/events","html_url":"https://github.com/eclipse/smarthome/issues/740","id":122216951,"number":740,"title":"\"Automation Integration Tests\" test failed","user":{"login":"maggu2810","id":204670,"avatar_url":"https://avatars.githubusercontent.com/u/204670?v=3","gravatar_id":"","url":"https://api.github.com/users/maggu2810","html_url":"https://github.com/maggu2810","followers_url":"https://api.github.com/users/maggu2810/followers","following_url":"https://api.github.com/users/maggu2810/following{/other_user}","gists_url":"https://api.github.com/users/maggu2810/gists{/gist_id}","starred_url":"https://api.github.com/users/maggu2810/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/maggu2810/subscriptions","organizations_url":"https://api.github.com/users/maggu2810/orgs","repos_url":"https://api.github.com/users/maggu2810/repos","events_url":"https://api.github.com/users/maggu2810/events{/privacy}","received_events_url":"https://api.github.com/users/maggu2810/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":8,"created_at":"2015-12-15T08:13:31Z","updated_at":"2015-12-16T08:50:53Z","closed_at":null,"body":"Current master, clean working copy\r\n\r\n```text\r\n$ git status; git show HEAD\r\nOn branch master\r\nYour branch is up-to-date with 'origin/master'.\r\nnothing to commit, working directory clean\r\ncommit 811769968085c4a24eca33a8b2aa5ecb57a0aa3e\r\nMerge: dcdfcb4 fc46c8b\r\nAuthor: Kai Kreuzer <kai@openhab.org>\r\nDate:   Mon Dec 14 22:50:21 2015 +0100\r\n\r\n    Merge pull request #739 from maggu2810/rest-locale\r\n    \r\n    add more localization to the REST API\r\n```\r\n\r\n```text\r\n[INFO] ------------------------------------------------------------------------\r\n[INFO] Building Eclipse SmartHome Automation Integration Tests 0.8.0-SNAPSHOT\r\n[INFO] ------------------------------------------------------------------------\r\n[INFO]\r\n[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Deleting /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target\r\n[INFO]\r\n[INFO] --- tycho-packaging-plugin:0.23.1:build-qualifier (default-build-qualifier) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] The project's OSGi version is 0.8.0.201512150747\r\n[INFO]\r\n[INFO] --- tycho-packaging-plugin:0.23.1:validate-id (default-validate-id) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO]\r\n[INFO] --- tycho-packaging-plugin:0.23.1:validate-version (default-validate-version) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO]\r\n[INFO] --- xtend-maven-plugin:2.6.2:compile (default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] No sources to compile in '/home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/test/groovy'\r\n[INFO]\r\n[INFO] --- build-helper-maven-plugin:1.8:add-source (add-source) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Source directory: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/main/groovy added.\r\n[INFO]\r\n[INFO] --- maven-resources-plugin:2.4.3:resources (default-resources) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Using 'UTF-8' encoding to copy filtered resources.\r\n[INFO] skip non existing resourceDirectory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/main/resources\r\n[INFO]\r\n[INFO] --- tycho-compiler-plugin:0.23.1:compile (default-compile) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Nothing to compile - all classes are up to date\r\n[INFO]\r\n[INFO] --- maven-compiler-plugin:3.1:compile (default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Changes detected - recompiling the module!\r\n[INFO] Using Groovy-Eclipse compiler to compile both Java and Groovy files\r\n[INFO]\r\n[INFO] --- xtend-maven-plugin:2.6.2:xtend-install-debug-info (default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[WARNING] Directory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/main/java is empty. Can't process.\r\n[WARNING] Directory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/xtend-gen is empty. Can't process.\r\n[WARNING] Directory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/main/groovy is empty. Can't process.\r\n[INFO] Installing Xtend files into 0 class files as secondary (via SMAP) debug sources in: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/classes\r\n[INFO]\r\n[INFO] --- xtend-maven-plugin:2.6.2:testCompile (default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] skip compiling sources because the configured directory '[/home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/test/java]' does not exists.\r\n[INFO]\r\n[INFO] --- build-helper-maven-plugin:1.8:add-test-source (add-test-source) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Test Source directory: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/test/groovy added.\r\n[INFO]\r\n[INFO] --- maven-resources-plugin:2.4.3:testResources (default-testResources) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Using 'UTF-8' encoding to copy filtered resources.\r\n[INFO] skip non existing resourceDirectory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/test/resources\r\n[INFO]\r\n[INFO] --- xtend-maven-plugin:2.6.2:xtend-test-install-debug-info (default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[WARNING] Directory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/src/test/java is empty. Can't process.\r\n[WARNING] Directory /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/xtend-gen is empty. Can't process.\r\n[INFO] Installing Xtend files into 0 class files as secondary (via SMAP) debug sources in: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/test-classes\r\n[INFO]\r\n[INFO] --- tycho-source-plugin:0.23.1:plugin-source (plugin-source) @ org.eclipse.smarthome.automation.integration.test ---\r\n[WARNING] bundle localization file /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/plugin.properties not found\r\n[INFO] Building jar: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/org.eclipse.smarthome.automation.integration.test-0.8.0-SNAPSHOT-sources.jar\r\n[INFO]\r\n[INFO] --- target-platform-configuration:0.23.1:target-platform (default-target-platform) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO]\r\n[INFO] --- tycho-packaging-plugin:0.23.1:package-plugin (default-package-plugin) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Building jar: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/org.eclipse.smarthome.automation.integration.test-0.8.0-SNAPSHOT.jar\r\n[INFO]\r\n[INFO] --- tycho-p2-plugin:0.23.1:p2-metadata-default (default-p2-metadata-default) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO]\r\n[INFO] --- tycho-surefire-plugin:0.23.1:test (default-test) @ org.eclipse.smarthome.automation.integration.test ---\r\n[INFO] Expected eclipse log file: /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/work/data/.metadata/.log\r\n[INFO] Command line:\r\n        [/opt/oracle-jdk-bin-1.8.0.66/jre/bin/java, -Dosgi.noShutdown=false, -Dosgi.os=linux, -Dosgi.ws=gtk, -Dosgi.arch=x86_64, -Dosgi.clean=true, -jar, /home/rathgeb/.m2/repository/p2/osgi/bundle/org.eclipse.equinox.launcher/1.3.0.v20140415-2008/org.eclipse.equinox.launcher-1.3.0.v20140415-2008.jar, -data, /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/work/data, -install, /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/work, -configuration, /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/work/configuration, -application, org.eclipse.tycho.surefire.osgibooter.headlesstest, -testproperties, /home/rathgeb/bin/pkgs/eclipse/smarthome-master/git/smarthome/bundles/automation/org.eclipse.smarthome.automation.integration.test/target/surefire.properties]\r\n08:49:34.875 [Start Level: Equinox Container: a0bee161-00a3-0015-1b4c-80ccc9b87d3b] DEBUG o.e.s.core.internal.CoreActivator - Core bundle has been started.\r\n08:49:35.034 [Start Level: Equinox Container: a0bee161-00a3-0015-1b4c-80ccc9b87d3b] DEBUG o.e.s.c.a.i.AutoUpdateActivator - AutoUpdate binding has been started.\r\n08:49:35.053 [Start Level: Equinox Container: a0bee161-00a3-0015-1b4c-80ccc9b87d3b] DEBUG o.e.s.a.m.core.internal.Activator - started bundle automation.module\r\n08:49:35.474 [Component Resolve Thread] DEBUG o.e.s.core.common.ThreadPoolManager - Created thread pool 'ConfigStatusService' with size 5-10\r\n\r\n-------------------------------------------------------\r\n T E S T S\r\n-------------------------------------------------------\r\nRunning org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest\r\n08:49:36.052 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:36.140 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:36.156 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:36.157 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:36.164 [main] DEBUG o.e.s.a.i.c.p.AutomationResourceBundlesEventQueue - Process bundle event 1, for automation bundle 'org.eclipse.smarthome.automation.integration.test'\r\n08:49:36.165 [main] DEBUG o.e.s.a.i.c.p.AutomationResourceBundlesEventQueue - Process bundle event 1, for automation bundle 'org.eclipse.smarthome.automation.module.core'\r\n08:49:36.185 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:36.191 [Automation Provider Processing Queue] DEBUG o.e.s.a.i.c.p.RuleResourceBundleImporter - Parse rules from bundle 'org.eclipse.smarthome.automation.integration.test'\r\n08:49:36.200 [Automation Provider Processing Queue] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:36.203 [Automation Provider Processing Queue] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element ItemSampleRule to ManagedRuleProvider.\r\n08:49:36.209 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule21-1188540569_COMPOSITE\r\n08:49:36.213 [Automation Provider Processing Queue] DEBUG o.e.s.a.i.c.p.RuleResourceBundleImporter - Parse rules from bundle 'org.eclipse.smarthome.automation.module.core'\r\n08:49:36.217 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:36.218 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2262b621, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:36.220 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2262b621 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@201b6b6f\r\n08:49:36.221 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@30bcf3c1, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:36.223 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@30bcf3c1 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@4c4d27c8\r\n08:49:36.224 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule21-1188540569_COMPOSITE to ManagedRuleProvider.\r\n08:49:36.246 [EventAdmin Async Event Dispatcher Thread] DEBUG o.e.s.core.common.ThreadPoolManager - Created thread pool 'safeCall' with size 5-10\r\n08:49:36.269 [pool-3-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ItemStateCondition 'ItemStateCondition6'checking if myMotionItem3 (State=NULL) = ON\r\n08:49:36.269 [pool-3-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ConditionSatisfied --> false\r\n08:49:36.269 [pool-3-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'ItemStateCondition6' of rule 'myRule21-1188540569_COMPOSITE' is unsatisfied.\r\n08:49:36.270 [pool-3-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule21-1188540569_COMPOSITE' is NOT executed, since it has unsatisfied conditions.\r\n08:49:36.271 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myMotionItem3/state\r\n08:49:39.291 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:39.298 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:39.299 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:39.303 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:39.309 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:39.314 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:39.315 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:39.316 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:39.316 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@24d4d7c9, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.316 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@24d4d7c9 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4c37b5b\r\n08:49:39.316 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@73db4768, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.316 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@73db4768 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@3c435123\r\n08:49:39.317 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:39.318 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:39.319 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:39.319 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert a rule added by api is executed as expected\r\n08:49:39.325 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule21\r\n08:49:39.325 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:39.326 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@42f3156d, of rule: myRule21\r\n08:49:39.326 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@1ddae9b5, of rule: myRule21\r\n08:49:39.326 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@1ddae9b5 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@24bdb479\r\n08:49:39.326 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@42f3156d -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@7e3f95fe\r\n08:49:39.327 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule21 to ManagedRuleProvider.\r\n08:49:39.329 [main] INFO  o.e.s.a.core.internal.RuleEngine - The rule rId = myRule21 is already enabled\r\n08:49:39.360 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myMotionItem2/state\r\n08:49:39.360 [pool-6-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'ItemStateConditionID' of rule 'ItemSampleRule' is unsatisfied.\r\n08:49:39.361 [pool-6-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'ItemSampleRule' is NOT executed, since it has unsatisfied conditions.\r\n08:49:39.363 [pool-7-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ItemStateCondition 'ItemStateCondition3'checking if myPresenceItem2 (State=ON) = ON\r\n08:49:39.363 [pool-7-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ConditionSatisfied --> true\r\n08:49:39.364 [pool-7-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item myLampItem2 with Command ON\r\n08:49:39.364 [pool-7-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule21' is executed.\r\n08:49:39.365 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myLampItem2/state\r\n08:49:39.462 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - myLampItem2 State: ON\r\n08:49:39.462 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:39.464 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:39.465 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:39.468 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:39.471 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:39.474 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:39.475 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:39.476 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:39.477 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@62e70ea3, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.477 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@62e70ea3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@675d8c96\r\n08:49:39.477 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@741b3bc3, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.477 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@741b3bc3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@45be7cd5\r\n08:49:39.478 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:39.479 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:39.479 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6d366c9b, of rule: myRule21\r\n08:49:39.479 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@5b58ed3c, of rule: myRule21\r\n08:49:39.479 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@5b58ed3c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3a320ade\r\n08:49:39.479 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6d366c9b -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@64beebb7\r\n08:49:39.480 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:39.482 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:39.482 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - test ModuleTypeProvider and TemplateProvider\r\n08:49:39.524 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:39.526 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:39.526 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:39.529 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:39.530 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:39.535 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:39.536 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:39.536 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:39.537 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@625e134e, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.537 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@625e134e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@89c10b7\r\n08:49:39.537 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5dbe30be, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:39.537 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5dbe30be -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@119f1f2a\r\n08:49:39.538 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:39.539 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:39.539 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7fd4acee, of rule: myRule21\r\n08:49:39.539 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@165b8a71, of rule: myRule21\r\n08:49:39.539 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@165b8a71 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2f058b8a\r\n08:49:39.539 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7fd4acee -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@756cf158\r\n08:49:39.540 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:39.542 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:39.543 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule switches from IDLE to NOT_INITIALIZED if a moduleHanlder disappears and back to IDLE if it appears again\r\n08:49:39.543 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - createSimpleRule\r\n08:49:39.543 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule_1665391757\r\n08:49:39.543 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:39.543 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4e76dac, of rule: myRule_1665391757\r\n08:49:39.544 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@611df6e3, of rule: myRule_1665391757\r\n08:49:39.544 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@611df6e3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@6273c5a4\r\n08:49:39.544 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4e76dac -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5d465e4b\r\n08:49:39.544 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule_1665391757 to ManagedRuleProvider.\r\n08:49:39.546 [main] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.module.core'...\r\n08:49:39.550 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED\r\n08:49:39.552 [main] DEBUG o.e.s.a.m.core.internal.Activator - started bundle automation.module\r\n08:49:39.553 [main] INFO  o.e.s.a.core.internal.RuleEngine - The rule rId = myRule_1665391757 is already enabled\r\n08:49:39.554 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED (HANDLER_MISSING_ERROR): Missing handlers: ItemStateCondition, ItemPostCommandAction, GenericEventTrigger, EventCondition\r\n08:49:39.655 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED (HANDLER_MISSING_ERROR): Missing handlers: ItemStateCondition, ItemPostCommandAction, GenericEventTrigger, EventCondition\r\n08:49:39.755 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED (HANDLER_MISSING_ERROR): Missing handlers: ItemStateCondition, ItemPostCommandAction, GenericEventTrigger, EventCondition\r\n08:49:39.856 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED (HANDLER_MISSING_ERROR): Missing handlers: ItemStateCondition, ItemPostCommandAction, GenericEventTrigger, EventCondition\r\n08:49:39.956 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: NOT_INITIALIZED (HANDLER_MISSING_ERROR): Missing handlers: ItemStateCondition, ItemPostCommandAction, GenericEventTrigger, EventCondition\r\n08:49:40.052 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@625e134e, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.053 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@625e134e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@410b6f1a\r\n08:49:40.053 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5dbe30be, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.054 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5dbe30be -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@31fca473\r\n08:49:40.054 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7fd4acee, of rule: myRule21\r\n08:49:40.055 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@5335e244, of rule: myRule21\r\n08:49:40.055 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@5335e244 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@60e1b304\r\n08:49:40.055 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7fd4acee -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@34ea5c57\r\n08:49:40.058 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4e76dac, of rule: myRule_1665391757\r\n08:49:40.058 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@94370d3, of rule: myRule_1665391757\r\n08:49:40.058 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@94370d3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2a23043e\r\n08:49:40.059 [pool-15-thread-1] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4e76dac -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@721a8642\r\n08:49:40.061 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleStatus: IDLE\r\n08:49:40.063 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.065 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.066 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.069 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.070 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.073 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.074 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.074 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.074 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4a9f80d3, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.075 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4a9f80d3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@41fe9859\r\n08:49:40.075 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5ac86ba5, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.075 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5ac86ba5 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@9635fa\r\n08:49:40.075 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.076 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.077 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2d10e0b1, of rule: myRule21\r\n08:49:40.077 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@1c98290c, of rule: myRule21\r\n08:49:40.077 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@1c98290c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5bda80bf\r\n08:49:40.077 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2d10e0b1 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@71e5f61d\r\n08:49:40.077 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.078 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@51df223b, of rule: myRule_1665391757\r\n08:49:40.078 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@fd46303, of rule: myRule_1665391757\r\n08:49:40.078 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@fd46303 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4204541c\r\n08:49:40.078 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@51df223b -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@6a62689d\r\n08:49:40.078 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.079 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.079 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule created from a more complex template is executed as expected\r\n08:49:40.082 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.082 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@69c79f09, of rule: xtemplateRuleUID\r\n08:49:40.083 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@69c79f09 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@62679465\r\n08:49:40.083 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@6a988392, of rule: xtemplateRuleUID\r\n08:49:40.083 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@6a988392 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@2b58f754\r\n08:49:40.084 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element xtemplateRuleUID to ManagedRuleProvider.\r\n08:49:40.096 [pool-24-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item xtempl_LampItem with Command ON\r\n08:49:40.097 [pool-24-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'xtemplateRuleUID' is executed.\r\n08:49:40.140 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.142 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.143 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.147 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.148 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.151 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.152 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.153 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.153 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7c22d4f, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.153 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7c22d4f -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@60bdf15d\r\n08:49:40.153 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@47da3952, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.154 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@47da3952 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@5fd62371\r\n08:49:40.154 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.155 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.155 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@66629f63, of rule: myRule21\r\n08:49:40.155 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@841e575, of rule: myRule21\r\n08:49:40.155 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@841e575 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1e5f4170\r\n08:49:40.155 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@66629f63 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@6c345c5f\r\n08:49:40.156 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.156 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@1568159, of rule: myRule_1665391757\r\n08:49:40.157 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@4fcee388, of rule: myRule_1665391757\r\n08:49:40.157 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@4fcee388 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3af17be2\r\n08:49:40.157 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@1568159 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@f9879ac\r\n08:49:40.158 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.158 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6e521c1e, of rule: xtemplateRuleUID\r\n08:49:40.158 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6e521c1e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@303e3593\r\n08:49:40.158 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@4ef27d66, of rule: xtemplateRuleUID\r\n08:49:40.159 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@4ef27d66 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@10c8f62\r\n08:49:40.159 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.160 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.161 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert a rule with generic condition works\r\n08:49:40.163 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule_4349\r\n08:49:40.163 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.164 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule_4349 to ManagedRuleProvider.\r\n08:49:40.165 [main] INFO  o.e.s.a.core.internal.RuleEngine - The rule rId = myRule_4349 is already enabled\r\n08:49:40.171 [pool-30-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item myLampItem5 with Command ON\r\n08:49:40.171 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myMotionItem5/state\r\n08:49:40.172 [pool-30-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule_4349' is executed.\r\n08:49:40.172 [pool-26-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'ItemStateConditionID' of rule 'ItemSampleRule' is unsatisfied.\r\n08:49:40.172 [pool-26-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'ItemSampleRule' is NOT executed, since it has unsatisfied conditions.\r\n08:49:40.173 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myLampItem5/state\r\n08:49:40.271 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - myLampItem5 State: ON\r\n08:49:40.272 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.273 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.274 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.277 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.278 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.280 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.281 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.282 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.282 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6d8792db, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.282 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6d8792db -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@493dfb8e\r\n08:49:40.283 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5d25e6bb, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.283 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5d25e6bb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@5df417a7\r\n08:49:40.283 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.284 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.285 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.285 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@403132fc, of rule: myRule21\r\n08:49:40.285 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@71c5b236, of rule: myRule21\r\n08:49:40.285 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@71c5b236 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2f7a7219\r\n08:49:40.285 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@403132fc -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@669513d8\r\n08:49:40.286 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.286 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@361c294e, of rule: myRule_1665391757\r\n08:49:40.286 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@7859e786, of rule: myRule_1665391757\r\n08:49:40.286 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@7859e786 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@314b8f2d\r\n08:49:40.286 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@361c294e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@664a9613\r\n08:49:40.287 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.287 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7876d598, of rule: xtemplateRuleUID\r\n08:49:40.287 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7876d598 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@71104a4\r\n08:49:40.287 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@4985cbcb, of rule: xtemplateRuleUID\r\n08:49:40.288 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@4985cbcb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@549621f3\r\n08:49:40.288 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.289 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.289 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule created from a template is executed as expected\r\n08:49:40.291 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.292 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element templateRuleUID to ManagedRuleProvider.\r\n08:49:40.295 [pool-37-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ItemStateCondition 'ItemStateConditionID_1'checking if templ_MotionItem (State=ON) = ON\r\n08:49:40.296 [pool-37-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ConditionSatisfied --> true\r\n08:49:40.296 [pool-37-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item templ_LampItem with Command ON\r\n08:49:40.296 [pool-37-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'templateRuleUID' is executed.\r\n08:49:40.345 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.347 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.348 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.351 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.352 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.354 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.356 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.357 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.357 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5db4c359, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.357 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5db4c359 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@18e7143f\r\n08:49:40.357 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@f9b7332, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.357 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@f9b7332 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@1bdf8190\r\n08:49:40.358 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.358 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.359 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.360 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.360 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@ec0c838, of rule: myRule21\r\n08:49:40.360 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@6e46d9f4, of rule: myRule21\r\n08:49:40.360 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@6e46d9f4 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@29cfd92b\r\n08:49:40.360 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@ec0c838 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@21c64522\r\n08:49:40.360 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.361 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@460f76a6, of rule: myRule_1665391757\r\n08:49:40.361 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@55f3c410, of rule: myRule_1665391757\r\n08:49:40.361 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@55f3c410 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@770d4269\r\n08:49:40.361 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@460f76a6 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4a8ab068\r\n08:49:40.361 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.362 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6bab2585, of rule: xtemplateRuleUID\r\n08:49:40.362 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6bab2585 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@532a02d9\r\n08:49:40.362 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@611f8234, of rule: xtemplateRuleUID\r\n08:49:40.362 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@611f8234 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@62923ee6\r\n08:49:40.363 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.364 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.364 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule can be added, updated and removed by the api\r\n08:49:40.371 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - createSimpleRule\r\n08:49:40.372 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule_-972033959\r\n08:49:40.372 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_-972033959'\r\n08:49:40.372 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@372ea2bc, of rule: myRule_-972033959\r\n08:49:40.372 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@4cc76301, of rule: myRule_-972033959\r\n08:49:40.372 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@4cc76301 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3f19b8b3\r\n08:49:40.373 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@372ea2bc -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@7de0c6ae\r\n08:49:40.373 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule_-972033959 to ManagedRuleProvider.\r\n08:49:40.374 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule_-972033959/added\r\n08:49:40.378 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Updated element myRule_-972033959 in ManagedRuleProvider.\r\n08:49:40.379 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Updated rule 'myRule_-972033959'.\r\n08:49:40.380 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@50687efb, of rule: myRule_-972033959\r\n08:49:40.380 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule_-972033959/updated\r\n08:49:40.380 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@517bd097, of rule: myRule_-972033959\r\n08:49:40.380 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@517bd097 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4a9cc6cb\r\n08:49:40.380 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@50687efb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5990e6c5\r\n08:49:40.384 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Removed element myRule_-972033959 from ManagedRuleProvider.\r\n08:49:40.385 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule_-972033959/removed\r\n08:49:40.436 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.438 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.438 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.440 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/removed\r\n08:49:40.441 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule_4349/removed\r\n08:49:40.441 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.441 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/ItemSampleRule/removed\r\n08:49:40.442 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.444 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.445 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.446 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.446 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2c715e84, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.446 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2c715e84 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3b9d6699\r\n08:49:40.446 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@7caa550, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.447 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@7caa550 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@70fab835\r\n08:49:40.447 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.448 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.449 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.450 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.450 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@64712be, of rule: myRule21\r\n08:49:40.450 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@53499d85, of rule: myRule21\r\n08:49:40.450 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@53499d85 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@782a4fff\r\n08:49:40.450 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@64712be -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@46c670a6\r\n08:49:40.451 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.451 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2fd1731c, of rule: myRule_1665391757\r\n08:49:40.451 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@5ae76500, of rule: myRule_1665391757\r\n08:49:40.451 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@5ae76500 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1133ec6e\r\n08:49:40.451 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2fd1731c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@355e34c7\r\n08:49:40.452 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.452 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@24f360b2, of rule: xtemplateRuleUID\r\n08:49:40.452 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@24f360b2 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@770d0ea6\r\n08:49:40.452 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@48c40605, of rule: xtemplateRuleUID\r\n08:49:40.453 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@48c40605 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@6cea706c\r\n08:49:40.453 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.454 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.455 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule211105981303_COMPOSITE\r\n08:49:40.455 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:40.455 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@1eba372c, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.455 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@21ec5d87, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.455 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@21ec5d87 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@552518c3\r\n08:49:40.455 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@1eba372c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1a69561c\r\n08:49:40.455 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@59aa20b3, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.456 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@59aa20b3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@138fe6ec\r\n08:49:40.456 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule211105981303_COMPOSITE to ManagedRuleProvider.\r\n08:49:40.467 [pool-49-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'ItemStateConditionID' of rule 'ItemSampleRule' is unsatisfied.\r\n08:49:40.469 [pool-49-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'ItemSampleRule' is NOT executed, since it has unsatisfied conditions.\r\n08:49:40.468 [pool-54-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ItemStateCondition 'ItemStateCondition8'checking if myPresenceItem4 (State=ON) = ON\r\n08:49:40.468 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myMotionItem4/state\r\n08:49:40.469 [pool-54-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ConditionSatisfied --> true\r\n08:49:40.470 [pool-54-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item myLampItem4 with Command ON\r\n08:49:40.471 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Event: smarthome/items/myLampItem4/state\r\n08:49:40.471 [pool-54-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule211105981303_COMPOSITE' is executed.\r\n08:49:40.565 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - myLampItem4 State: ON\r\n08:49:40.565 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.566 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.566 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.569 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.569 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.571 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.572 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.572 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.572 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2a76b80a, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.572 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2a76b80a -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2f4854d6\r\n08:49:40.573 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@61d9efe0, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.573 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@61d9efe0 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@43ed0ff3\r\n08:49:40.573 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.574 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.574 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.575 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:40.575 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@21d5c1a0, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.575 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@4de025bf, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.575 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@4de025bf -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1eef9aef\r\n08:49:40.575 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@21d5c1a0 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@11389053\r\n08:49:40.575 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5db99216, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.576 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5db99216 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@18cc679e\r\n08:49:40.576 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.576 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2e77b8cf, of rule: myRule21\r\n08:49:40.576 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@2c4ca0f9, of rule: myRule21\r\n08:49:40.576 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@2c4ca0f9 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@7df587ef\r\n08:49:40.576 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2e77b8cf -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@6e57e95e\r\n08:49:40.577 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.577 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@740abb5, of rule: myRule_1665391757\r\n08:49:40.577 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@560cbf1a, of rule: myRule_1665391757\r\n08:49:40.577 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@560cbf1a -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@551a20d6\r\n08:49:40.577 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@740abb5 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@578524c3\r\n08:49:40.578 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.578 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7a11c4c7, of rule: xtemplateRuleUID\r\n08:49:40.578 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@7a11c4c7 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4152d38d\r\n08:49:40.578 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@3591009c, of rule: xtemplateRuleUID\r\n08:49:40.579 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@3591009c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@61edc883\r\n08:49:40.579 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.580 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.580 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule can be added by a ruleProvider\r\n08:49:40.580 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - createSimpleRule\r\n08:49:40.580 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created: myRule_-340391353\r\n08:49:40.586 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_-340391353'\r\n08:49:40.586 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2de366bb, of rule: myRule_-340391353\r\n08:49:40.586 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@3f093abe, of rule: myRule_-340391353\r\n08:49:40.586 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@3f093abe -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@4eeea57d\r\n08:49:40.586 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2de366bb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@780ec4a5\r\n08:49:40.587 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy13' has been added.\r\n08:49:40.588 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy13' has been removed.\r\n08:49:40.590 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.591 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.591 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.593 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.593 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.595 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.596 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.596 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.596 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@70ab80e3, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.596 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@70ab80e3 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@67427b69\r\n08:49:40.596 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@78461bc4, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.597 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@78461bc4 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@58c540cf\r\n08:49:40.597 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.597 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.598 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.598 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:40.598 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@532721fd, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.598 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@410954b, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.598 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@410954b -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3b366632\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@532721fd -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@51f49060\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@514eedd8, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@514eedd8 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@3af4e0bf\r\n08:49:40.599 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@245a26e1, of rule: myRule21\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@4d63b624, of rule: myRule21\r\n08:49:40.599 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@4d63b624 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5b800468\r\n08:49:40.600 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@245a26e1 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2e185cd7\r\n08:49:40.600 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.600 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@407cf41, of rule: myRule_1665391757\r\n08:49:40.600 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@6815c5f2, of rule: myRule_1665391757\r\n08:49:40.601 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@6815c5f2 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@60094a13\r\n08:49:40.601 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@407cf41 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5aceec94\r\n08:49:40.601 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.602 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@10b892d5, of rule: xtemplateRuleUID\r\n08:49:40.602 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@10b892d5 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@579d011c\r\n08:49:40.602 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@3670f00, of rule: xtemplateRuleUID\r\n08:49:40.602 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@3670f00 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@42257bdd\r\n08:49:40.602 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.603 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.604 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule with connections is executed\r\n08:49:40.604 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_ConnectionTest'\r\n08:49:40.605 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule21_ConnectionTest to ManagedRuleProvider.\r\n08:49:40.605 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - Rule created and added: myRule21_ConnectionTest\r\n08:49:40.611 [pool-72-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'EventCondition_2' of rule 'myRule21_ConnectionTest' is unsatisfied.\r\n08:49:40.611 [pool-72-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule21_ConnectionTest' is NOT executed, since it has unsatisfied conditions.\r\n08:49:40.612 [pool-66-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The condition 'ItemStateConditionID' of rule 'ItemSampleRule' is unsatisfied.\r\n08:49:40.612 [pool-66-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'ItemSampleRule' is NOT executed, since it has unsatisfied conditions.\r\n08:49:40.613 [pool-64-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ItemStateCondition 'ItemStateCondition6'checking if myMotionItem3 (State=ON) = ON\r\n08:49:40.613 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule21_ConnectionTest/state\r\n08:49:40.613 [pool-64-thread-1] DEBUG o.e.s.a.m.c.h.ItemStateConditionHandler - ConditionSatisfied --> true\r\n08:49:40.614 [pool-64-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item myLampItem3 with Command ON\r\n08:49:40.614 [pool-64-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'myRule21-1188540569_COMPOSITE' is executed.\r\n08:49:40.614 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule21_ConnectionTest/state\r\n08:49:40.615 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/ItemSampleRule/state\r\n08:49:40.615 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/ItemSampleRule/state\r\n08:49:40.615 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state\r\n08:49:40.616 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationTest - RuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state\r\n08:49:40.814 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.815 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.816 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.820 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:40.821 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.begin\r\n08:49:40.823 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.824 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.825 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.825 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@21362712, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.825 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@21362712 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@200a26bc\r\n08:49:40.825 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@bc57b40, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.826 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@bc57b40 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@34abdee4\r\n08:49:40.826 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.827 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_ConnectionTest'\r\n08:49:40.828 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.828 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.829 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:40.829 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@cc6460c, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.829 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@52350abb, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.830 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@52350abb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1a6f2363\r\n08:49:40.830 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@cc6460c -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2427e004\r\n08:49:40.830 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5ebd56e9, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.830 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5ebd56e9 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@2f66e802\r\n08:49:40.830 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.831 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@56b78e55, of rule: myRule21\r\n08:49:40.831 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@76318a7d, of rule: myRule21\r\n08:49:40.831 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@76318a7d -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@3277e499\r\n08:49:40.831 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@56b78e55 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@585811a4\r\n08:49:40.831 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.832 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4c4d362a, of rule: myRule_1665391757\r\n08:49:40.832 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@5400db36, of rule: myRule_1665391757\r\n08:49:40.832 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@5400db36 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2d72f75e\r\n08:49:40.832 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@4c4d362a -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@8ab78bc\r\n08:49:40.832 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.833 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2c5d601e, of rule: xtemplateRuleUID\r\n08:49:40.833 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2c5d601e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@486be205\r\n08:49:40.833 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@f713686, of rule: xtemplateRuleUID\r\n08:49:40.833 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@f713686 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@5a62b2a4\r\n08:49:40.834 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.835 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @Before.finish\r\n08:49:40.835 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - assert that a rule with non existing moduleTypeHandler is added to the ruleRegistry in state NOT_INITIALIZED\r\n08:49:40.836 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_UNINITIALIZED'\r\n08:49:40.836 [main] DEBUG o.e.s.c.c.r.AbstractManagedProvider - Added new element myRule21_UNINITIALIZED to ManagedRuleProvider.\r\n08:49:40.836 [main] INFO  o.e.s.a.i.t.AutomationIntegrationTest - @After\r\n08:49:40.838 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:40.838 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:40.841 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\nTests run: 12, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.188 sec <<< FAILURE! - in org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest\r\nassert that a rule based on a composite modules is initialized and executed correctly(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 3.295 sec  <<< FAILURE!\r\njava.lang.AssertionError:\r\nExpected: is not null\r\n     but: was null\r\n        at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)\r\n        at org.junit.Assert.assertThat(Assert.java:865)\r\n        at org.junit.Assert.assertThat(Assert.java:832)\r\n        at org.junit.Assert$assertThat.callStatic(Unknown Source)\r\n        at org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest$_assert_that_a_rule_based_on_a_composite_modules_is_initialized_and_executed_correctly_closure23.doCall(AutomationIntegrationTest.groovy:363)\r\n        at org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest$_assert_that_a_rule_based_on_a_composite_modules_is_initialized_and_executed_correctly_closure23.doCall(AutomationIntegrationTest.groovy)\r\n        at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)\r\n        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)\r\n        at java.lang.reflect.Method.invoke(Method.java:497)\r\n        at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)\r\n        at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:324)\r\n        at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:278)\r\n        at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1016)\r\n        at org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:39)\r\n        at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:45)\r\n        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:108)\r\n        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:112)\r\n        at org.eclipse.smarthome.test.OSGiTest.waitForAssert(OSGiTest.groovy:181)\r\n        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)\r\n        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)\r\n        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)\r\n        at java.lang.reflect.Method.invoke(Method.java:497)\r\n        at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite$PogoCachedMethodSiteNoUnwrapNoCoerce.invoke(PogoMetaMethodSite.java:207)\r\n        at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite.callCurrent(PogoMetaMethodSite.java:56)\r\n        at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:49)\r\n        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:133)\r\n        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:149)\r\n        at org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest.assert that a rule based on a composite modules is initialized and executed correctly(AutomationIntegrationTest.groovy:363)\r\n\r\nassert a rule added by api is executed as expected(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.162 sec\r\ntest ModuleTypeProvider and TemplateProvider(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.058 sec\r\nassert that a rule switches from IDLE to NOT_INITIALIZED if a moduleHanlder disappears and back to IDLE if it appears again(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.541 sec\r\nassert that a rule created from a more complex template is executed as expected(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.077 sec\r\nassert a rule with generic condition works(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.13 sec\r\nassert that a rule created from a template is executed as expected(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.073 sec\r\nassert that a rule can be added, updated and removed by the api(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.09 sec\r\ntest chain of composite Modules(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.127 sec\r\nassert that a rule can be added by a ruleProvider(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.024 sec\r\nassert that a rule with connections is executed(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.228 sec\r\nassert that a rule with non existing moduleTypeHandler is added to the ruleRegistry in state NOT_INITIALIZED(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationTest)  Time elapsed: 0.02 sec\r\nRunning org.eclipse.smarthome.automation.integration.test.AutomationIntegrationJsonTest\r\n08:49:40.874 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @Before.begin\r\n08:49:40.879 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:40.880 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:40.881 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:40.881 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2e8ab815, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.881 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2e8ab815 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@d1f74b8\r\n08:49:40.881 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@41394595, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:40.881 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@41394595 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@5c77053b\r\n08:49:40.882 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\n08:49:40.883 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_ConnectionTest'\r\n08:49:40.883 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:40.884 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:40.885 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5149f008, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@7072bc39, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@7072bc39 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2ca65ce4\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5149f008 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@327120c8\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5707c1cb, of rule: myRule211105981303_COMPOSITE\r\n08:49:40.885 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@5707c1cb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@672f11c2\r\n08:49:40.886 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:40.886 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2970a5bc, of rule: myRule21\r\n08:49:40.886 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@50305a, of rule: myRule21\r\n08:49:40.886 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@50305a -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@6d511b5f\r\n08:49:40.886 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2970a5bc -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@41200e0c\r\n08:49:40.887 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:40.887 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2ad3a1bb, of rule: myRule_1665391757\r\n08:49:40.887 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@6bc28a83, of rule: myRule_1665391757\r\n08:49:40.887 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@6bc28a83 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@13579834\r\n08:49:40.887 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2ad3a1bb -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@24be2d9c\r\n08:49:40.887 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:40.888 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2555fff0, of rule: xtemplateRuleUID\r\n08:49:40.888 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@2555fff0 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@7a0e1b5e\r\n08:49:40.888 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@702ed190, of rule: xtemplateRuleUID\r\n08:49:40.888 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@702ed190 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@70e29e14\r\n08:49:40.889 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_UNINITIALIZED'\r\n08:49:40.889 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:40.895 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @Before.finish\r\n08:49:40.896 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - assert that rule added by json is executed correctly\r\nAutoUpdate Item -> myPresenceItem\r\nAutoUpdate Item -> myMotionItem\r\n08:49:40.905 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - Event: smarthome/items/myMotionItem/state\r\n08:49:40.906 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"RUNNING\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"RUNNING\",\"statusDetail\":\"NONE\"}\r\n08:49:40.906 [pool-85-thread-1] DEBUG o.e.s.a.m.c.h.ItemPostCommandActionHandler - Executing ItemPostCommandAction on Item myLampItem with Command ON\r\nAutoUpdate Item -> myLampItem\r\n08:49:40.907 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - Event: smarthome/items/myLampItem/state\r\n08:49:40.908 [pool-85-thread-1] DEBUG o.e.s.a.core.internal.RuleEngine - The rule 'ItemSampleRule' is executed.\r\n08:49:40.908 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.008 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @After\r\n08:49:41.009 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:41.009 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:41.012 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\n08:49:41.013 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @Before.begin\r\n08:49:41.015 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'com.sun.proxy.$Proxy8' has been added.\r\n08:49:41.016 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.core.items.ManagedItemProvider' has been added.\r\n08:49:41.016 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21-1188540569_COMPOSITE'\r\n08:49:41.016 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@c1fca1e, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:41.016 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@c1fca1e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@344344fa\r\n08:49:41.016 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@2db2cd5, of rule: myRule21-1188540569_COMPOSITE\r\n08:49:41.016 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.017 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@2db2cd5 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@73393584\r\n08:49:41.018 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.018 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_4349'\r\nRuleEvent: smarthome/rules/myRule21-1188540569_COMPOSITE/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.019 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule_4349/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule_4349/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.019 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_ConnectionTest'\r\n08:49:41.019 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule_4349/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule_4349/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.020 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_ConnectionTest/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21_ConnectionTest/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.020 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_ConnectionTest/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21_ConnectionTest/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.020 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'ItemSampleRule'\r\n08:49:41.022 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.022 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'templateRuleUID'\r\n08:49:41.023 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/ItemSampleRule/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.023 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule211105981303_COMPOSITE'\r\n08:49:41.023 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@750fe12e, of rule: myRule211105981303_COMPOSITE\r\n08:49:41.023 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@f8908f6, of rule: myRule211105981303_COMPOSITE\r\n08:49:41.023 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@f8908f6 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@2ef8a8c3\r\n08:49:41.023 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@750fe12e -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@24f43aa3\r\n08:49:41.023 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@63fd4873, of rule: myRule211105981303_COMPOSITE\r\n08:49:41.024 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/templateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/templateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.024 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@63fd4873 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@7957dc72\r\n08:49:41.024 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21'\r\n08:49:41.024 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/templateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.024 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6ab72419, of rule: myRule21\r\nRuleEvent: smarthome/rules/templateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.024 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@3aacf32a, of rule: myRule21\r\n08:49:41.024 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@3aacf32a -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@82c57b3\r\n08:49:41.024 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@6ab72419 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@5be82d43\r\n08:49:41.025 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule211105981303_COMPOSITE/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule211105981303_COMPOSITE/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.025 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule_1665391757'\r\n08:49:41.025 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5ea502e0, of rule: myRule_1665391757\r\n08:49:41.025 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.Condition@443dbe42, of rule: myRule_1665391757\r\n08:49:41.025 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.Condition@443dbe42 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@1734f68\r\n08:49:41.025 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5ea502e0 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@77b7ffa4\r\n08:49:41.026 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'xtemplateRuleUID'\r\n08:49:41.026 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule211105981303_COMPOSITE/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.026 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5bbc9f97, of rule: xtemplateRuleUID\r\nRuleEvent: smarthome/rules/myRule211105981303_COMPOSITE/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.026 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeCondition@5bbc9f97 -> org.eclipse.smarthome.automation.core.internal.composite.CompositeConditionHandler@7dac3fd8\r\n08:49:41.026 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - create composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@425357dd, of rule: xtemplateRuleUID\r\n08:49:41.026 [main] DEBUG o.e.s.a.c.i.c.CompositeModuleHandlerFactory - Set handler to composite module:org.eclipse.smarthome.automation.core.internal.RuntimeTrigger@425357dd -> org.eclipse.smarthome.automation.core.internal.composite.CompositeTriggerHandler@65f87a2c\r\n08:49:41.026 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.026 [main] DEBUG o.e.s.a.core.internal.RuleEngine - Added rule 'myRule21_UNINITIALIZED'\r\n08:49:41.027 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'org.eclipse.smarthome.automation.core.internal.ManagedRuleProvider' has been added.\r\n08:49:41.027 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.028 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule_1665391757/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule_1665391757/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.028 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule_1665391757/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule_1665391757/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.029 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.029 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.029 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.029 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @Before.finish\r\n08:49:41.030 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - assert that a rule from json file is added automatically\r\n08:49:41.030 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/xtemplateRuleUID/state --> {\"status\":\"IDLE\",\"statusDetail\":\"NONE\"}\r\n08:49:41.030 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.030 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\nRuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"NONE\"}\r\n08:49:41.031 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"HANDLER_INITIALIZING_ERROR\",\"description\":\"Missing handler  \\u0027GenericEventTriggerWhichDoesNotExist\\u0027 for module \\u0027ItemStateChangeTrigger\\u0027\\n\"}\r\nRuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"HANDLER_INITIALIZING_ERROR\",\"description\":\"Missing handler  \\u0027GenericEventTriggerWhichDoesNotExist\\u0027 for module \\u0027ItemStateChangeTrigger\\u0027\\n\"}\r\n08:49:41.031 [ESH-safeCall-4] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - RuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"HANDLER_INITIALIZING_ERROR\",\"description\":\"Missing handler  \\u0027GenericEventTriggerWhichDoesNotExist\\u0027 for module \\u0027ItemStateChangeTrigger\\u0027\\n\"}\r\nRuleEvent: smarthome/rules/myRule21_UNINITIALIZED/state --> {\"status\":\"NOT_INITIALIZED\",\"statusDetail\":\"HANDLER_INITIALIZING_ERROR\",\"description\":\"Missing handler  \\u0027GenericEventTriggerWhichDoesNotExist\\u0027 for module \\u0027ItemStateChangeTrigger\\u0027\\n\"}\r\n08:49:41.044 [main] INFO  o.e.s.a.i.t.AutomationIntegrationJsonTest - @After\r\n08:49:41.045 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider '$Proxy8' has been removed.\r\n08:49:41.045 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedItemProvider' has been removed.\r\n08:49:41.048 [main] DEBUG o.e.s.c.c.registry.AbstractRegistry - Provider 'ManagedRuleProvider' has been removed.\r\nTests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.176 sec - in org.eclipse.smarthome.automation.integration.test.AutomationIntegrationJsonTest\r\nassert that a rule from json file is executed correctly(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationJsonTest)  Time elapsed: 0.139 sec\r\nassert that a rule from json file is added automatically(org.eclipse.smarthome.automation.integration.test.AutomationIntegrationJsonTest)  Time elapsed: 0.036 sec\r\n\r\nResults :\r\n\r\nFailed tests:\r\n  AutomationIntegrationTest.assert that a rule based on a composite modules is initialized and executed correctly:363->OSGiTest.waitForAssert:181\r\nExpected: is not null\r\n     but: was null\r\n\r\nTests run: 14, Failures: 1, Errors: 0, Skipped: 0\r\n\r\n08:49:41.059 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.osgi'...\r\n08:49:41.065 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.integration.test'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.config.xml'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.event'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.parser.gson'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.ds'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.core.autoupdate'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.core'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.hamcrest.core'...\r\n08:49:41.067 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.app'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.core.contenttype'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.core'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.module.core'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.util'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.core.runtime'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.api'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.automation.providers'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.smarthome.config.core'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.preferences'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.codehaus.groovy'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.registry'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.core.jobs'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.tycho.surefire.osgibooter'...\r\n08:49:41.068 [Framework stop] DEBUG o.e.s.c.x.o.XmlDocumentBundleTracker - Removing the XML related objects from module 'org.eclipse.equinox.common'...\r\n08:49:41.309 [Framework stop] DEBUG o.e.s.c.a.i.AutoUpdateActivator - AutoUpdate binding has been stopped.\r\n08:49:41.311 [Framework stop] DEBUG o.e.s.core.internal.CoreActivator - Core bundle has been stopped.\r\n```"},"comment":{"url":"https://api.github.com/repos/eclipse/smarthome/issues/comments/165035743","html_url":"https://github.com/eclipse/smarthome/issues/740#issuecomment-165035743","issue_url":"https://api.github.com/repos/eclipse/smarthome/issues/740","id":165035743,"user":{"login":"danchom","id":11029931,"avatar_url":"https://avatars.githubusercontent.com/u/11029931?v=3","gravatar_id":"","url":"https://api.github.com/users/danchom","html_url":"https://github.com/danchom","followers_url":"https://api.github.com/users/danchom/followers","following_url":"https://api.github.com/users/danchom/following{/other_user}","gists_url":"https://api.github.com/users/danchom/gists{/gist_id}","starred_url":"https://api.github.com/users/danchom/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/danchom/subscriptions","organizations_url":"https://api.github.com/users/danchom/orgs","repos_url":"https://api.github.com/users/danchom/repos","events_url":"https://api.github.com/users/danchom/events{/privacy}","received_events_url":"https://api.github.com/users/danchom/received_events","type":"User","site_admin":false},"created_at":"2015-12-16T08:50:53Z","updated_at":"2015-12-16T08:50:53Z","body":"I'm not sure about this,  yes this will fix the test, but  the rule can be executed when the item's state is not changed yet and the user can not rely on such rule."}}