Upgrading to openhab3 not working on raspberry pi

Setup, Configuration and Use Migration
1

Hi all,

I need help, I upgraded from openhab2 to openhab3 on my raspberry pi running openhabian. Then it failed to start. So after some googling i figured out that i need java11. Ok installed that using the config tool. Installed fine (java -version shows it is good). But still it is not working.
Seems to be in a restart loop or something (see below).

Maybe it is a permission issue? The “fix permissions” option also fails in the config tool.

Any idea how to fix?

Thank you,

Ramon

openhabian@openHABianPi:~ $ sudo /bin/systemctl status openhab.service
● openhab.service - openHAB instance, reachable at http://openHABianPi:8080
   Loaded: loaded (/usr/lib/systemd/system/openhab.service; enabled; vendor preset: enabled)
  Drop-In: /etc/systemd/system/openhab.service.d
           └─override.conf
   Active: active (running) since Fri 2021-03-26 21:46:33 CET; 13s ago
     Docs: https://www.openhab.org/docs/
           https://community.openhab.org
  Process: 17484 ExecStop=/usr/share/openhab/runtime/bin/karaf stop (code=exited, status=1/FAILURE)
 Main PID: 17602 (karaf)
    Tasks: 31 (limit: 4915)
   CGroup: /system.slice/openhab.service
           ├─17602 /bin/sh /usr/share/openhab/runtime/bin/karaf debug
           └─17700 /usr/bin/java -agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=*:5005 -Dopenhab.home=/usr/share/openhab -Dopenhab.conf=/etc/openhab -Dopenhab.runtime=/usr/sh

Mar 26 21:46:42 openHABianPi karaf[17602]:    ___   ___   ___   ___  | | | |   / \   | __ )
Mar 26 21:46:42 openHABianPi karaf[17602]:   / _ \ / _ \ / _ \ / _ \ | |_| |  / _ \  |  _ \
Mar 26 21:46:42 openHABianPi karaf[17602]:  | (_) | (_) |  __/| | | ||  _  | / ___ \ | |_) )
Mar 26 21:46:42 openHABianPi karaf[17602]:   \___/|  __/ \___/|_| |_||_| |_|/_/   \_\|____/
Mar 26 21:46:42 openHABianPi karaf[17602]:        |_|       3.0.1 - Release Build
Mar 26 21:46:42 openHABianPi karaf[17602]: [1B blob data]
Mar 26 21:46:42 openHABianPi karaf[17602]: Use '<tab>' for a list of available commands
Mar 26 21:46:42 openHABianPi karaf[17602]: and '[cmd] --help' for help on a specific command.
Mar 26 21:46:42 openHABianPi karaf[17602]: To exit, use '<ctrl-d>' or 'logout'.
Mar 26 21:46:44 openHABianPi karaf[17602]: openhab>

openhabian@openHABianPi:~ $ sudo /bin/systemctl status openhab.service
● openhab.service - openHAB instance, reachable at http://openHABianPi:8080
   Loaded: loaded (/usr/lib/systemd/system/openhab.service; enabled; vendor preset: enabled)
  Drop-In: /etc/systemd/system/openhab.service.d
           └─override.conf
   Active: active (running) since Fri 2021-03-26 21:48:53 CET; 1s ago
     Docs: https://www.openhab.org/docs/
           https://community.openhab.org
  Process: 18931 ExecStop=/usr/share/openhab/runtime/bin/karaf stop (code=exited, status=1/FAILURE)
 Main PID: 19050 (karaf)
    Tasks: 3 (limit: 4915)
   CGroup: /system.slice/openhab.service
           ├─19050 /bin/sh /usr/share/openhab/runtime/bin/karaf debug
           └─19148 /usr/bin/java -agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=*:5005 -Dopenhab.home=/usr/share/openhab -Dopenhab.conf=/etc/openhab -Dopenhab.runtime=/usr/sh

Mar 26 21:48:53 openHABianPi systemd[1]: Started openHAB instance, reachable at http://openHABianPi:8080.


openhabian@openHABianPi:~ $ sudo /bin/systemctl status openhab.service
● openhab.service - openHAB instance, reachable at http://openHABianPi:8080
   Loaded: loaded (/usr/lib/systemd/system/openhab.service; enabled; vendor preset: enabled)
  Drop-In: /etc/systemd/system/openhab.service.d
           └─override.conf
   Active: activating (auto-restart) (Result: exit-code) since Fri 2021-03-26 21:50:13 CET; 4s ago
     Docs: https://www.openhab.org/docs/
           https://community.openhab.org
  Process: 19883 ExecStop=/usr/share/openhab/runtime/bin/karaf stop (code=exited, status=1/FAILURE)
  Process: 19669 ExecStart=/usr/share/openhab/runtime/bin/karaf ${OPENHAB_STARTMODE} (code=exited, status=0/SUCCESS)
 Main PID: 19669 (code=exited, status=0/SUCCESS)

Mar 26 21:50:13 openHABianPi systemd[1]: openhab.service: Control process exited, code=exited status=1
Mar 26 21:50:13 openHABianPi systemd[1]: openhab.service: Unit entered failed state.
Mar 26 21:50:13 openHABianPi systemd[1]: openhab.service: Failed with result 'exit-code'.


openhabian@openHABianPi:~ $ tail -f /var/log/openhab/openhab.log
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:1004) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:425) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:413) ~[bundleFile:?]
        at org.apache.karaf.features.internal.osgi.Activator.doStart(Activator.java:180) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.run(BaseActivator.java:312) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        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:834) [?:?]
2
  • did you follow the prerequisites and installed a java ‘flavour’ that is listed there ?
  • what does java -version show ?
  • tail of /var/log/openhab/openhab.log does not show enough information what is shown in the first lines of the error block that you listed ?
  • what is shown by sudo journalctl -u openhab ?
  • you stated that running “fix permissions” ends with an error message. what is the exact behvior / message ?
3

Hi Wolfgang,

Thank you for taking the time to help me!

  • did you follow the prerequisites and installed a java ‘flavour’ that is listed there ?

yes i did install a java via sudo openhabian-config

  • what does java -version show ?
openhabian@openHABianPi:~ $ java -version
openjdk version "11.0.10" 2021-01-19 LTS
OpenJDK Runtime Environment Zulu11.45+27-CA (build 11.0.10+9-LTS)
OpenJDK Client VM Zulu11.45+27-CA (build 11.0.10+9-LTS, mixed mode)
  • tail of /var/log/openhab/openhab.log does not show enough information what is shown in the first lines of the error block that you listed ?

This is the entire log:

openhabian@openHABianPi:~ $ cat /var/log/openhab/openhab.log
2021-03-26 22:44:42.422 [WARN ] [org.jline                           ] - Unable to create a system terminal, creating a dumb terminal (enable debug logging for more information)
2021-03-26 22:44:46.285 [WARN ] [raf.features.internal.osgi.Activator] - Error starting activator
java.lang.IllegalStateException: BundleContext is no longer valid
        at org.eclipse.osgi.internal.framework.BundleContextImpl.checkValid(BundleContextImpl.java:989) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:468) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:487) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:1004) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:425) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:413) ~[bundleFile:?]
        at org.apache.karaf.features.internal.osgi.Activator.doStart(Activator.java:180) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.run(BaseActivator.java:312) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        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:834) [?:?]
  • what is shown by sudo journalctl -u openhab ?

A lot of repeating the same thing, this is part of the log:

Mar 26 20:59:40 openHABianPi karaf[14300]: Listening for transport dt_socket at address: 5005
Mar 26 20:59:51 openHABianPi karaf[14300]: [1B blob data]
Mar 26 20:59:51 openHABianPi karaf[14300]:                            _   _     _     ____
Mar 26 20:59:51 openHABianPi karaf[14300]:    ___   ___   ___   ___  | | | |   / \   | __ )
Mar 26 20:59:51 openHABianPi karaf[14300]:   / _ \ / _ \ / _ \ / _ \ | |_| |  / _ \  |  _ \
Mar 26 20:59:51 openHABianPi karaf[14300]:  | (_) | (_) |  __/| | | ||  _  | / ___ \ | |_) )
Mar 26 20:59:51 openHABianPi karaf[14300]:   \___/|  __/ \___/|_| |_||_| |_|/_/   \_\|____/
Mar 26 20:59:51 openHABianPi karaf[14300]:        |_|       3.0.1 - Release Build
Mar 26 20:59:51 openHABianPi karaf[14300]: [1B blob data]
Mar 26 20:59:51 openHABianPi karaf[14300]: Use '<tab>' for a list of available commands
Mar 26 20:59:51 openHABianPi karaf[14300]: and '[cmd] --help' for help on a specific command.
Mar 26 20:59:51 openHABianPi karaf[14300]: To exit, use '<ctrl-d>' or 'logout'.
Mar 26 20:59:55 openHABianPi karaf[14300]: openhab>
Mar 26 21:00:07 openHABianPi karaf[14636]: Can't connect to the container. The container is not running.
Mar 26 21:00:07 openHABianPi systemd[1]: openhab.service: Control process exited, code=exited status=1
Mar 26 21:00:07 openHABianPi systemd[1]: openhab.service: Unit entered failed state.
Mar 26 21:00:07 openHABianPi systemd[1]: openhab.service: Failed with result 'exit-code'.
Mar 26 21:00:12 openHABianPi systemd[1]: openhab.service: Service hold-off time over, scheduling restart.
Mar 26 21:00:12 openHABianPi systemd[1]: Stopped openHAB instance, reachable at http://openHABianPi:8080.
Mar 26 21:00:12 openHABianPi systemd[1]: Started openHAB instance, reachable at http://openHABianPi:8080.
Mar 26 21:00:14 openHABianPi karaf[14745]: Listening for transport dt_socket at address: 5005
Mar 26 21:00:17 openHABianPi systemd[1]: Stopping openHAB instance, reachable at http://openHABianPi:8080...
Mar 26 21:00:20 openHABianPi karaf[14866]: Can't connect to the container. The container is not running.
Mar 26 21:00:20 openHABianPi systemd[1]: openhab.service: Control process exited, code=exited status=1
Mar 26 21:00:36 openHABianPi systemd[1]: Stopped openHAB instance, reachable at http://openHABianPi:8080.
Mar 26 21:00:36 openHABianPi systemd[1]: openhab.service: Unit entered failed state.
Mar 26 21:00:36 openHABianPi systemd[1]: openhab.service: Failed with result 'exit-code'.
Mar 26 21:03:50 openHABianPi systemd[1]: Started openHAB instance, reachable at http://openHABianPi:8080.
Mar 26 21:03:52 openHABianPi karaf[15684]: Listening for transport dt_socket at address: 5005
Mar 26 21:03:53 openHABianPi karaf[15684]: java.lang.RuntimeException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:102)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlersInternal(BootstrapLogManager.java:137)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlers(BootstrapLogManager.java:70)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.configureLogger(BootstrapLogManager.java:75)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.launch(Main.java:244)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.main(Main.java:178)
Mar 26 21:03:53 openHABianPi karaf[15684]: Caused by: java.io.FileNotFoundException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open0(Native Method)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.open(BootstrapLogManager.java:193)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.<init>(BootstrapLogManager.java:182)
Mar 26 21:03:53 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:100)
Mar 26 21:03:53 openHABianPi karaf[15684]:         ... 5 more
Mar 26 21:03:56 openHABianPi karaf[15684]: java.lang.RuntimeException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:102)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlersInternal(BootstrapLogManager.java:137)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlers(BootstrapLogManager.java:70)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.configureLogger(BootstrapLogManager.java:75)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.KarafActivatorManager.<init>(KarafActivatorManager.java:48)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.launch(Main.java:280)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.main(Main.java:178)
Mar 26 21:03:56 openHABianPi karaf[15684]: Caused by: java.io.FileNotFoundException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open0(Native Method)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.open(BootstrapLogManager.java:193)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.<init>(BootstrapLogManager.java:182)
Mar 26 21:03:56 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:100)
Mar 26 21:03:56 openHABianPi karaf[15684]:         ... 6 more
Mar 26 21:03:57 openHABianPi karaf[15684]: java.lang.RuntimeException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:102)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlersInternal(BootstrapLogManager.java:137)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlers(BootstrapLogManager.java:70)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.configureLogger(BootstrapLogManager.java:75)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.lock.SimpleFileLock.<init>(SimpleFileLock.java:40)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:490)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.createLock(Main.java:516)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.doMonitor(Main.java:388)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main.access$100(Main.java:74)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.Main$3.run(Main.java:377)
Mar 26 21:03:57 openHABianPi karaf[15684]: Caused by: java.io.FileNotFoundException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open0(Native Method)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.open(BootstrapLogManager.java:193)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.<init>(BootstrapLogManager.java:182)
Mar 26 21:03:57 openHABianPi karaf[15684]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal(BootstrapLogManager.java:100)
Mar 26 21:03:57 openHABianPi karaf[15684]:         ... 12 more
Mar 26 21:04:00 openHABianPi karaf[15684]: org.ops4j.pax.logging.pax-logging-api [log4j2] ERROR : RollingFileManager (/var/log/openhab/openhab.log) java.io.FileNotFoundException: /var/log/o
Mar 26 21:04:00 openHABianPi karaf[15684]: java.io.FileNotFoundException: /var/log/openhab/openhab.log (Permission denied)
Mar 26 21:04:00 openHABianPi karaf[15684]:         at java.base/java.io.FileOutputStream.open0(Native Method)

you stated that running “fix permissions” ends with an error message. what is the exact behvior / message ?

Its option 14 but the actual error message says the 10

image
image1227×329 11.4 KB

image
image852×324 5.81 KB

Thank you,

Ramon

4

it looks like the permission of directory /var/log/openhab is not correct.
What is the permission of that directory and the file in case the file is available:

ls -ld /var/log/openhab /var/log/openhab/openhab.log

both need to be owned by openhab:openhab ( which means user:group )

5

it does look a bit odd especially the readme.txt why does that have “x”?!? Also the directory apparantly is not owned correctly. I changed that now but it does

openhabian@openHABianPi:~ $ ls -ld /var/log/openhab /var/log/openhab/openhab.log
drwxrwxr-x 2 openhab openhabian 4096 Mar 27 09:13 /var/log/openhab
-rw-r--r-- 1 openhab openhab    1646 Mar 27 09:14 /var/log/openhab/openhab.log

openhabian@openHABianPi:~ $ ls -l /var/log/openhab/
total 32
-rw-rw-rw- 1 openhab openhab    0 Mar 25 18:06 audit.log
-rw-rw-rw- 1 openhab openhab    0 Mar 25 18:06 events.log
-rw-r--r-- 1 openhab openhab  178 Mar 27 09:15 openhab.log
-rw-r--r-- 1 openhab openhab 1626 Mar 27 09:12 openhab.log.1
-rw-r--r-- 1 openhab openhab 1646 Mar 27 09:13 openhab.log.2
-rw-r--r-- 1 openhab openhab 1646 Mar 27 09:13 openhab.log.3
-rw-r--r-- 1 openhab openhab 1646 Mar 27 09:14 openhab.log.4
-rw-r--r-- 1 openhab openhab 1626 Mar 27 09:14 openhab.log.5
-rw-r--r-- 1 openhab openhab 1646 Mar 27 09:14 openhab.log.6
-rw-r--r-- 1 openhab openhab 1646 Mar 27 09:15 openhab.log.7
-rwxrwxr-x 1 openhab openhab    0 Jan 27 21:42 Readme.txt

Im guessing its related to the “BundleContext is no longer valid”

openhabian@openHABianPi:~ $ cat /var/log/openhab/openhab.log
2021-03-27 09:27:05.134 [WARN ] [org.jline                           ] - Unable to create a system terminal, creating a dumb terminal (enable debug logging for more information)
2021-03-27 09:27:09.053 [WARN ] [raf.features.internal.osgi.Activator] - Error starting activator
java.lang.IllegalStateException: BundleContext is no longer valid
        at org.eclipse.osgi.internal.framework.BundleContextImpl.checkValid(BundleContextImpl.java:989) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:468) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:487) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:1004) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:425) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:413) ~[bundleFile:?]
        at org.apache.karaf.features.internal.osgi.Activator.doStart(Activator.java:180) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.run(BaseActivator.java:312) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        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:834) [?:?]

thx for the help!

6

now i get these type of errors in /var/log/openhab/openhab.log

2021-03-27 12:21:46.580 [SEVERE] [org.apache.karaf.main.Main] - Could not launch framework
java.lang.RuntimeException: Error initializing storage.
        at org.eclipse.osgi.internal.framework.EquinoxContainer.<init>(EquinoxContainer.java:70)
        at org.eclipse.osgi.launch.Equinox.<init>(Equinox.java:31)
        at org.eclipse.osgi.launch.EquinoxFactory.newFramework(EquinoxFactory.java:24)
        at org.apache.karaf.main.Main.launch(Main.java:256)
        at org.apache.karaf.main.Main.main(Main.java:178)
Caused by: java.io.IOException: Unable to create lock manager.
        at org.eclipse.osgi.storagemanager.StorageManager.open(StorageManager.java:698)
        at org.eclipse.osgi.storage.Storage.getChildStorageManager(Storage.java:1776)
        at org.eclipse.osgi.storage.Storage.getInfoInputStream(Storage.java:1793)
        at org.eclipse.osgi.storage.Storage.<init>(Storage.java:132)
        at org.eclipse.osgi.storage.Storage.createStorage(Storage.java:85)
        at org.eclipse.osgi.internal.framework.EquinoxContainer.<init>(EquinoxContainer.java:68)
        ... 4 more

2021-03-27 12:21:58.146 [SEVERE] [org.apache.karaf.main.Main] - Could not launch framework
java.lang.RuntimeException: Error initializing storage.
        at org.eclipse.osgi.internal.framework.EquinoxContainer.<init>(EquinoxContainer.java:70)
        at org.eclipse.osgi.launch.Equinox.<init>(Equinox.java:31)
        at org.eclipse.osgi.launch.EquinoxFactory.newFramework(EquinoxFactory.java:24)
        at org.apache.karaf.main.Main.launch(Main.java:256)
        at org.apache.karaf.main.Main.main(Main.java:178)
Caused by: java.io.IOException: Unable to create lock manager.
        at org.eclipse.osgi.storagemanager.StorageManager.open(StorageManager.java:698)
        at org.eclipse.osgi.storage.Storage.getChildStorageManager(Storage.java:1776)
        at org.eclipse.osgi.storage.Storage.getInfoInputStream(Storage.java:1793)
        at org.eclipse.osgi.storage.Storage.<init>(Storage.java:132)
        at org.eclipse.osgi.storage.Storage.createStorage(Storage.java:85)
        at org.eclipse.osgi.internal.framework.EquinoxContainer.<init>(EquinoxContainer.java:68)
        ... 4 more
7

there seem to be other files/directories that are not owned by openhab and now prevent the startup from creating these files.
Please execute

sudo openhab-cli  reset-ownership

and check if this solves the problem.

8

Thank you for the suggestion but that did not seem to help. Still the same problem.

How do i remove the whole thing and reinstall? Maybe that helps

openhabian@openHABianPi:/ $ cat /var/log/openhab/openhab.log
2021-03-27 15:00:10.326 [WARN ] [org.jline                           ] - Unable to create a system terminal, creating a dumb terminal (enable debug logging for more information)
2021-03-27 15:00:13.818 [WARN ] [raf.features.internal.osgi.Activator] - Error starting activator
java.lang.IllegalStateException: BundleContext is no longer valid
        at org.eclipse.osgi.internal.framework.BundleContextImpl.checkValid(BundleContextImpl.java:989) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:468) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:487) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:1004) ~[org.eclipse.osgi-3.12.100.jar:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:425) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.register(BaseActivator.java:413) ~[bundleFile:?]
        at org.apache.karaf.features.internal.osgi.Activator.doStart(Activator.java:180) ~[bundleFile:?]
        at org.apache.karaf.util.tracker.BaseActivator.run(BaseActivator.java:312) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        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) [?:?]
9

OK I uninstalled, then reinstalled, now it seems to work.

Not sure what the issue now was. But regardless its fixed

I appreciate the help!

1 Like