Real OH error, although not really clear:
2017-06-09 20:24:38.515 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 1 triggers
2017-06-09 20:24:38.527 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 1 triggers
2017-06-09 20:24:39.035 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 0 ms
2017-06-09 20:24:39.036 [DEBUG] [pse.xtext.util.OnChangeEvictingCache] - Clear 592 cache entries for resource time_of_day.rules after 6481 hits and 592 misses (quota: 91%)
2017-06-09 20:24:39.041 [INFO ] [el.core.internal.ModelRepositoryImpl] - Refreshing model 'time_of_day.rules'
2017-06-09 20:24:39.042 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 0 ms
2017-06-09 20:24:39.045 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model 'time_of_day.rules' is either empty or cannot be parsed correctly!
2017-06-09 20:24:39.046 [DEBUG] [e.internal.engine.RuleTriggerManager] - Removed scheduled cron job 'time_of_day.rules#Calculate time of day state#0 0 6,23,0 * * ? *'
2017-06-09 20:24:39.046 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 1 triggers
2017-06-09 20:24:39.050 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 3 ms
2017-06-09 20:24:39.050 [DEBUG] [.linking.impl.AbstractCleaningLinker] - beforeModelLinked took: 0ms
2017-06-09 20:24:39.057 [DEBUG] [.linking.impl.AbstractCleaningLinker] - doLinkModel took: 7ms
2017-06-09 20:24:39.057 [DEBUG] [.linking.impl.AbstractCleaningLinker] - afterModelLinked took: 0ms
2017-06-09 20:24:39.072 [DEBUG] [pse.xtext.util.OnChangeEvictingCache] - Clear 423 cache entries for resource tmp_time_of_day.rules after 70 hits and 423 misses (quota: 14%)
2017-06-09 20:24:39.089 [DEBUG] [t.linking.impl.DefaultLinkingService] - before getLinkedObjects: node: 'long'
2017-06-09 20:24:39.095 [DEBUG] [t.linking.impl.DefaultLinkingService] - after getLinkedObjects: node: 'long' result: long
2017-06-09 20:24:39.099 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=162,c=32768,r=162],false)@COMMITTED
2017-06-09 20:24:39.099 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 162 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,945/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.099 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=162,l=162,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.099 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=169,c=32768,r=169],false)@COMMITTED
2017-06-09 20:24:39.100 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 169 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,1/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.100 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=169,l=169,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.101 [DEBUG] [ntime.internal.engine.RuleEngineImpl] - Executing rule 'Total consumption update'
2017-06-09 20:24:39.102 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=171,c=32768,r=171],false)@COMMITTED
2017-06-09 20:24:39.102 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 171 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,2/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.102 [DEBUG] [t.linking.impl.DefaultLinkingService] - before getLinkedObjects: node: 'long'
2017-06-09 20:24:39.102 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=171,l=171,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.105 [DEBUG] [ntime.internal.engine.RuleEngineImpl] - Executing rule 'Total consumption update'
2017-06-09 20:24:39.105 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=167,c=32768,r=167],false)@COMMITTED
2017-06-09 20:24:39.105 [DEBUG] [t.linking.impl.DefaultLinkingService] - after getLinkedObjects: node: 'long' result: long
2017-06-09 20:24:39.105 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 167 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,3/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.105 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=167,l=167,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.105 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=240,c=32768,r=240],false)@COMMITTED
2017-06-09 20:24:39.105 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 240 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,0/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.105 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=240,l=240,c=32768,r=0],false)@COMMITTED
,W,1/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.139 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=166,l=166,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.141 [DEBUG] [t.linking.impl.DefaultLinkingService] - before getLinkedObjects: node: 'DateTimeType'
2017-06-09 20:24:39.142 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=161,c=32768,r=161],false)@COMMITTED
2017-06-09 20:24:39.143 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 161 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,4/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.143 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=161,l=161,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.144 [DEBUG] [t.linking.impl.DefaultLinkingService] - after getLinkedObjects: node: 'DateTimeType' result: DateTimeType
2017-06-09 20:24:39.145 [DEBUG] [t.linking.impl.DefaultLinkingService] - before getLinkedObjects: node: 'long'
2017-06-09 20:24:39.149 [DEBUG] [t.linking.impl.DefaultLinkingService] - after getLinkedObjects: node: 'long' result: long
2017-06-09 20:24:39.154 [DEBUG] [t.linking.impl.DefaultLinkingService] - before getLinkedObjects: node: 'long'
2017-06-09 20:24:39.157 [DEBUG] [t.linking.impl.DefaultLinkingService] - after getLinkedObjects: node: 'long' result: long
2017-06-09 20:24:39.228 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=162,c=32768,r=162],false)@COMMITTED
2017-06-09 20:24:39.228 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 162 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,85/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.228 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=162,l=162,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.228 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: FLUSH (null,[p=0,l=162,c=32768,r=162],false)@COMMITTED
2017-06-09 20:24:39.228 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 162 SelectChannelEndPoint@6e00d91d{/192.168.1.191:52632<->5080,Open,in,out,-,W,0/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-06-09 20:24:39.228 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5e2c992a[PROCESSING][i=null,cb=Blocker@70c2df64{null}] generate: DONE (null,[p=162,l=162,c=32768,r=0],false)@COMMITTED
2017-06-09 20:24:39.231 [DEBUG] [pse.xtext.util.OnChangeEvictingCache] - Clear 592 cache entries for resource tmp_time_of_day.rules after 2544 hits and 592 misses (quota: 81%)
2017-06-09 20:24:39.232 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'time_of_day.rules'
2017-06-09 20:24:39.234 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 2 ms
2017-06-09 20:24:39.234 [DEBUG] [.linking.impl.AbstractCleaningLinker] - beforeModelLinked took: 0ms
2017-06-09 20:24:39.237 [DEBUG] [.linking.impl.AbstractCleaningLinker] - doLinkModel took: 3ms
2017-06-09 20:24:39.237 [DEBUG] [.linking.impl.AbstractCleaningLinker] - afterModelLinked took: 0ms
2017-06-09 20:24:39.248 [DEBUG] [pse.xtext.util.OnChangeEvictingCache] - Clear 423 cache entries for resource time_of_day.rules after 70 hits and 423 misses (quota: 14%)
This is everything on debug, the only hints imho are
2017-06-09 20:24:39.042 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 0 ms
2017-06-09 20:24:39.045 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model 'time_of_day.rules' is either empty or cannot be parsed correctly!
And the fact that rules do not seem to trigger although strange is the following:
2017-06-09 20:24:39.232 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'time_of_day.rules'
2017-06-09 20:24:39.234 [DEBUG] [er.antlr.AbstractInternalAntlrParser] - Parsing took: 2 ms
2017-06-09 20:24:39.234 [DEBUG] [.linking.impl.AbstractCleaningLinker] - beforeModelLinked took: 0ms
2017-06-09 20:24:39.237 [DEBUG] [.linking.impl.AbstractCleaningLinker] - doLinkModel took: 3ms
2017-06-09 20:24:39.237 [DEBUG] [.linking.impl.AbstractCleaningLinker] - afterModelLinked took: 0ms
2017-06-09 20:24:39.248 [DEBUG] [e.internal.engine.RuleTriggerManager] - Scheduled rule 'Calculate time of day state' with cron expression '0 0 6,23,0 * * ? *'
Maybe it reloads twice one when the editor emptied the file and another time after writing…
But my rule is still not triggered, I will try to add some debug statements later one.