openHAB 2.3 Release Candidate available!

I keep encountering (during startup processing of rules)

2018-05-22 19:35:52.474 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

Not sure if that’s anything harmless or critical, just wondering as I haven’t seen it before and didn’t see anything in Github .

This is a ZWave log entry. This is nothing new, and it means that you’re system is receiving a message that the binding doesn’t handle.

Is that something really worth for a WARN message and to bother the regular user with it? I’d definitely prefer a DEBUG message in such cases unless something is malfunctioning and the user has a chance to correct it.

2 Likes

It could be reduced, but it has been this way for 3 years, so my point was it’s nothing to be worried about (to answer the question that was asked :wink: ).

However, I do think it fits in correctly with the guidelines as this shouldn’t ever happen.

1 Like

There are also some warnings for items with tags when you start a new instance and choose the Demo package in the “Initial setup”:

21:48:25.744 [INFO ] [del.core.internal.ModelRepositoryImpl] - Loading model 'demo.sitemap'
21:48:25.771 [INFO ] [del.core.internal.ModelRepositoryImpl] - Loading model 'demo.things'
21:48:29.388 [INFO ] [del.core.internal.ModelRepositoryImpl] - Loading model 'demo.rules'
21:48:29.420 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'demo.things'
21:48:29.433 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'demo.sitemap'
21:48:29.449 [INFO ] [del.core.internal.ModelRepositoryImpl] - Refreshing model 'demo.items'
21:48:29.458 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Light_GF_Living_Table in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.460 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Light_GF_Living_Table' has been updated.
21:48:29.460 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Light_C_Workshop in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.461 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Light_C_Workshop' has been updated.
21:48:29.461 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Light_Garden_Garage in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.462 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Light_Garden_Garage' has been updated.
21:48:29.462 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Temperature_GF_Kitchen in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.463 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Temperature_GF_Kitchen' has been updated.
21:48:29.464 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Thermostat in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.465 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Thermostat' has been updated.
21:48:29.465 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Temperature_Setpoint in ManagedMetadataProviderImpl, because it does not exists.
21:48:29.466 [INFO ] [smarthome.event.ItemUpdatedEvent     ] - Item 'Temperature_Setpoint' has been updated.
21:48:29.466 [WARN ] [mmon.registry.AbstractManagedProvider] - Could not update element with key _tags:Thermostat_Mode in ManagedMetadataProviderImpl, because it does not exists.

The warnings don’t show after restarting openHAB.

1 Like

The Paper UI Control page logs a big error in the browser console when it is first loaded but it doesn’t seem to affect functionality:


I can’t seem to change the volume of radio stations via the Basic UI of the Demo sitemap. It logs warnings for this:

22:32:17.391 [WARN ] [.core.audio.internal.AudioManagerImpl] - An exception occurred while getting the volume of sink 'enhancedjavasound' : Cannot determine master volume level

Looks like I’m not the only one with this issue: WARNING - Audio sink warning master volume level


Minor

  • The URL of the FFH radio station in the Demo is broken:
22:28:43.843 [INFO ] [smarthome.event.ItemStateChangedEvent] - Radio_Station changed from 0 to 3
22:28:43.889 [ERROR] [e.smarthome.core.audio.URLAudioStream] - Cannot set up stream 'http://edge.live.mp3.mdn.newmedia.nacamar.net/radioffh/livestream.mp3.m3u': http://edge.live.mp3.mdn.newmedia.nacamar.net/radioffh/livestream.mp3.m3u
java.io.FileNotFoundException: http://edge.live.mp3.mdn.newmedia.nacamar.net/radioffh/livestream.mp3.m3u
	at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1890) ~[?:?]
...
22:28:43.920 [WARN ] [.smarthome.model.script.actions.Audio] - Failed streaming audio url: IO Error
  • The icon of the Map/Location of the “Brandenburg Gate Berlin” in the Widget Overview does not exist. Browser console logs: GET http://localhost:8080/icon/mapview?format=svg 404 (Not Found)

I thought this one would vanish :slight_smile:

I’m pretty sure (though not mentioned in the log) that it’s this item:

Number:Intensity WDa_Cur_SolarRadiation "Strahlung" {channel="weatherunderground:weather:wda:current#solarRadiation"}

And the error is about the W/m2

2018-05-23 00:04:25.541 [ERROR] [me.core.internal.events.EventHandler] - Creation of ESH-Event failed, because one of the registered event factories has thrown an exception: Error invoking #valueOf(String) on class 'org.eclipse.smarthome.core.library.types.QuantityType' with value '0 W/m2'.
java.lang.IllegalStateException: Error invoking #valueOf(String) on class 'org.eclipse.smarthome.core.library.types.QuantityType' with value '0 W/m2'.
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:178) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseType(ItemEventFactory.java:146) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.getState(ItemEventFactory.java:124) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.createStateEvent(ItemEventFactory.java:111) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.createEventByType(ItemEventFactory.java:77) ~[?:?]
        at org.eclipse.smarthome.core.events.AbstractEventFactory.createEvent(AbstractEventFactory.java:50) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.createESHEvent(EventHandler.java:121) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.handleEvent(EventHandler.java:95) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.handleEvent(EventHandler.java:72) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler.lambda$0(ThreadedEventHandler.java:67) ~[?:?]
        at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: java.lang.reflect.InvocationTargetException
        at sun.reflect.GeneratedMethodAccessor88.invoke(Unknown Source) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:169) ~[?:?]
        ... 10 more
Caused by: java.lang.IllegalArgumentException: unexpected token 8
        at tec.uom.se.quantity.Quantities.getQuantity(Quantities.java:80) ~[?:?]
        at org.eclipse.smarthome.core.library.types.QuantityType.<init>(QuantityType.java:89) ~[?:?]
        at org.eclipse.smarthome.core.library.types.QuantityType.valueOf(QuantityType.java:132) ~[?:?]
        at sun.reflect.GeneratedMethodAccessor88.invoke(Unknown Source) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:169) ~[?:?]
        ... 10 more
2018-05-23 00:04:26.237 [ERROR] [me.core.internal.events.EventHandler] - Creation of ESH-Event failed, because one of the registered event factories has thrown an exception: Error invoking #valueOf(String) on class 'org.eclipse.smarthome.core.library.types.QuantityType' with value '0 W/m2'.
java.lang.IllegalStateException: Error invoking #valueOf(String) on class 'org.eclipse.smarthome.core.library.types.QuantityType' with value '0 W/m2'.
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:178) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseType(ItemEventFactory.java:146) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.getState(ItemEventFactory.java:124) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.createStateEvent(ItemEventFactory.java:111) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.createEventByType(ItemEventFactory.java:77) ~[?:?]
        at org.eclipse.smarthome.core.events.AbstractEventFactory.createEvent(AbstractEventFactory.java:50) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.createESHEvent(EventHandler.java:121) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.handleEvent(EventHandler.java:95) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.EventHandler.handleEvent(EventHandler.java:72) ~[?:?]
        at org.eclipse.smarthome.core.internal.events.ThreadedEventHandler.lambda$0(ThreadedEventHandler.java:67) ~[?:?]
        at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: java.lang.reflect.InvocationTargetException
        at sun.reflect.GeneratedMethodAccessor88.invoke(Unknown Source) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:169) ~[?:?]
        ... 10 more
Caused by: java.lang.IllegalArgumentException: unexpected token 8
        at tec.uom.se.quantity.Quantities.getQuantity(Quantities.java:80) ~[?:?]
        at org.eclipse.smarthome.core.library.types.QuantityType.<init>(QuantityType.java:89) ~[?:?]
        at org.eclipse.smarthome.core.library.types.QuantityType.valueOf(QuantityType.java:132) ~[?:?]
        at sun.reflect.GeneratedMethodAccessor88.invoke(Unknown Source) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.smarthome.core.items.events.ItemEventFactory.parseSimpleClassName(ItemEventFactory.java:169) ~[?:?]
        ... 10 more

I finally installed 2.3.0.RC1-1 on a clean rPi3 and started testing it
So far (without any configs/addons): it works fine :lollipop:

(more testing results soon)

1 Like

Thanks - should be fixed with Fixed handling of IRRADIANCE unit by kaikreuzer · Pull Request #5602 · eclipse-archived/smarthome · GitHub.

I get this in the last few builds:

2018-05-23 07:20:52.826 [WARN ] [rm.AbstractFileTransformationService] - Could not transform 'NULL' with the file 'beaufort.scale' : Scale can only be used with numeric inputs

Any way to get rid of that.

Also I get a lot of:

2018-05-23 07:20:15.283 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP

2018-05-23 07:20:15.307 [WARN ] [.core.transform.TransformationHelper] - Couldn't transform value because transformation service of type 'MAP' is not available.

2018-05-23 07:20:15.311 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP

2018-05-23 07:20:15.314 [WARN ] [.core.transform.TransformationHelper] - Couldn't transform value because transformation service of type 'MAP' is not available.

2018-05-23 07:20:15.387 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP

2018-05-23 07:20:15.391 [WARN ] [.core.transform.TransformationHelper] - Couldn't transform value because transformation service of type 'MAP' is not available.

2018-05-23 07:20:15.452 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type SCALE

2018-05-23 07:20:15.465 [WARN ] [.core.transform.TransformationHelper] - Couldn't transform value because transformation service of type 'SCALE' is not available.

2018-05-23 07:20:15.533 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS

2018-05-23 07:20:15.554 [WARN ] [.core.transform.TransformationHelper] - Couldn't transform value because transformation service of type 'JS' is not available.

At start up just after the items files loading.
Is there a way to get the transforms to load before the items?

This will also occur after saving an .items file with tags in it, while OH is running. The warnings are gone after an OH restart.

Both fixed with fixed small issues in demo files by kaikreuzer · Pull Request #715 · openhab/openhab-distro · GitHub.

1 Like

Not sure how to fix this. Could you enter an issue at ESH for it, please? If anyone has an idea how to fix it, please do so - if not, it should be ok to leave it as is for the release if it does not have any functional impact.

Could you please enable this debug logger:

log:set DEBUG org.eclipse.smarthome.core.audio

and post the resulting exception (which will then be logged next to this warning)?
What platform are you running on?

(Likely) same problem for me. Running on Pi3 with Zulu java 1.8.0_152-b76, I have been having to use alsa tools to (statically) set volume. I think it used to work long ago when I was still using Oracle java but can’t tell for sure.
EDIT: However, according to this, the same provlem happens on x86 and Oracle Java.

Calling setMasterVolume(float) gives

2018-05-23 12:11:41.149 [WARN ] [core.audio.internal.AudioManagerImpl] - An exception occurred while getting the volume of sink 'enhancedjavasound' : Cannot determine master volume level
java.io.IOException: Cannot determine master volume level
        at org.eclipse.smarthome.io.javasound.internal.JavaSoundAudioSink.getVolume(JavaSoundAudioSink.java:118) ~[?:?]
        at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.getVolume(AudioManagerImpl.java:184) ~[?:?]
        at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.play(AudioManagerImpl.java:122) ~[?:?]
        at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.playFile(AudioManagerImpl.java:164) ~[?:?]
        at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.playFile(AudioManagerImpl.java:144) ~[?:?]
        at org.eclipse.smarthome.model.script.actions.Audio.playSound(Audio.java:40) ~[?:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeOperation(XbaseInterpreter.java:1085) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeOperation(XbaseInterpreter.java:1060) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._invokeFeature(XbaseInterpreter.java:1046) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeFeature(XbaseInterpreter.java:991) ~[?:?]
        at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.invokeFeature(ScriptInterpreter.java:143) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:901) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:864) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.doEvaluate(XbaseInterpreter.java:223) ~[?:?]
        at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.doEvaluate(ScriptInterpreter.java:219) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.internalEvaluate(XbaseInterpreter.java:203) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:446) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.doEvaluate(XbaseInterpreter.java:227) ~[?:?]
        at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.doEvaluate(ScriptInterpreter.java:219) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.internalEvaluate(XbaseInterpreter.java:203) ~[?:?]
        at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.evaluate(XbaseInterpreter.java:189) ~[?:?]
        at org.eclipse.smarthome.model.script.runtime.internal.engine.ScriptImpl.execute(ScriptImpl.java:82) ~[?:?]
        at org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl.lambda$2(RuleEngineImpl.java:345) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
        at java.lang.Thread.run(Thread.java:748) [?:?]

I have some problems with linking items to a channel in PaperUI. I clicked on the blue button to link a channel in the Configuration -> Thing section, selected “Create new item”, confirmed it by clicking on “Link”. Popup remained open and nothing happened. The Item has been created in the background but the link didn’t establish.

Some more errors in the browser console - missing files / icons - and a dupes check after entering the Configuration -> Items section.

1 Like

Innogy smarthome binding is not working in RC1. OH2 with installed binding is not starting up, removing cache and tmp folders also does not help. After removing the binding from addons.cfg and trying to reinstall the following error messages are displayed:

13:01:52.372 [ERROR] [.core.karaf.internal.FeatureInstaller] - Failed installing ‘openhab-misc-lsp, openhab-binding-innogysmarthome, openhab-misc-myopenhab’: Unable to resolve root: missing requirement [root] osgi.identity; osgi.identity=openhab-binding-innogysmarthome; type=karaf.feature; version=“[2.3.0.RC1,2.3.0.RC1]”; filter:=“(&(osgi.identity=openhab-binding-innogysmarthome)(type=karaf.feature)(version>=2.3.0.RC1)(version<=2.3.0.RC1))” [caused by: Unable to resolve openhab-binding-innogysmarthome/2.3.0.RC1: missing requirement [openhab-binding-innogysmarthome/2.3.0.RC1] osgi.identity; osgi.identity=openhab-httpclient; type=karaf.feature]

The openhab-httpclient seems to be missing, trying to install from karaf leads to:

openhab> feature:install openhab-httpclient
Error executing command: No matching features for openhab-httpclient/0.0.0

Openhab-httpclient not exists on my installation (from apt unstable repo), installing esh-tp-httpclient does not solve the innogy issue:

openhab> feature:list |grep -i httpclient
esh-tp-httpclient │ 0.10.0.oh230RC1 │ x │ Started │ distro-2.3.0.RC1 │

No, but reduced logging level and moved exception handling one level up by kaikreuzer · Pull Request #5603 · eclipse-archived/smarthome · GitHub should remove those warnings from the log.

Thanks, excellent finding! Should be fixed with https://github.com/openhab/openhab2-addons/pull/3585.

I’m testing on Ubuntu 16.04.4 (amd64) and the Oracle 1.8.0_171 JVM. It’s same warning as @mstormi:

13:23:04.643 [WARN ] [.core.audio.internal.AudioManagerImpl] - An exception occurred while getting the volume of sink 'enhancedjavasound' : Cannot determine master volume level
java.io.IOException: Cannot determine master volume level
	at org.eclipse.smarthome.io.javasound.internal.JavaSoundAudioSink.getVolume(JavaSoundAudioSink.java:118) ~[?:?]
	at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.getVolume(AudioManagerImpl.java:184) ~[?:?]
	at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.play(AudioManagerImpl.java:122) ~[?:?]
	at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.stream(AudioManagerImpl.java:175) ~[?:?]
	at org.eclipse.smarthome.core.audio.internal.AudioManagerImpl.stream(AudioManagerImpl.java:169) ~[?:?]
	at org.eclipse.smarthome.model.script.actions.Audio.playStream(Audio.java:81) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeOperation(XbaseInterpreter.java:1085) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeOperation(XbaseInterpreter.java:1060) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._invokeFeature(XbaseInterpreter.java:1046) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.invokeFeature(XbaseInterpreter.java:991) ~[?:?]
	at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.invokeFeature(ScriptInterpreter.java:143) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:901) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:864) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.doEvaluate(XbaseInterpreter.java:223) ~[?:?]
	at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.doEvaluate(ScriptInterpreter.java:219) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.internalEvaluate(XbaseInterpreter.java:203) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:491) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.doEvaluate(XbaseInterpreter.java:255) ~[?:?]
	at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.doEvaluate(ScriptInterpreter.java:219) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.internalEvaluate(XbaseInterpreter.java:203) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter._doEvaluate(XbaseInterpreter.java:446) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.doEvaluate(XbaseInterpreter.java:227) ~[?:?]
	at org.eclipse.smarthome.model.script.interpreter.ScriptInterpreter.doEvaluate(ScriptInterpreter.java:219) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.internalEvaluate(XbaseInterpreter.java:203) ~[?:?]
	at org.eclipse.xtext.xbase.interpreter.impl.XbaseInterpreter.evaluate(XbaseInterpreter.java:189) ~[?:?]
	at org.eclipse.smarthome.model.script.runtime.internal.engine.ScriptImpl.execute(ScriptImpl.java:82) ~[?:?]
	at org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl.lambda$2(RuleEngineImpl.java:345) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]

When changing the volume on the openHAB 2.2.0 Demo it also does not work. It also does not log this warning.