Openhab.service: Main process exited, code=exited, status=255/EXCEPTION

Hello, after clearing the cache (I turned off openhab and then clearing the cache (sudo openhab-cli clean-cache)). I can’t restart openhab anymore, it tries every 5 seconds to restart and I get this error “openhab.service: Main process exited, code=exited, status=255/EXCEPTION” every time…
Here is the debug I managed to get at +/- 4 seconds after booting :

sep 17 12:31:03 GC-Silent systemd[1]: Started openHAB - empowering the smart home.
sep 17 12:31:05 GC-Silent karaf[1482]: java.io.FileNotFoundException: /var/lib/openhab/tmp/karaf.pid (Permission non accor>
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.open0(Native Method)
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:126)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.InstanceHelper.writePid(InstanceHelper.java:126)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.launch(Main.java:278)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.main(Main.java:183)
sep 17 12:31:05 GC-Silent karaf[1482]: java.lang.RuntimeException: /var/log/openhab/openhab.log (Permission non accordée)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlersInterna>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlers(Bootst>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager.configureLogger(Bootstrap>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.launch(Main.java:279)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.main(Main.java:183)
sep 17 12:31:05 GC-Silent karaf[1482]: Caused by: java.io.FileNotFoundException: /var/log/openhab/openhab.log (Permission >
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.open0(Native Method)
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
sep 17 12:31:05 GC-Silent karaf[1482]:         at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.open(Bo>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager$SimpleFileHandler.<init>(>
sep 17 12:31:05 GC-Silent karaf[1482]:         at org.apache.karaf.main.util.BootstrapLogManager.getDefaultHandlerInternal>
sep 17 12:31:05 GC-Silent karaf[1482]:         ... 5 more
sep 17 12:31:07 GC-Silent karaf[1482]: !SESSION 2022-09-17 12:31:07.348 -----------------------------------------------
sep 17 12:31:07 GC-Silent karaf[1482]: eclipse.buildId=unknown
sep 17 12:31:07 GC-Silent karaf[1482]: java.version=11.0.15
sep 17 12:31:07 GC-Silent karaf[1482]: java.vendor=Azul Systems, Inc.
sep 17 12:31:07 GC-Silent karaf[1482]: BootLoader constants: OS=linux, ARCH=x86_64, WS=gtk, NL=fr_BE
sep 17 12:31:07 GC-Silent karaf[1482]: !ENTRY org.eclipse.osgi 4 0 2022-09-17 12:31:07.382
sep 17 12:31:07 GC-Silent karaf[1482]: !MESSAGE Error reading configuration: /var/lib/openhab/cache/org.eclipse.osgi/.mana>
sep 17 12:31:07 GC-Silent karaf[1482]: !STACK 0
sep 17 12:31:07 GC-Silent karaf[1482]: java.io.FileNotFoundException: /var/lib/openhab/cache/org.eclipse.osgi/.manager/.fi>
sep 17 12:31:07 GC-Silent karaf[1482]:         at java.base/java.io.RandomAccessFile.open0(Native Method)
sep 17 12:31:07 GC-Silent karaf[1482]:         at java.base/java.io.RandomAccessFile.open(RandomAccessFile.java:345)
sep 17 12:31:07 GC-Silent karaf[1482]:         at java.base/java.io.RandomAccessFile.<init>(RandomAccessFile.java:259)
sep 17 12:31:07 GC-Silent karaf[1482]:         at java.base/java.io.RandomAccessFile.<init>(RandomAccessFile.java:214)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.internal.location.Locker_JavaNio.lock(Locker_JavaNio.ja>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storagemanager.StorageManager.lock(StorageManager.java:>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storagemanager.StorageManager.open(StorageManager.java:>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storage.Storage.getChildStorageManager(Storage.java:219>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storage.Storage.getInfoInputStream(Storage.java:2211)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storage.Storage.<init>(Storage.java:256)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.storage.Storage.createStorage(Storage.java:184)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.internal.framework.EquinoxContainer.<init>(EquinoxConta>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.launch.Equinox.<init>(Equinox.java:53)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.launch.EquinoxFactory.newFramework(EquinoxFactory.java:>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.eclipse.osgi.launch.EquinoxFactory.newFramework(EquinoxFactory.java:>
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.launch(Main.java:291)
sep 17 12:31:07 GC-Silent karaf[1482]:         at org.apache.karaf.main.Main.main(Main.java:183)
sep 17 12:31:07 GC-Silent systemd[1]: openhab.service: Main process exited, code=exited, status=255/EXCEPTION
sep 17 12:31:07 GC-Silent systemd[1]: openhab.service: Failed with result 'exit-code'.
sep 17 12:31:07 GC-Silent systemd[1]: openhab.service: Consumed 2.241s CPU time.
sep 17 12:31:12 GC-Silent systemd[1]: openhab.service: Scheduled restart job, restart counter is at 1.
lines 1-54

Does anyone have a similar problem and can help me? I am on linux. Thanks in advance.

Check the permissions of the directories for the listed files as well as of the files themselves in case they exist:

ls -ld /var/log/openhab/ /var/log/openhab/openhab.log /var/lib/openhab/ /var/lib/openhab/tmp/ /var/lib/openhab/tmp/karaf.pid 
drwxrwxr-x 17 openhab openhab   4096 Aug  6 22:20 /var/lib/openhab/
drwxr-xr-x  8 openhab openhab   4096 Sep 11 15:09 /var/lib/openhab/tmp/
-rw-r--r--  1 openhab openhab      3 Aug  6 22:20 /var/lib/openhab/tmp/karaf.pid
drwxr-xr-x  1 openhab openhab   4096 Sep  6 08:30 /var/log/openhab/
-rw-r--r--  1 openhab openhab 503603 Sep 17 15:00 /var/log/openhab/openhab.log
1 Like

I’ll go take a look at that, thanks :upside_down_face:. Do you know what the access denial could have caused? And how can I restore access to the files to OpenHab? Thanks a lot !

I would assume that the file(s)/directory/(ies) is/are owned by root.
They should be owned by openhab. This could happen if a process is started by using root. The normal startup switches over to user openhab.
May be it is a glitch of what happened a few days ago when the jfrog repo was not available - but that is just a guess.

In case you use openhabian use the menu entry that is available to fix permissions.
In case you don’t use openhabian you manually need to fix the permissions using

sudo chown -R openhab:openhab /var/lib/openhab  /var/log/openhab
2 Likes

Thank you for your interest in my problem. Know that the problem is solved thanks to you, a big thank you.

1 Like

hi ,
with the recent update to 3.4.0.M6 i do get error for the shellyem3.
the channels are not updated anymore.
Anybody facing similar issues?

11:28:08.830 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Energiemessung: Create new thing of type shelly:shellyem3 using ShellyRelayHandler
11:28:08.846 [DEBUG] [.shelly.internal.ShellyHandlerFactory] - Thing handler for uid shelly:shellyem3:zaehlerkeller added, total things = 11
11:28:08.847 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellyem3:zaehlerkeller' changed from UNINITIALIZED (DISABLED) to INITIALIZING
11:28:10.850 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Thing name derived from UID shelly:shellyem3:zaehlerkeller
11:28:10.851 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Using userId admin from bindingConfig
11:28:10.851 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Device config: IP address=192.168.1.91, HTTP user/password=admin/***, update interval=60
11:28:10.851 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Configured Events: Button: false, Switch (on/off): false, Push: false, Roller: true, Sensor: true, CoIoT: true, Enable AutoCoIoT: true
11:28:10.851 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Start initializing for thing Energiemessung, type shellyem3, IP address 192.168.1.91, Gen2: false, CoIoT: true
11:28:10.852 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellyem3:zaehlerkeller' changed from INITIALIZING to UNKNOWN (CONFIGURATION_PENDING): Initialisierung oder Gerät im Schlafmodus.
11:28:10.959 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Auto-CoIoT is enabled, disabling action urls
11:28:10.960 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Starting CoIoT (autoCoIoT=true/true)
11:28:10.960 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: Starting CoAP Listener
11:28:10.994 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: Using CoAP device description from successful HTTP /cit/d
11:28:10.994 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Device Description for shellyem3-zaehlerkeller: {"blk":[{"I":1,"D":"relay_0"},{"I":2,"D":"emeter_0"},{"I":3,"D":"emeter_1"},{"I":4,"D":"emeter_2"},{"I":5,"D":"device"}],"sen":[{"I":9103,"T":"EVC","D":"cfgChanged","R":"U16","L":5},{"I":1101,"T":"S","D":"output","R":"0/1","L":1},{"I":4105,"T":"P","D":"power","U":"W","L":2},{"I":4106,"T":"E","D":"energy","U":"Wh","L":2},{"I":4107,"T":"E","D":"energyReturned","U":"Wh","L":2},{"I":4108,"T":"V","D":"voltage","U":"V","L":2},{"I":4109,"T":"I","D":"current","U":"A","L":2},{"I":4110,"T":"S","D":"powerFactor","R":["0/1","-1"],"L":2},{"I":4205,"T":"P","D":"power","U":"W","L":3},{"I":4206,"T":"E","D":"energy","U":"Wh","L":3},{"I":4207,"T":"E","D":"energyReturned","U":"Wh","L":3},{"I":4208,"T":"V","D":"voltage","U":"V","L":3},{"I":4209,"T":"I","D":"current","U":"A","L":3},{"I":4210,"T":"S","D":"powerFactor","R":["0/1","-1"],"L":3},{"I":4305,"T":"P","D":"power","U":"W","L":4},{"I":4306,"T":"E","D":"energy","U":"Wh","L":4},{"I":4307,"T":"E","D":"energyReturned","U":"Wh","L":4},{"I":4308,"T":"V","D":"voltage","U":"V","L":4},{"I":4309,"T":"I","D":"current","U":"A","L":4},{"I":4310,"T":"S","D":"powerFactor","R":["0/1","-1"],"L":4},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
11:28:10.994 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id=1: relay_0
11:28:10.995 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id=2: emeter_0
11:28:10.995 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id=3: emeter_1
11:28:10.995 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id=4: emeter_2
11:28:10.995 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id=5: device
11:28:10.995 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: Adding 21 sensor definitions
11:28:10.996 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
11:28:10.996 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 1101: output, Type=S, Range=0/1, Links=1
11:28:10.996 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4105: power, Type=P, Range=null, Links=2
11:28:10.996 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4106: energy, Type=E, Range=null, Links=2
11:28:10.996 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4107: energyReturned, Type=E, Range=null, Links=2
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4108: voltage, Type=V, Range=null, Links=2
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4109: current, Type=I, Range=null, Links=2
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4205: power, Type=P, Range=null, Links=3
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4206: energy, Type=E, Range=null, Links=3
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4207: energyReturned, Type=E, Range=null, Links=3
11:28:10.997 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4208: voltage, Type=V, Range=null, Links=3
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4209: current, Type=I, Range=null, Links=3
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4305: power, Type=P, Range=null, Links=4
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4306: energy, Type=E, Range=null, Links=4
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4307: energyReturned, Type=E, Range=null, Links=4
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4308: voltage, Type=V, Range=null, Links=4
11:28:10.998 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4309: current, Type=I, Range=null, Links=4
11:28:10.999 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
11:28:10.999 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
11:28:10.999 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Initializing device shellyem3-8caab561944a, type SHEM-3, Hardware: Rev: prod-2020-1, batch 1; Firmware: v1.12.1-ga9117d3 / 20221027-110030
11:28:10.999 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Shelly settings info for shellyem3-8caab561944a: {"device":{"type":"SHEM-3","mac":"8CAAB561944A","hostname":"shellyem3-8CAAB561944A","num_outputs":1,"num_meters":0,"num_emeters":3,"report_period":1},"wifi_ap":{"enabled":false,"ssid":"shellyem3-8CAAB561944A","key":""},"wifi_sta":{"enabled":true,"ssid":"kreutzer","ipv4_method":"static","ip":"192.168.1.91","gw":"192.168.1.1","mask":"255.255.255.0","dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"ap_roaming":{"enabled":false,"threshold":-70},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyem3-8CAAB561944A","reconnect_timeout_max":60.000000,"reconnect_timeout_min":2.000000,"clean_session":true,"keep_alive":60,"max_qos":0,"retain":false,"update_period":30},"coiot": {"enabled":true,"update_period":15,"peer":""},"sntp":{"server":"time.google.com","enabled":true},"login":{"enabled":false,"unprotected":false,"username":"admin"},"pin_code":"","name":"WP","fw":"20221027-110030/v1.12.1-ga9117d3","discoverable":true,"build_info":{"build_id":"20221027-110030/v1.12.1-ga9117d3","build_timestamp":"2022-10-27T11:00:30Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Zurich","lat":47.448490,"lng":8.211500,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":true,"tz_dst_auto":true,"time":"11:28","unixtime":1671013690,"led_status_disable":false,"debug_enable":false,"allow_cross_origin":false,"actions":{"active":false,"names":["out_on_url","out_off_url","over_power_url","under_power_url","over_power_url","under_power_url","over_power_url","under_power_url","over_power_url","under_power_url","n_mismatch_url"]},"hwinfo":{"hw_revision":"prod-2020-1", "batch_id":1},"cf_output":0,"relays":[{"name":null,"ison":false,"has_timer":false,"default_state":"off","auto_on":0.00,"auto_off":0.00,"schedule":false,"schedule_rules":[]}],"emeters":[{"name":null,"appliance_type":"General","max_power":0,"range_extender":1},{"name":null,"appliance_type":"General","max_power":0,"range_extender":1},{"name":null,"appliance_type":"General","max_power":0,"range_extender":1}],"emeter_n":{"range_extender":1,"mismatch_threshold":1.00},"eco_mode_enabled":true}
11:28:11.000 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Device hasRelays:true (numRelays=1),isRoller:false (numRoller=0),isDimmer:false,numMeter=3,isEMeter:true), ext. Switch Add-On: n/a,isSensor:false,isDS:false,hasBattery:false,isSense:false,isMotion:false,isLight:false,isBulb:false,isDuo:false,isRGBW2:false,inColor:false,alwaysOn:true, updatePeriod:70sec
11:28:11.000 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Thing successfully initialized.
11:28:11.001 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Update status job started, interval=20*3=60sec.
11:28:11.002 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'shelly:shellyem3:zaehlerkeller' changed from UNKNOWN (CONFIGURATION_PENDING): Initialisierung oder Gerät im Schlafmodus. to ONLINE

11:28:13.148 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Unable to refresh status: Status konnte nicht aktualisiert werden
java.lang.NullPointerException: null
        at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions.createRelayChannels(ShellyChannelDefinitions.java:350) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.createRelayChannels(ShellyRelayHandler.java:293) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.updateRelays(ShellyRelayHandler.java:337) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.updateDeviceStatus(ShellyRelayHandler.java:202) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyBaseHandler.refreshStatus(ShellyBaseHandler.java:522) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
        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) [?:?]

11:28:15.959 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Message from /192.168.1.91:5683 (MID=6571): {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]}
11:28:15.959 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Version 2 detected
11:28:15.960 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]} (serial=47892)
11:28:15.960 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: 21 CoAP sensor updates received
11:28:15.961 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter1#currentWatts updated with 612.85 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.962 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter1#voltage updated with 235.1 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.962 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter2#currentWatts updated with 366.40 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.962 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter2#voltage updated with 237.5 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.962 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter3#currentWatts updated with 339.31 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.963 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter3#voltage updated with 237.2 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.963 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel relay#output updated with OFF (type class org.openhab.core.library.types.OnOffType).
11:28:15.963 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: 18 channels updated from CoIoT status, serial=47892
11:28:15.963 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Message from /192.168.1.91:5683 (MID=6571): {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]}
11:28:15.964 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]} (serial=47892)
11:28:15.965 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: 21 CoAP sensor updates received
11:28:15.982 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Message from /192.168.1.91:5683 (MID=7774): {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]}
11:28:15.982 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,612.85],[0,4106,407273.0],[0,4107,0.0],[0,4108,235.07],[0,4109,3.28],[0,4110,0.79],[0,4205,366.40],[0,4206,176783.4],[0,4207,148.5],[0,4208,237.53],[0,4209,2.68],[0,4210,0.57],[0,4305,339.31],[0,4306,186699.7],[0,4307,0.0],[0,4308,237.17],[0,4309,2.45],[0,4310,0.59],[0,6102,0]]} (serial=47892)
11:28:15.983 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: 21 CoAP sensor updates received
11:28:15.984 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter1#currentWatts updated with 612.85 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.985 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter1#voltage updated with 235.1 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.985 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter2#currentWatts updated with 366.40 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.985 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter2#voltage updated with 237.5 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.985 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter3#currentWatts updated with 339.31 W (type class org.openhab.core.library.types.QuantityType).
11:28:15.985 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Channel meter3#voltage updated with 237.2 V (type class org.openhab.core.library.types.QuantityType).
11:28:15.986 [DEBUG] [elly.internal.api1.Shelly1CoapHandler] - shellyem3-zaehlerkeller: 17 channels updated from CoIoT status, serial=47892
11:28:16.324 [DEBUG] [ly.internal.handler.ShellyBaseHandler] - shellyem3-zaehlerkeller: Unable to refresh status: Status konnte nicht aktualisiert werden
java.lang.NullPointerException: null
        at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions.createRelayChannels(ShellyChannelDefinitions.java:350) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.createRelayChannels(ShellyRelayHandler.java:293) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.updateRelays(ShellyRelayHandler.java:337) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyRelayHandler.updateDeviceStatus(ShellyRelayHandler.java:202) ~[?:?]
        at org.openhab.binding.shelly.internal.handler.ShellyBaseHandler.refreshStatus(ShellyBaseHandler.java:522) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
        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) [?:?]