Moving to OH3.3. effectively breaks HABApp because there have been some late changes which I have not caught in time.
Please wait with the openHAB update until the release of the next official HABapp version.
I’ll make an announcement as usual.
If you want to help you can always participate in the beta test.
Phase 1:
I upgraded from 3.2 to 3.3 and after the upgrade nothing worked anymore, was not possible to open the UI, rules did not work, it was running but somehow not.
I tried all fixes of openhabian, but no change.
I thought I let it run as it may need to upgrade files in the background, but even after 2h nothing worked.
I decided to restart the whole server.
Phase 2:
OpenHAB UI started, still nothing was working, all Things were grayed out.
Log was full of errors regarding MAP() values und rrd4j persistance.
I let it run for some hours and restarted the server.
Phase 3 (now):
Things were still greyed out. I decided to uninstall and reinstall the telegram binding. Suddenly all Things went green, UI was responding.
Still log is now full of JDBC errors
UPDATE:
I am not sure what caused all of this but my persistence configurations were all not working anymore after the upgrade. Had to start from Scratch with a mysql persistence…
2022-07-07 22:35:51.774 [WARN ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: failed to open connection: Failed to initialize pool: Communications link failure
The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.
2022-07-07 22:35:51.774 [WARN ] [jdbc.internal.JdbcPersistenceService] - JDBC::store: No connection to database. Cannot persist state '233.963 V' for item 'Outlet19_Voltage (Type=NumberItem, State=233.963 V, Label=Outlet19 Voltage, Category=outlet, Groups=[Outlets])'! Will retry connecting to database when error count:0 equals errReconnectThreshold:0
2022-07-07 22:35:51.778 [INFO ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: Driver is available::Yank setupDataSource
2022-07-07 22:35:51.779 [WARN ] [.zaxxer.hikari.util.DriverDataSource] - Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation.
2022-07-07 22:35:54.845 [WARN ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: failed to open connection: Failed to initialize pool: Communications link failure
The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.
2022-07-07 22:35:54.846 [INFO ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: Driver is available::Yank setupDataSource
2022-07-07 22:35:54.846 [WARN ] [.zaxxer.hikari.util.DriverDataSource] - Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation.
2022-07-07 22:35:57.918 [WARN ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: failed to open connection: Failed to initialize pool: Communications link failure
The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.
2022-07-07 22:35:57.918 [WARN ] [jdbc.internal.JdbcPersistenceService] - JDBC::store: No connection to database. Cannot persist state '0.064 A' for item 'Outlet20_Current (Type=NumberItem, State=0.064 A, Label=Outlet20 Current, Category=outlet, Groups=[Outlets])'! Will retry connecting to database when error count:0 equals errReconnectThreshold:0
2022-07-07 22:35:57.923 [INFO ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: Driver is available::Yank setupDataSource
2022-07-07 22:35:57.923 [WARN ] [.zaxxer.hikari.util.DriverDataSource] - Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation.
2022-07-07 22:36:00.087 [INFO ] [org.openhab.core.model.script.FILE ] - Run Rule: periodic_timer_scenes.rules - periodic every 1 MINUTE
2022-07-07 22:36:00.990 [WARN ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: failed to open connection: Failed to initialize pool: Communications link failure
The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.
2022-07-07 22:36:00.990 [INFO ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: Driver is available::Yank setupDataSource
2022-07-07 22:36:00.990 [WARN ] [.zaxxer.hikari.util.DriverDataSource] - Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation.
2022-07-07 22:36:04.062 [WARN ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: failed to open connection: Failed to initialize pool: Communications link failure
The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.
2022-07-07 22:36:04.062 [WARN ] [jdbc.internal.JdbcPersistenceService] - JDBC::store: No connection to database. Cannot persist state '233.287072 V' for item 'Outlet16_Voltage (Type=NumberItem, State=233.287072 V, Label=Outlet16 Voltage, Category=outlet, Groups=[Outlets])'! Will retry connecting to database when error count:0 equals errReconnectThreshold:0
2022-07-07 22:36:04.066 [INFO ] [persistence.jdbc.internal.JdbcMapper] - JDBC::openConnection: Driver is available::Yank setupDataSource
2022-07-07 22:36:04.067 [WARN ] [.zaxxer.hikari.util.DriverDataSource] - Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation.
I updated from 3.2.0 to 3.3.0 (docker image) and cant’ get it to work anymore.
All things show up as UNINITIALIZED. Looking at the log shows constanly added exceptions of this kind:
Error downloading mvn:org.eclipse.jetty.websocket/websocket-common/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty.websocket/websocket-client/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty/jetty-util/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty/jetty-proxy/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty/jetty-client/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty.websocket/websocket-api/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty/jetty-io/9.4.43.v20210629
Error downloading mvn:org.eclipse.jetty/jetty-http/9.4.43.v20210629
at org.apache.karaf.features.internal.download.impl.MavenDownloadManager$MavenDownloader.<init>(MavenDownloadManager.java:91)
at org.apache.karaf.features.internal.download.impl.MavenDownloadManager.createDownloader(MavenDownloadManager.java:72)
at org.apache.karaf.features.internal.region.Subsystem.downloadBundles(Subsystem.java:457)
at org.apache.karaf.features.internal.region.Subsystem.downloadBundles(Subsystem.java:452)
at org.apache.karaf.features.internal.region.SubsystemResolver.resolve(SubsystemResolver.java:224)
at org.apache.karaf.features.internal.service.Deployer.deploy(Deployer.java:399)
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.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
Suppressed: java.io.IOException: Error downloading mvn:org.eclipse.jetty.websocket/websocket-common/9.4.43.v20210629
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:77)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
... 3 more
Caused by: java.io.IOException: Error resolving artifact org.eclipse.jetty.websocket:websocket-client:jar:9.4.43.v20210629: [Could not find artifact org.eclipse.jetty.websocket:websocket-client:jar:9.4.43.v20210629 in openhab (https://openhab.jfrog.io/openhab/libs-release/)]
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.apache.karaf.features.internal.download.impl.MavenDownloadTask.download(MavenDownloadTask.java:52)
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:60)
... 6 more
Suppressed: shaded.org.eclipse.aether.transfer.ArtifactNotFoundException: Could not find artifact org.eclipse.jetty.websocket:websocket-client:jar:9.4.43.v20210629 in openhab (https://openhab.jfrog.io/openhab/libs-release/)
at shaded.org.eclipse.aether.connector.basic.ArtifactTransportListener.transferFailed(ArtifactTransportListener.java:48)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$TaskRunner.run(BasicRepositoryConnector.java:368)
at shaded.org.eclipse.aether.util.concurrency.RunnableErrorForwarder$1.run(RunnableErrorForwarder.java:75)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$DirectExecutor.execute(BasicRepositoryConnector.java:642)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector.get(BasicRepositoryConnector.java:262)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.performDownloads(DefaultArtifactResolver.java:489)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:390)
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)
... 11 more
Caused by: shaded.org.eclipse.aether.resolution.ArtifactResolutionException: Error resolving artifact org.eclipse.jetty.websocket:websocket-client:jar:9.4.43.v20210629
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)
Later continuously:
Suppressed: java.io.IOException: Error downloading mvn:com.fasterxml.jackson.dataformat/jackson-dataformat-cbor/2.12.5
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:77)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
... 3 more
Caused by: java.io.IOException: Error resolving artifact com.fasterxml.jackson.dataformat:jackson-dataformat-cbor:jar:2.12.5: [Could not find artifact com.fasterxml.jackson.dataformat:jackson-dataformat-cbor:jar:2.12.5 in openhab (https://openhab.jfrog.io/openhab/libs-release/)]
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.apache.karaf.features.internal.download.impl.MavenDownloadTask.download(MavenDownloadTask.java:52)
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:60)
... 6 more
Suppressed: shaded.org.eclipse.aether.transfer.ArtifactNotFoundException: Could not find artifact com.fasterxml.jackson.dataformat:jackson-dataformat-cbor:jar:2.12.5 in openhab (https://openhab.jfrog.io/openhab/libs-release/)
at shaded.org.eclipse.aether.connector.basic.ArtifactTransportListener.transferFailed(ArtifactTransportListener.java:48)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$TaskRunner.run(BasicRepositoryConnector.java:368)
at shaded.org.eclipse.aether.util.concurrency.RunnableErrorForwarder$1.run(RunnableErrorForwarder.java:75)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$DirectExecutor.execute(BasicRepositoryConnector.java:642)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector.get(BasicRepositoryConnector.java:262)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.performDownloads(DefaultArtifactResolver.java:489)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:390)
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)
... 11 more
Caused by: shaded.org.eclipse.aether.resolution.ArtifactResolutionException: Error resolving artifact com.fasterxml.jackson.dataformat:jackson-dataformat-cbor:jar:2.12.5
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)
... 11 more
Suppressed: java.io.IOException: Error downloading mvn:com.fasterxml.jackson.dataformat/jackson-dataformat-yaml/2.12.5
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:77)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
... 3 more
Caused by: java.io.IOException: Error resolving artifact com.fasterxml.jackson.dataformat:jackson-dataformat-yaml:jar:2.12.5: [Could not find artifact com.fasterxml.jackson.dataformat:jackson-dataformat-yaml:jar:2.12.5 in openhab (https://openhab.jfrog.io/openhab/libs-release/)]
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.apache.karaf.features.internal.download.impl.MavenDownloadTask.download(MavenDownloadTask.java:52)
at org.apache.karaf.features.internal.download.impl.AbstractRetryableDownloadTask.run(AbstractRetryableDownloadTask.java:60)
... 6 more
Suppressed: shaded.org.eclipse.aether.transfer.ArtifactNotFoundException: Could not find artifact com.fasterxml.jackson.dataformat:jackson-dataformat-yaml:jar:2.12.5 in openhab (https://openhab.jfrog.io/openhab/libs-release/)
at shaded.org.eclipse.aether.connector.basic.ArtifactTransportListener.transferFailed(ArtifactTransportListener.java:48)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$TaskRunner.run(BasicRepositoryConnector.java:368)
at shaded.org.eclipse.aether.util.concurrency.RunnableErrorForwarder$1.run(RunnableErrorForwarder.java:75)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector$DirectExecutor.execute(BasicRepositoryConnector.java:642)
at shaded.org.eclipse.aether.connector.basic.BasicRepositoryConnector.get(BasicRepositoryConnector.java:262)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.performDownloads(DefaultArtifactResolver.java:489)
at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:390)
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)
... 11 more
Caused by: shaded.org.eclipse.aether.resolution.ArtifactResolutionException: Error resolving artifact com.fasterxml.jackson.dataformat:jackson-dataformat-yaml:jar:2.12.5
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)
... 11 more
What could possibly be the reason for this and how do I fix it? I couldn’t find any similar topics yet.
Edit: I have reverted to a backup, which is working fine. Have tried the update again, same failed result. If no one has a solution for this, I will continue using 3.2.0 for now, hoping this is something that will be fixed for the next release. If there is something I should or could try to help fixing this, please let me know.
Jython version: 2.7.2.final
Operating system: Linux
OS Version: 4.19.0-21-amd64
Java vendor: Azul Systems, Inc.
Java VM name: OpenJDK 64-Bit Server VM
Java runtime name: OpenJDK Runtime Environment
Java runtime version: 11.0.15+10-LTS
Unexpected exception from an event executor: java.lang.OutOfMemoryError: Java heap space
Not sure what is happening and how to find out the reason, I thought it was running smoothly but now java is running close to 200% cpu (2 cpus for the vm) using 60+% memory (2GB available) and actions like a button click take many minutes to get processed.
I have a button in habpanel which makes a googletts voice to tell me the time. It just now told me “it’s 15:28” when it actually was 15:35 already. But I had clicked the button on 15:26, it took ~2 min for the buttonclick to reach the rule which takes the time and formats the message, then 7 min for that string to reach a different rule which hands it over to the speech engine and finally says it.
I restarted openhab.service twice already, rebooted the vm.
Of course I’m not sure that this effect is specific to 3.3.0-1, it’s just what I have running.
What could I do to get to the culprit that eats up java processing and heap? Is it advisable to downgrade oh to contrast the situation with a prior build?
Update:
oh well, sudo openhab-cli clean-cache after stopping oh may have done the needed relief.
I’ve been struggling with the update from 3.2 to 3.3. The NetAtmo changes so far have been the easiest to fix. The remaining issue(s) I’m stuck on are:
Persistence (RRD4J) appears to be saving values, however the restoring values at start is not working. In the log I see the loading of the persistence file, but not the line for when persistence logs that it is ready to work
Rules are not running. The log shows the rules being loaded, however they are never run.
I’ve increased the general logging to INFO and I do not see any failures, so do not know how to track down the failure(s). I acknowledge the issues might be related if something is blocking the initialization. Any guidance/help is greatly appreciated.
Maybe I have an idea @smf007, I had the same behavior regarding the rules when my OH3 ran on Java 17 instead of Java 11. Refer to this post for details on how to fix it.
Hello Friends,
i upgraded to 3.3 and my mobile app was empty. No problem so farbecaue it was setup ugly. But the Tabs Location, Equipment and Property was also empty. I tried different thing and didn’t get a step forward.
Now i found out the Semantic Model didn’t work. All my locations which worked in 3.2 without any problem are now “non-semantic”.
All configurations are done by config-file.
Do you have a fix for this?
Additional Info: When i setup a location via MainUI, it disappears instantly after “Save”, but it’s in the Items-List
After leaving it a couple days, I started looking at the runlevels of the bundles in case it was all related to some race condition. Restarting the persistence bundle immediately resulted in the saved “onstart” values being loaded. I then tried restarting the various rules bundles in different orders to no luck… then magically 9 hours later the log finally showed the rules engine having started and all of my rules started working. There was nothing in the Log during those 9 hrs, so no indication about what was going on.
I upgraded from 3.2 to 3.3 (on Raspi / Docker) some weeks back, and after every (!) reboot of the entire Raspi, openHAB did not load with the following log entries:
Launching the openHAB runtime...
**java.io.FileNotFoundException: /openhab/userdata/tmp/karaf.pid (Permission denied)**
at java.base/java.io.FileOutputStream.open0(Native Method)
at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:126)
at org.apache.karaf.main.InstanceHelper.writePid(InstanceHelper.java:126)
at org.apache.karaf.main.Main.launch(Main.java:278)
at org.apache.karaf.main.Main.main(Main.java:183)
!SESSION 2022-08-02 21:00:53.456 -----------------------------------------------
eclipse.buildId=unknown
java.version=11.0.15
java.vendor=Eclipse Adoptium
BootLoader constants: OS=linux, ARCH=arm, WS=gtk, NL=en_US
!ENTRY org.eclipse.osgi 4 0 2022-08-02 21:00:53.731
!MESSAGE Error saving on update
!STACK 0
**java.io.IOException: Permission denied**
at java.base/java.io.UnixFileSystem.createFileExclusively(Native Method)
at java.base/java.io.File.createTempFile(File.java:2129)
at org.eclipse.osgi.storagemanager.StorageManager.createTempFile(StorageManager.java:744)
at org.eclipse.osgi.storagemanager.StorageManager.getOutputStream(StorageManager.java:807)
at org.eclipse.osgi.storage.Storage.save0(Storage.java:1307)
at org.eclipse.osgi.storage.Storage.save(Storage.java:1281)
at org.eclipse.osgi.internal.framework.StorageSaver$StorageSaverTask.run(StorageSaver.java:31)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
Just restarting only the openHAB container always solved the problem, but as a Newbie it almost derailed me.
From what I can see, this was a cache problem, which I resolved via…
sudo su
cd /var/lib/docker/volumes/
rm -r /var/lib/docker/volumes/openhab_data_openhab_userdata/_data/tmp/*
rm -r /var/lib/docker/volumes/openhab_data_openhab_userdata/_data/cache/*
Assuming this is not a usability problem on my side, maybe a future update can just empty the cache to avoid this.
It’s a permission problem. Did you by chance change the UID under which OH is running inside your container?
During an upgrade, the cache is already cleared automatically, but if the permissions are wrong it wouldn’t be able to do so. Look in userdata/logs/update.log to see if there were any errors after “Clearing the cache…”.
Not that I know of. If I remember correctly, I did a fresh install of 3.2 to get rid of another problem with frequent disconnects (unfortunately not successfully ), restored my userdata via duplicati, and then some weeks later did the update.
Looks ok to me:
Replacing userdata system files with newer versions...
Clearing cache...
Performing post-update tasks for version 3.3.0:
Deleting File: /openhab/userdata/config/org/openhab/mqttbroker.config
SUCCESS: openHAB updated from 3.2.0 to 3.3.0
All I can say is if it ever happens again, we’ll need to see the permissions on those files. File permissions all based on user and group and a “Permission Denied” error means the user openHAB is running under does not have permission to write (in this case) to that file nor permission to create files in those folders.
Given the complexity added by containers it’s easy to get this messed up. Beyond that, all I can do is repeat that the upgrade script already does clear the cache.