Many issues with v2.1

After updating to v2.1 via apt-get on Raspbian I have (a few?) issues. :frowning:
I rebotet and restarted the service.

2017-06-29 00:13:00.075 [WARN ] [nce.extensions.PersistenceExtensions] - There is no queryable persistence service registered with the id 'rrd4j'
2017-06-29 00:13:00.080 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule Update max and min for Temp_Bedroom: cannot invoke method public abstract org.eclipse.smarthome.core.types.State org.eclipse.smar
thome.core.persistence.HistoricItem.getState() on null
2017-06-29 00:14:00.038 [WARN ] [nce.extensions.PersistenceExtensions] - There is no queryable persistence service registered with the id 'rrd4j'
2017-06-29 00:14:00.044 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule Update max and min for Temp_Outside: cannot invoke method public abstract org.eclipse.smarthome.core.types.State org.eclipse.smar
thome.core.persistence.HistoricItem.getState() on null
2017-06-29 00:14:00.051 [WARN ] [nce.extensions.PersistenceExtensions] - There is no queryable persistence service registered with the id 'rrd4j'
2017-06-29 00:14:00.059 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule Update max and min for gTemps_Flat: cannot invoke method public abstract org.eclipse.smarthome.core.types.State org.eclipse.smart
home.core.persistence.HistoricItem.getState() on null
2017-06-29 00:14:00.086 [WARN ] [nce.extensions.PersistenceExtensions] - There is no queryable persistence service registered with the id 'rrd4j'
2017-06-29 00:14:00.092 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule Update max and min for Temp_Bedroom: cannot invoke method public abstract org.eclipse.smarthome.core.types.State org.eclipse.smar
thome.core.persistence.HistoricItem.getState() on null

The rdd4j-addon is ā€œStartedā€ accordung to the karaf console.

This is another strage error:

2017-06-29 00:03:43.172 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.168.1.34
2017-06-29 00:03:43.357 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://172.17.0.1:8080
2017-06-29 00:03:43.359 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.168.1.34
2017-06-29 00:03:43.361 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://172.17.0.1:8443

And when I open http://openhab:8080/ in the browser I get this:

And:

2017-06-29 00:03:15.946 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model 'netatmo.things', using it anyway:
Provide a thing type ID and a thing ID in this format:
 <thingTypeId> <thingId>
Provide a thing type ID and a thing ID in this format:
 <thingTypeId> <thingId>          

But it looks good to me. :confused:

// Bridge configuration:
Bridge netatmo:netatmoapi:home "Netatmo API" [ clientId="xxxxxxxxxxx", clientSecret="xxxxxxxx", username = "xxxxxx@xxxxxxxxx.net", password = "xxxxxxxxx", readStation=true, readThermostat=false] {

// Thing configuration:
Thing netatmo:NAMain:home:inside "Netatmo Inside"  [ equipmentId="70:ee:50:06:a9:xx" ]
Thing netatmo:NAModule1:home:outside "Netatmo Outside"  [ equipmentId="02:00:00:05:db:xx", parentId="70:ee:50:06:a9:xx" ]
}

And:

2017-06-29 00:41:01.940 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP
2017-06-29 00:41:01.942 [WARN ] [.core.transform.TransformationHelper] - couldn't transform value  because transformationService of type 'MAP' is unavailable
2017-06-29 00:41:01.949 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP
2017-06-29 00:41:01.953 [WARN ] [ui.internal.items.ItemUIRegistryImpl] - couldn't transform value in label because transformationService of type 'MAP' is unavailable

And:

2017-06-29 00:41:15.474 [WARN ] [ome.core.thing.internal.ThingManager] - Cannot delegate command 'OFF' for item 'Subwoofer_Color' to handler for channel 'lifx:colorlight:subwoofer:color', because no thing with the UID 'lifx:colorlight:subwoofer' could be found.
2017-06-29 00:41:15.487 [WARN ] [ome.core.thing.internal.ThingManager] - Cannot delegate update 'OFF' for item 'Subwoofer_Color' to handler for channel 'lifx:colorlight:subwoofer:color', because no thing with the UID 'lifx:colorlight:subwoofer' could be found.
2017-06-29 00:41:15.492 [WARN ] [ome.core.thing.internal.ThingManager] - Cannot delegate command 'OFF' for item 'Beamer_Color' to handler for channel 'lifx:colorlight:beamer:color', because no thing with the UID 'lifx:colorlight:beamer' could be found.
2017-06-29 00:41:15.519 [WARN ] [ome.core.thing.internal.ThingManager] - Cannot delegate update 'OFF' for item 'Beamer_Color' to handler for channel 'lifx:colorlight:beamer:color', because no thing with the UID 'lifx:colorlight:beamer' could be found.

I think there is bigger problem, that causes as these issues, sinde almost nothing is working anymore.

I guess it has somesthing to do with the following warning.

2017-06-29 00:38:14.476 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.168.1.34
2017-06-29 00:38:14.496 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://172.17.0.1:8080
2017-06-29 00:38:14.509 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.168.1.34
2017-06-29 00:38:14.513 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://172.17.0.1:8443
2017-06-29 00:38:15.440 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.

Great I forgot to make an image of my sdcard before updating and wont have time tomorrow. :slight_smile:

Not seen these ones before, what do you use to download and install add ons? Do you use the kar files or a cfg file to set it up?

I use config-files for everything.

I had that same problem with lifx, had to uninstall the binding in paper and put org.eclipse.smarthome.binding.lifx-0.9.0.b4.jar in my /usr/share/openhab2/addons/ directory. I ran into this issue ONLY when using .things file.

I deactivated the docker0interface so the ip address issue is solved.

But the other problems still exist.

What is in your addons.cfg file? @sipvoip was that issue also with 2.1?

Issue started a few weeks ago after lifx changs.

addons.cfg

package = expert



# A comma-separated list of bindings to install (e.g. "sonos,knx,zwave")
binding = chromecast,lifx,harmonyhub,owserver1,mqtt1,kostalinverter,kodi,avmfritz,netatmo,onkyo,enocean1,epsonprojector1,weather1

# A comma-separated list of UIs to install (e.g. "basic,paper")
ui = paper,classic,basic

# A comma-separated list of persistence services to install (e.g. "rrd4j,jpa")
persistence = rrd4j,influxdb

# A comma-separated list of actions to install (e.g. "mail,pushover")
action = mqtt, mail

# A comma-separated list of transformation services to install (e.g. "map,jsonpath")
#transformation =

# A comma-separated list of voice services to install (e.g. "marytts,freetts")
#voice =

# A comma-separated list of miscellaneous services to install (e.g. "myopenhab")
misc = openhabcloud

I restored a backup for now and I am going to bed.

Thanks for the help so far.
I will try to solve these issues tomorrow or the day after when I have time.

I notice transformation ā€œmapā€ is missing, adding that should reduce your errors but I doubt thatā€™s the root cause. After doing that, stop openhab and clear (not delete) your /var/lib/openhab2/cache and tmp folders before starting openhab again. Is there any difference?

Upgraded yesterday evening as well since then my homematic binding and zwave binding stopped working. All things are in state uninitialized.
Already removed the binding and re-installed. No changes.
In debug mode I donā€™t see any obvious hints other than very less info about the bindings at all. Seems like the bindings itself do not initialize properly.
Still investigating.

I think I found the easy fix:
Deleting the content of /var/lib/openhab2/cache and /var/lib/openhab2/tmp.

At least openhab is running and accessible again.

But lets go through the error I posted above and try to figure out what the different courses are.

There is no queryable persistence service registered with the id ā€˜rrd4jā€™

I guess this is just, because the vaulues for the last few minutes were missing so some values (AVR, MIN, MAX) could not be calculated.

Found multiple local interfaces - ignoring 192.168.1.34

I am not sure yet, but the webservice is accessible via the normal IP address.
So I think this is a problem af a binding maybe. (harmony is not working yet?!)

Validation issues found in configuration model ā€˜netatmo.thingsā€™, using it anyway:

Not sure yet.

Cannot get service reference for transformation service of type MAP

I am not getting this WARN anymore. Deleting the cache might solved this.

Cannot delegate command ā€˜OFFā€™ for item ā€˜Subwoofer_Colorā€™ to handler for channel ā€˜lifx:colorlight:subwoofer:colorā€™, because no thing with the UID ā€˜lifx:colorlight:subwooferā€™ could be found.

I am not getting this WARN anymore. Deleting the cache might solved this.

It will have solved this, you might have missed my reply to you above:

Iā€™m not entirely sure why this is happening though, it seems as if this is specific to APT so will have to continue looking.

Note that you will have to make the /tmp and set itā€™s permissions if youā€™ve deleted it. (Iā€™m working on that) Iā€™ll go back an edit the above to say just clear it.

Nope, Iā€™m also seeing this with manual installed snapshot builds, especially with the #93* builds.
First start has the errors @christoph_wempe mentioned above (except the multiple interfaces) , second start (after everything has settled) is clean.
Only a few times I had to delete tmp and cache a second time to make it work.
In my opinion. not that big of an issue ā€¦

I still donā€™t understand the whole problem.

But from a user perspective, I would suggest the update should clean the cache and tmp folders if this prevents issues.

The folders are cleared on each update, but there must be something else happening on a new versions first launch which I donā€™t quite understand yet. I will keep looking into the issue.

the Harmony binding is still not working.
The Hub is discovered, but I cannot send or receive activity commands.

I just reproduced this on a test system.

After the update I get:

2017-07-02 01:41:04.621 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 224.0.0.1 Interface: eth0
2017-07-02 01:41:04.628 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 255.255.255.255 Interface: eth0
2017-07-02 01:41:04.629 [TRACE] [discovery.HarmonyHubDiscoveryService] - Request packet sent to: 192.168.1.255 Interface: eth0
2017-07-02 01:41:04.856 [TRACE] [discovery.HarmonyHubDiscoveryService] - READ email:my@mailadress.com;mode:3;accountId:652xxxx;ip:192.168.1.26;port:5222;uuid:e74e1192-8fe0-4a2a-801c-19afd37xxxx;hubId:97;current_fw_version:4.12.36;productId:Pimento;setupSessionType:0;setupSessionClient:Mob-c1578473-7192-4516-a879-f89ce1bacad2;setupSessionIsStale:true;setupSessionSetupType:;setupStatus:0;host_name:Wohnzimmer;friendlyName:Wohnzimmer;discoveryServerUri:https://svcs.myharmony.com/Discovery/Discovery.svc;openApiVersion:2;minimumOpenApiClientVersionRequired:1;recommendedOpenApiClientVersion:1;protocolVersion:{XMPP="1.0", HTTP="1.0", RF="1.0", WEBSOCKET="1.0"};hubProfiles:{Harmony="2.0"};remoteId:6655130;oohEnabled:true
2017-07-02 01:41:05.074 [TRACE] [discovery.HarmonyHubDiscoveryService] - READ email:my@mailadress.com;mode:3;accountId:652xxxx;ip:192.168.1.26;port:5222;uuid:e74e1192-8fe0-4a2a-801c-19afd37xxxx;hubId:97;current_fw_version:4.12.36;productId:Pimento;setupSessionType:0;setupSessionClient:Mob-c1578473-7192-4516-a879-f89ce1bacad2;setupSessionIsStale:true;setupSessionSetupType:;setupStatus:0;host_name:Wohnzimmer;friendlyName:Wohnzimmer;discoveryServerUri:https://svcs.myharmony.com/Discovery/Discovery.svc;openApiVersion:2;minimumOpenApiClientVersionRequired:1;recommendedOpenApiClientVersion:1;protocolVersion:{XMPP="1.0", HTTP="1.0", RF="1.0", WEBSOCKET="1.0"};hubProfiles:{Harmony="2.0"};remoteId:6655130;oohEnabled:true
2017-07-02 01:41:05.292 [TRACE] [discovery.HarmonyHubDiscoveryService] - READ email:my@mailadress.com;mode:3;accountId:652xxxx;ip:192.168.1.26;port:5222;uuid:e74e1192-8fe0-4a2a-801c-19afd37xxxx;hubId:97;current_fw_version:4.12.36;productId:Pimento;setupSessionType:0;setupSessionClient:Mob-c1578473-7192-4516-a879-f89ce1bacad2;setupSessionIsStale:true;setupSessionSetupType:;setupStatus:0;host_name:Wohnzimmer;friendlyName:Wohnzimmer;discoveryServerUri:https://svcs.myharmony.com/Discovery/Discovery.svc;openApiVersion:2;minimumOpenApiClientVersionRequired:1;recommendedOpenApiClientVersion:1;protocolVersion:{XMPP="1.0", HTTP="1.0", RF="1.0", WEBSOCKET="1.0"};hubProfiles:{Harmony="2.0"};remoteId:6655130;oohEnabled:true
2017-07-02 01:42:20.778 [ERROR] [harmonyhub.handler.HarmonyHubHandler] - Could not start activity
java.lang.NullPointerException
        at org.openhab.binding.harmonyhub.handler.HarmonyHubHandler.handleCommand(HarmonyHubHandler.java:109)[206:org.openhab.binding.harmonyhub:2.1.0]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$4.call(ThingManager.java:377)[106:org.eclipse.smarthome.core.thing:0.9.0.b5]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$4.call(ThingManager.java:1)[106:org.eclipse.smarthome.core.thing:0.9.0.b5]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.executeDirectly(SafeMethodCaller.java:218)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:189)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:83)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:67)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.thing.internal.ThingManager.receiveCommand(ThingManager.java:374)[106:org.eclipse.smarthome.core.thing:0.9.0.b5]
        at org.eclipse.smarthome.core.items.events.AbstractItemEventSubscriber.receive(AbstractItemEventSubscriber.java:47)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.internal.events.OSGiEventManager$1.call(OSGiEventManager.java:192)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.internal.events.OSGiEventManager$1.call(OSGiEventManager.java:1)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:181)[99:org.eclipse.smarthome.core:0.9.0.b5]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_131]
        at java.lang.Thread.run(Thread.java:748)[:1.8.0_131]

The harmony binding is working now.

Beside playing around with other bindings.I rebooted the system agein.
Maybe that solved itā€¦ :confused:

Does this mean, I need to delete everything like
sudo rm --recursive /var/lib/openhab2/cache/*

Which looks like this in my case:

-rw-r--r--   1 openhab openhab 1060 Jul  1 15:22 1498914492823.log
-rw-r--r--   1 openhab openhab  639 Jul  1 15:23 1498915357614.log
-rw-r--r--   1 openhab openhab  639 Jul  1 16:17 1498915555260.log
drwxr-xr-x   3 openhab openhab 4096 Jul  1 15:22 org.eclipse.core.runtime
drwxr-xr-x 219 openhab openhab 4096 Jul  3 11:57 org.eclipse.osgi

Correct, same command for /var/lib/openhab2/tmp too:

sudo rm --recursive /var/lib/openhab2/cache/*
sudo rm --recursive /var/lib/openhab2/tmp/*

openHAB should be stopped before you do this.

2 Likes

Alright. Thanks, Ben.