openHAB 2.3 Release Candidate available!


(Kai Kreuzer) #1

All,

We are planning to release openHAB 2.3 next Monday (May 28).
In contrast to our plans, we didn’t manage to provide regular milestone builds on our way there (sorry for that), but we now at least have a Release Candidate build available!

You can download the distro ZIP here and the KAR add-ons here (thanks @pfink!).

The build is also available in the “testing” repos for apt and yum (thanks @Benjy!).

It would be great if as many people as possible could go and test this build - if you come across critical or major issues that must be solved before the release, please report them here asap!

Thank you all!
Kai


LifX Binding enters race condition of some sort. Causes LifX bulb(s) to flicker
(Markus Storm) #2

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 .


(Chris Jackson) #3

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.


(Kai Kreuzer) #4

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.


(Chris Jackson) #5

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.


(Wouter Born) #6

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.


(Wouter Born) #7

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)

(Udo Hartmann) #8

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

(Angelos) #9

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)


(Kai Kreuzer) #10

Thanks - should be fixed with https://github.com/eclipse/smarthome/pull/5602.


(Vincent Regaud) #11

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?


(Scott Rushworth) #12

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.


(Kai Kreuzer) #13

Both fixed with https://github.com/openhab/openhab-distro/pull/715.


(Kai Kreuzer) #14

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.


(Kai Kreuzer) #15

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?


(Markus Storm) #16

(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) [?:?]

(Christoph Weitkamp) #17

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.


(Martin Forst) #18

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 │


(Kai Kreuzer) #19

No, but https://github.com/eclipse/smarthome/pull/5603 should remove those warnings from the log.


(Kai Kreuzer) #20

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