OH3.2 - no start, after clean-cache

TomaxMe · August 12, 2022, 3:07pm

I’ve restored log from DEBUG to INFO for binding.http, seeing no effect I proceed with clean-cache command. After restart, all become a mess - OH is starting no more:

It starts with this error, then the included bellow come:

2022-08-12 16:32:43.346 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/3.4.0-SNAPSHOT/xml/features

2022-08-12 16:32:30.035 [INFO ] [org.openhab.ui.internal.UIService   ] - Started UI on port 8080
2022-08-12 16:32:42.633 [INFO ] [community.CommunityKarafAddonHandler] - Reinstalling missing marketplace KAR: marketplace:137681
2022-08-12 16:32:43.346 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/3.4.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/3.4.0-SNAPSHOT/xml/features
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:121) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.<init>(RepositoryImpl.java:51) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryCacheImpl.create(RepositoryCacheImpl.java:51) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatureCache(FeaturesServiceImpl.java:611) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.ensureCacheLoaded(FeaturesServiceImpl.java:582) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getRepository(FeaturesServiceImpl.java:530) ~[?:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.findMissingDependency(KarServiceImpl.java:165) ~[?:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:116) ~[?:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:95) ~[?:?]
	at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.installFromCache(CommunityKarafAddonHandler.java:163) ~[?:?]
	at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.lambda$8(CommunityKarafAddonHandler.java:181) ~[?:?]
	at java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183) ~[?:?]
	at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:177) ~[?:?]
	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195) ~[?:?]
	at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:177) ~[?:?]
	at java.util.Iterator.forEachRemaining(Iterator.java:133) ~[?:?]
	at java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801) ~[?:?]
	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484) ~[?:?]
	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474) ~[?:?]
	at java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150) ~[?:?]
	at java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173) ~[?:?]
	at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[?:?]
	at java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:497) ~[?:?]
	at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.ensureCachedKarsAreInstalled(CommunityKarafAddonHandler.java:178) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.configureIOException(AetherBasedResolver.java:803) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:774) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:657) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:598) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:565) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:555) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.Connection.getInputStream(Connection.java:123) ~[?:?]
	at java.net.URL.openStream(URL.java:1165) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 29 more
	Suppressed: shaded.org.eclipse.aether.transfer.ArtifactNotFoundException: Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:403) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:215) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:192) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:247) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:767) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:657) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:598) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:565) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:555) ~[?:?]
		at org.ops4j.pax.url.mvn.internal.Connection.getInputStream(Connection.java:123) ~[?:?]
		at java.net.URL.openStream(URL.java:1165) ~[?:?]
		at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
		at org.apache.karaf.features.internal.service.RepositoryImpl.<init>(RepositoryImpl.java:51) ~[?:?]
		at org.apache.karaf.features.internal.service.RepositoryCacheImpl.create(RepositoryCacheImpl.java:51) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatureCache(FeaturesServiceImpl.java:611) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.ensureCacheLoaded(FeaturesServiceImpl.java:582) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getRepository(FeaturesServiceImpl.java:530) ~[?:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.findMissingDependency(KarServiceImpl.java:165) ~[?:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:116) ~[?:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:95) ~[?:?]
		at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.installFromCache(CommunityKarafAddonHandler.java:163) ~[?:?]
		at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.lambda$8(CommunityKarafAddonHandler.java:181) ~[?:?]
		at java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183) ~[?:?]
		at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:177) ~[?:?]
		at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195) ~[?:?]
		at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:177) ~[?:?]
		at java.util.Iterator.forEachRemaining(Iterator.java:133) ~[?:?]
		at java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801) ~[?:?]
		at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484) ~[?:?]
		at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474) ~[?:?]
		at java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150) ~[?:?]
		at java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173) ~[?:?]
		at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[?:?]
		at java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:497) ~[?:?]
		at org.openhab.core.addon.marketplace.karaf.internal.community.CommunityKarafAddonHandler.ensureCachedKarsAreInstalled(CommunityKarafAddonHandler.java:178) ~[?:?]
		at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
		at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
		at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
		at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
		at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: shaded.org.eclipse.aether.resolution.ArtifactResolutionException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:3.4.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:413) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:215) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:192) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:247) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:767) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:657) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:598) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:565) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.AetherBasedResolver.resolve(AetherBasedResolver.java:555) ~[?:?]
	at org.ops4j.pax.url.mvn.internal.Connection.getInputStream(Connection.java:123) ~[?:?]
	at java.net.URL.openStream(URL.java:1165) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 29 more
2022-08-12 16:32:44.625 [INFO ] [rketplace.MarketplaceBundleInstaller] - Reinstalling missing marketplace bundle: marketplace:130551
2022-08-12 16:32:44.898 [WARN ] [rketplace.MarketplaceBundleInstaller] - The marketplace bundle was successfully installed but doesn't start: Could not resolve module: org.openhab.binding.samsungtv [233]
  Unresolved requirement: Import-Package: org.openhab.core.config.discovery.upnp
2022-08-12 16:33:03.722 [INFO ] [org.openhab.ui.internal.UIService   ] - Stopped UI
2022-08-12 16:33:05.080 [INFO ] [place.internal.json.JsonAddonService] - bundle org.openhab.core.addon.marketplace:3.3.0 (149)[org.openhab.core.addon.marketplace.internal.json.JsonAddonService(153)] : Could not obtain all required dependencies, getService returning null
2022-08-12 16:33:05.459 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-package-standard': null
2022-08-12 16:33:05.512 [WARN ] [core.karaf.internal.FeatureInstaller] - Could not determine addons folder, its content or the list of installed repositories!
2022-08-12 16:33:05.532 [INFO ] [core.karaf.internal.FeatureInstaller] - Some .kar files are not installed yet. Delaying add-on installation by 15s.
2022-08-12 16:33:07.519 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/London'.
2022-08-12 16:33:07.621 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ' '.
2022-08-12 16:33:07.658 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_RO'.
2022-08-12 16:33:07.663 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Measurement system set to 'SI'.
2022-08-12 16:33:13.196 [ERROR] [raf.deployer.features.osgi.Activator] - BundleContext is no longer valid org.apache.karaf.deployer.features_4.3.7 [72]
java.lang.IllegalStateException: BundleContext is no longer valid org.apache.karaf.deployer.features_4.3.7 [72]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.checkValid(BundleContextImpl.java:1026) ~[org.eclipse.osgi-3.17.200.jar:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:494) ~[org.eclipse.osgi-3.17.200.jar:?]
	at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:450) ~[?:?]
	at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:435) ~[?:?]
	at org.apache.karaf.deployer.features.osgi.Activator.access$300(Activator.java:35) ~[?:?]
	at org.apache.karaf.deployer.features.osgi.Activator$DeploymentFinishedListener.deploymentEvent(Activator.java:87) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.callListeners(FeaturesServiceImpl.java:322) ~[?:?]
	at org.apache.karaf.features.internal.service.Deployer.deploy(Deployer.java:1072) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.doProvision(FeaturesServiceImpl.java:1069) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.lambda$doProvisionInThread$13(FeaturesServiceImpl.java:1004) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:829) [?:?]

rlkoshak · August 12, 2022, 3:29pm

When you clean the cache it deletes all your installed add-ons, meaning it needs to go out and download them again. This can take some time. How long have you waited before trying to restart? Why did you clean the cache in the first place? There are only two circumstances where the cache should be cleared:

during an upgrage/downgrade in which case it happens automatically for you
if you have problems installing/deleting an add-on

It might also be the case that there is some sort of networking or server issue where the files are not currently available. This would be a temporary problem.

TomaxMe · August 12, 2022, 3:31pm

I delete them 15 min before posting the topic… Reason: not able to restore log to INFO from DEBUG and somebody here in the forum talk about to follow with solution - clean-cache.

rlkoshak · August 12, 2022, 3:34pm

You can try downloading the kar file for the add-ons from here for your OS and drop that into the addons folder. That will cause OH to grab the bindings and such from that file instead of trying to download them from the Internet.

TomaxMe · August 12, 2022, 3:36pm

Let me try… and I’ll get back asap.

TomaxMe · August 12, 2022, 3:39pm

@rlkoshak : are you talking about this file, right ?

To replace in TMP/Addons/ :

rlkoshak · August 12, 2022, 3:48pm

Yes

No. In the addons folder for your type of installation. On Linux that would be /usr/share/openhab/addons. Look at the “File Locations” section on the installation instructions for how ever you installed OH.

TomaxMe · August 12, 2022, 3:55pm

I found Addons file, but there is already the .kar file, do I have to replace it, right ?

Sorry for asking like a dump head, but I don’t want to mess OH again… it drives me crazy since 2.0 version - when I felt found a stable configuration, adding something new or a new update and start all over to deal with fixes etc.

rlkoshak · August 12, 2022, 4:00pm

Are you running 3.3.0? If not delete that file and see if OH will download the proper files. It is possible to see those errors when you have a mismatch between the version of OH you are running and that kar file.

If you are running the 3.3.0. release, the kar file will not fix the problem.

For that file to exist, you would have had to put it there. Why did you download the kar file in the first place?

TomaxMe · August 12, 2022, 4:22pm

@rlkoshak

OH 3.3.0 running.
Replace the correct version kar file, as you recommanded, then restarted - no success.

One remark here: logs have a unsincronized time (here is 7:21 PM, and log have 5.37 PM).
Also, lost access to OH GUI.

Log here:

2022-08-12 17:37:15.925 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Office_1_Sensorpower' changed from 130.9 to 104.9
2022-08-12 17:37:15.944 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AC_Sensorpower' changed from 0.4 to 0.5
2022-08-12 17:37:17.445 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AC_Sensorpower' changed from 0.5 to 0.4
2022-08-12 17:37:18.109 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node10' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:18.415 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AC_Sensorpower' changed from 0.4 to 0.5
2022-08-12 17:37:18.620 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node10' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:18.647 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node4' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:19.228 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node4' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:19.286 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node7' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:19.999 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node7' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:20.026 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node3' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:20.395 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node3' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:20.412 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node11' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:21.238 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:66326849a7:node11' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:21.248 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:66326849a7' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:21.567 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:66326849a7' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:25.280 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'network:pingdevice:pingDimmerGates' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:25.469 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'network:pingdevice:pingDimmerGates' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:25.514 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'mqtt:broker:MQTTBroker' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:26.236 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'mqtt:broker:MQTTBroker' changed from UNINITIALIZED to OFFLINE (COMMUNICATION_ERROR): Client sent DISCONNECT
2022-08-12 17:37:26.265 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'mqtt:broker:MQTTBroker' changed from OFFLINE (COMMUNICATION_ERROR): Client sent DISCONNECT to OFFLINE
2022-08-12 17:37:26.385 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'mqtt:broker:MQTTBroker' changed from OFFLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:37:26.409 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'http:url:dimmerGates' changed from ONLINE to UNINITIALIZED
2022-08-12 17:37:26.474 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'http:url:dimmerGates' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-08-12 17:29:39.068 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@22152619[provider=null,keyStore=null,trustStore=null]
2022-08-12 17:29:39.098 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@22152619[provider=null,keyStore=null,trustStore=null]
2022-08-12 17:29:41.202 [INFO ] [nding.http.internal.HttpThingHandler] - Using the secure client for thing 'http:url:dimmerGates'.
2022-08-12 17:29:50.208 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2022-08-12 17:29:50.769 [WARN ] [core.thing.internal.ThingManagerImpl] - Initializing handler for thing 'mqtt:broker:MQTTBroker' takes more than 5000ms.
2022-08-12 17:29:52.671 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-08-12 17:29:53.425 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to 'localhost' with clientid da0ae727-7568-4d8a-bc5f-c7971f91f4cd
2022-08-12 17:30:10.649 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2022-08-12 17:30:16.391 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2022-08-12 17:30:16.400 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2022-08-12 17:30:20.994 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 10: Not initialized (ie node unknown), ignoring message.
2022-08-12 17:37:17.145 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Stopped HABPanel
2022-08-12 17:37:17.535 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
2022-08-12 17:37:28.259 [INFO ] [org.openhab.ui.internal.UIService   ] - Stopped UI
2022-08-12 17:37:38.143 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.153 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.403 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.418 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.443 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.525 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.554 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.596 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.627 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.653 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.662 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.706 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.703 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.728 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.758 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.761 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
2022-08-12 17:37:38.803 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/things's Observer 
2022-08-12 17:37:38.825 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.1.6:8080/rest/inbox's Observer 
==> /var/log/openhab/events.log <==
2022-08-12 17:29:34.780 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AC_Electricmeterwatts' changed from NULL to 0.5
2022-08-12 17:29:34.916 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Office_1_Sensorpower' changed from NULL to 106.1

rlkoshak · August 12, 2022, 4:54pm

I think, for all intents and purposes, your OH installation is corrupted. You’ve got one inexplicable problem or error after another which points to something serious and fundamentally wrong with the whole machine. You can spend the hours and hours playing wack-a-mole trying to solve them each one by one, or take a backup of your configs and start with a fresh install and restoration of your backup. In the end that approach is more likely to succeed and will take far less time in the long run.

Just to be safe, I’d use a fresh SD card too in case there’s something fishy with that.

TomaxMe · August 12, 2022, 5:03pm

@rlkoshak , in order to backup which files do I have to copy from my Raspbbery Pi ? It’s enough: conf - directory and userdata-directory ?

rlkoshak · August 12, 2022, 5:05pm

Use openhab-cli backup. If you are using anything outside of openHAB like InfluxDB or NodeRed or the like, you’ll need to look at the docs for those service for how to backup that data.

TomaxMe · August 12, 2022, 5:08pm

Yeah, forgot about openhab-cli backup. Just made it. Thanks a lot for your time!

JustinG · August 13, 2022, 6:00pm

For what it’s worth: I just ran into this exact same error when attempting to update my OH docker container 3.3 → 3.4M1. The system was completely unstable and would not boot past this error followed by three errors about the jsscripting add-on.

The damage that was done to the system was pervasive enough that I couldn’t even roll-back to my stable 3.3 container. After a couple of other failed attempts, I deleted the contents of the \bundles and \kar directories in \userdata\marketplace and the 3.3 container booted up again without issue and the update to 3.4M1 succeeded immediately thereafter.

I had 3 marketplace add-ons: Samsung TV Beta, Mimic TTS, and Solarcast PV. I don’t know if the error was related to one of these in particular or the add-on bindings in general. I have been able to reinstall the Mimic and Solarcast bindings in 3.4M1 without any issue (I haven’t tried the Samsung because I’ve gone back to the core, non-beta version, for now).

Edit: issue filed - Boot failure after cache clearing related to marketplace bundles · Issue #3063 · openhab/openhab-core · GitHub

TomaxMe · August 14, 2022, 9:36am

@JustinG : thanks!

system · September 25, 2022, 1:36am

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.