Skip to content
This repository has been archived by the owner on May 7, 2020. It is now read-only.

ManagedMetadataProvider Warnings #5490

Closed
AngelosF opened this issue Apr 27, 2018 · 0 comments · Fixed by #5495
Closed

ManagedMetadataProvider Warnings #5490

AngelosF opened this issue Apr 27, 2018 · 0 comments · Fixed by #5495
Labels
bug Core

Comments

@AngelosF
Copy link

AngelosF commented Apr 27, 2018 •
edited

System info:
openHAB 2.3.0 Snapshot #1264
Debian Jessie (Linux 3.16.0-5-amd64 #1 SMP Debian 3.16.51-3+deb8u1 (2018-01-08) x86_64 GNU/Linux)
Java HotSpot(TM) 64-Bit Server VM (build 25.171-b11, mixed mode)
binding = knx1,mqtt1,zwave,weather1,samsungtv,astro,ntp,wol1
persistence = mapdb, influxdb

Issue:
Following the upgrade of OH2.3 Snapshot to build #1264 (Apr 26, 2018 3:32:56 PM)
I get the following in the openhab.log everytime an *.items file is loaded: (multiple entries of "no ManagedMetadataProvider was available" on WARN log level:

Log start:

2018-04-27 08:21:19.146 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to 'xxxx,yyyy'.
2018-04-27 08:21:19.404 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://172.16.13.100:8081
2018-04-27 08:21:19.406 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://172.16.13.100:8444

Relevant section:

2018-04-27 08:21:24.652 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'KNX.items'
2018-04-27 08:21:24.679 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'All_GF_Lights', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.684 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P03_Wh_Light', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.685 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P04_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.686 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P04_Mirror', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.687 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P05_Light', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.688 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P06_Large_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.689 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P06_Small_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.690 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P07_Large_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.692 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P07_Small_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.693 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P08_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.694 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P08_Ventilator', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.695 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P08_Light', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.696 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P08_Terrace_Large_LED', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:24.698 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'P08_Terrace_Small_LED', presumably no ManagedMetadataProvider was available
[...] (multiple entries of "no ManagedMetadataProvider was available" [...]
2018-04-27 08:21:25.607 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'ntp.items'
2018-04-27 08:21:25.610 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'NTP_Time', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:25.615 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'SamsungTV.items'
2018-04-27 08:21:25.618 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'TV11_Volume', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:25.619 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'TV11_Mute', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:25.620 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'TV11_Power', presumably no ManagedMetadataProvider was available
2018-04-27 08:21:25.621 [WARN ] [core.internal.items.ItemRegistryImpl] - Could not persist tags of item 'TV11_Key_C', presumably no ManagedMetadataProvider was available

Log end:

2018-04-27 08:21:25.795 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mapdb.persist'
2018-04-27 08:21:25.806 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'influxdb.persist'
2018-04-27 08:21:26.572 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-04-27 08:21:27.614 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Sun.rules'
2018-04-27 08:21:27.727 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Master_Lights.rules'
2018-04-27 08:21:27.774 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'UPS.rules'
2018-04-27 08:21:28.736 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Fib_Eyes.rules'
2018-04-27 08:21:28.781 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Email.rules'
2018-04-27 08:21:28.940 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Fib_Buttons.rules'
2018-04-27 08:21:29.015 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Paradox.rules'
2018-04-27 08:21:29.047 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Simona_Home.rules'
2018-04-27 08:21:34.849 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Unifi.rules'
2018-04-27 08:21:35.112 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Ana.sitemap'
2018-04-27 08:21:35.131 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'HomeR.sitemap'
2018-04-27 08:21:35.228 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Astro.things'
2018-04-27 08:21:35.295 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'ntp.things'
2018-04-27 08:21:35.300 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'SamsungTV.things'
2018-04-27 08:21:35.310 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mPower.things'
2018-04-27 08:21:35.318 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Unifi.things'
2018-04-27 08:21:35.629 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:sun:local
2018-04-27 08:21:35.662 [INFO ] [ding.astro.handler.AstroThingHandler] - Scheduled Positional job astro:sun:local every 60 seconds
2018-04-27 08:21:35.695 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:moon:local
2018-04-27 08:21:35.708 [INFO ] [ding.astro.handler.AstroThingHandler] - Scheduled Positional job astro:moon:local every 60 seconds
2018-04-27 08:21:36.797 [INFO ] [nx.internal.connection.KNXConnection] - Established connection to KNX bus on 224.0.23.12:3671 in mode ROUTER.
2018-04-27 08:21:36.802 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2018-04-27 08:21:36.806 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'homer'
2018-04-27 08:21:37.399 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-04-27 08:21:37.448 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-04-27 08:21:37.561 [INFO ] [b.core.service.AbstractActiveService] - NetworkUpsTools Refresh Service has been started
2018-04-27 08:21:37.563 [INFO ] [b.core.service.AbstractActiveService] - NetworkUpsTools Refresh Service has been started
2018-04-27 08:21:37.884 [INFO ] [eather.internal.common.WeatherConfig] - ProviderConfig[providerName=WUNDERGROUND,apiKey=xxx]
2018-04-27 08:21:37.885 [INFO ] [eather.internal.common.WeatherConfig] - LocationConfig[providerName=WUNDERGROUND,language=en,updateInterval=10,la
titude=xxxx,longitude=yyyy,woeid=<null>,locationId=HomeR,name=HomeR]
2018-04-27 08:21:39.626 [INFO ] [nternal.connector.MpowerSSHConnector] - connected to mPower on host 172.16.13.1
2018-04-27 08:21:39.762 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2018-04-27 08:21:39.834 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-04-27 08:21:40.753 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port '/dev/ttyACM0'
2018-04-27 08:21:40.778 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2018-04-27 08:21:40.785 [INFO ] [mmandclass.ZWaveSecurityCommandClass] - Update networkKey
2018-04-27 08:21:40.798 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2018-04-27 08:21:40.798 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2018-04-27 08:21:40.906 [INFO ] [ternal.scheduler.WeatherJobScheduler] - Starting and scheduling weatherJob-HomeR with interval of 10 minutes
2018-04-27 08:21:42.734 [INFO ] [nternal.connector.MpowerSSHConnector] - connected to mPower on host 172.16.13.6
2018-04-27 08:21:43.949 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 1: Node found
2018-04-27 08:21:43.949 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 2: Node found
2018-04-27 08:21:43.950 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 3: Node found
2018-04-27 08:21:43.950 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 4: Node found
2018-04-27 08:21:43.951 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 6: Node found
2018-04-27 08:21:43.951 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 8: Node found
2018-04-27 08:21:43.951 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 9: Node found
2018-04-27 08:21:43.952 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2018-04-27 08:21:43.952 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2018-04-27 08:21:43.952 [INFO ] [age.SerialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller---------
---
2018-04-27 08:21:43.953 [INFO ] [age.SerialApiGetInitDataMessageClass] - # Nodes = 7
2018-04-27 08:21:43.953 [INFO ] [age.SerialApiGetInitDataMessageClass] - -------------------------------------------------------------------------
---
2018-04-27 08:21:45.564 [INFO ] [nternal.connector.MpowerSSHConnector] - connected to mPower on host 172.16.13.3
2018-04-27 08:21:47.805 [INFO ] [nternal.connector.MpowerSSHConnector] - connected to mPower on host 172.16.13.4

Community forum reference: https://community.openhab.org/t/oh2-3s1264-warning-could-not-persist-tags-of-item-during-start-up/44203
sjsf added a commit to sjsf/smarthome that referenced this issue Apr 27, 2018
@sjsf
[Metadata] delay metadata modifications if the managed provider is mi…
2836afa 
…ssing

Until now, metadata simply was not persisted if the managed provider was not
available.

This became obvious in eclipse-archived#5490 when storing tags was not possible suring startup,
but actually all other use-cases related to metadata would be affected too.
Therefore this is fixed in the metadata registry directly and not only for tags
in the item registry.

fixes eclipse-archived#5490
Signed-off-by: Simon Kaufmann <simon.kfm@googlemail.com>
@sjsf sjsf mentioned this issue Apr 27, 2018
Merged
sjsf added a commit to sjsf/smarthome that referenced this issue Apr 27, 2018
@sjsf
[metadata] make managed provider mandatory
1b0c051 
...in oder to prevent that e.g. the item registry cannot persist
tags at any time.

fixes eclipse-archived#5490
Signed-off-by: Simon Kaufmann <simon.kfm@googlemail.com>
sjsf added a commit to sjsf/smarthome that referenced this issue Apr 27, 2018
@sjsf
[metadata] deal with managed provider not being available for migration
8379ee9 
fixes eclipse-archived#5490
Signed-off-by: Simon Kaufmann <simon.kfm@googlemail.com>
kaikreuzer pushed a commit that referenced this issue Apr 27, 2018
@sjsf @kaikreuzer
[metadata] deal with managed provider not being available for migrati…
6199ffe 
…on (#5495)

fixes #5490
Signed-off-by: Simon Kaufmann <simon.kfm@googlemail.com>
ermartens pushed a commit to ermartens/smarthome that referenced this issue Jun 15, 2018
@sjsf
[metadata] deal with managed provider not being available for migrati…
c4ffc93 
…on (eclipse-archived#5495)

fixes eclipse-archived#5490
Signed-off-by: Simon Kaufmann <simon.kfm@googlemail.com>
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
bug Core
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

[Metadata] delay metadata modifications if the managed provider is mi… sjsf/smarthome
2 participants
@sjsf @AngelosF