“[shelly] Stack Overflow reading N current from 3EM and no channel data

beanz · December 30, 2023, 1:00pm

Running OH 4.1.0 official docker container on Synology. JVR is 17.0.9.
Using the ShellyBinding as it came with the distribution.

Newly I found the following stack trace in the openhab.log which could be related to the Shelly binding.

2023-12-30 10:08:59.292 [ERROR] [fornium.elements.util.SerialExecutor] - unexpected error occurred:
java.lang.StackOverflowError: null
	at org.ops4j.pax.logging.internal.TrackingLogger.isDebugEnabled(TrackingLogger.java:60) ~[bundleFile:?]
	at org.ops4j.pax.logging.slf4j.Slf4jLogger.isDebugEnabled(Slf4jLogger.java:339) ~[bundleFile:?]
	at org.eclipse.jetty.util.log.JettyAwareLogger.isDebugEnabled(JettyAwareLogger.java:170) ~[?:?]
	at org.eclipse.jetty.util.log.Slf4jLog.isDebugEnabled(Slf4jLog.java:120) ~[?:?]
	at org.eclipse.jetty.client.AbstractConnectionPool.tryCreate(AbstractConnectionPool.java:280) ~[?:?]
	at org.eclipse.jetty.client.AbstractConnectionPool.acquire(AbstractConnectionPool.java:258) ~[?:?]
	at org.eclipse.jetty.client.HttpDestination.process(HttpDestination.java:388) ~[?:?]
	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:372) ~[?:?]
	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:366) ~[?:?]
	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:343) ~[?:?]
	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:323) ~[?:?]
	at org.eclipse.jetty.client.HttpClient.send(HttpClient.java:627) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.sendAsync(HttpRequest.java:780) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:767) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:728) ~[?:?]
	at org.openhab.binding.shelly.internal.api.ShellyHttpClient.innerRequest(ShellyHttpClient.java:188) ~[?:?]
	at org.openhab.binding.shelly.internal.api.ShellyHttpClient.httpRequest(ShellyHttpClient.java:113) ~[?:?]
	at org.openhab.binding.shelly.internal.api.ShellyHttpClient.callApi(ShellyHttpClient.java:98) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1HttpApi.getCoIoTDescription(Shelly1HttpApi.java:345) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.discover(Shelly1CoapHandler.java:555) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.handleDeviceDescription(Shelly1CoapHandler.java:369) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.discover(Shelly1CoapHandler.java:558) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.handleDeviceDescription(Shelly1CoapHandler.java:369) ~[?:?]
...

last two lines repeat until the stack overruns.

Any ideas how to fix or can I provide some more information?

wborn · January 3, 2024, 1:00pm

Maybe @markus7017 has seen this before?

Apparently the stackoverflow occurs because the device description is invalid and it goes in an infinite recursion while trying to detect a valid description:

github.com

openhab/openhab-addons/blob/d434fc5e4e8d291478273d3c8e07141c4457f575/bundles/org.openhab.binding.shelly/src/main/java/org/openhab/binding/shelly/internal/api1/Shelly1CoapHandler.java#L364-L371


      
          if (!valid) {
              logger.debug(
                      "{}: Incompatible device description detected for CoIoT version {} (id length mismatch), discarding!",
                      thingName, coiot.getVersion());
          
              discover();
              return;
          }

beanz · January 3, 2024, 10:21pm

Meanwhile I enabled debug logging on the Shelly binding.
The log is being filled with the same pattern starting at “Using CoAP device description from successful HTTP /cit/d” No clue why the extraction from device description fails.
As Wouter indicates, the recursion can be observed in the log very well.
Some and then the stack overflow occurs - unpredictable at which sensor definition it occurs. Within an hour after restart or even 14 hours later. If you ask me, it takes surprisingly long unitl it bails out.

2024-01-03 08:39:46.566 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 08:39:46.568 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 08:39:46.570 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 08:39:46.571 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 08:39:46.573 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 08:39:46.574 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 08:39:46.576 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 08:39:46.578 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 08:39:46.579 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 08:39:46.581 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-03 08:39:46.582 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-03 08:39:46.584 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-03 08:39:46.586 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-03 08:39:46.587 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-03 08:39:46.589 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-03 08:39:46.590 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-03 08:39:46.592 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-03 08:39:46.593 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-03 08:39:46.595 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-03 08:39:46.597 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-03 08:39:46.598 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-03 08:39:46.600 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-03 08:39:46.601 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-03 08:39:46.603 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-03 08:39:46.605 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-03 08:39:46.606 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-03 08:39:46.608 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-03 08:39:46.610 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-03 08:39:46.611 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 08:39:46.613 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 08:39:46.614 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 08:39:46.616 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 08:39:47.288 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 08:39:47.289 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 08:39:47.291 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 08:39:47.293 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 08:39:47.294 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 08:39:47.296 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 08:39:47.297 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 08:39:47.299 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 08:39:47.300 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 08:39:47.302 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-03 08:39:47.304 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-03 08:39:47.305 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-03 08:39:47.307 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-03 08:39:47.308 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-03 08:39:47.310 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-03 08:39:47.312 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-03 08:39:47.313 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-03 08:39:47.315 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-03 08:39:47.317 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-03 08:39:47.318 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-03 08:39:47.320 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-03 08:39:47.321 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-03 08:39:47.323 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-03 08:39:47.325 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-03 08:39:47.326 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-03 08:39:47.328 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-03 08:39:47.329 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-03 08:39:47.331 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-03 08:39:47.333 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 08:39:47.334 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 08:39:47.336 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 08:39:47.337 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 08:39:47.376 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 08:39:47.378 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 08:39:47.379 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 08:39:47.381 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 08:39:47.383 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 08:39:47.384 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 08:39:47.386 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 08:39:47.387 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 08:39:47.389 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 08:39:47.398 [ERROR] [fornium.elements.util.SerialExecutor] - unexpected error occurred:
java.lang.StackOverflowError: null
	at java.lang.ClassLoader.defineClass1(Native Method) ~[?:?]
	at java.lang.ClassLoader.defineClass(ClassLoader.java:1017) ~[?:?]
	at org.eclipse.osgi.internal.loader.ModuleClassLoader.defineClass(ModuleClassLoader.java:283) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.classpath.ClasspathManager.defineClass(ClasspathManager.java:716) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.classpath.ClasspathManager.findClassImpl(ClasspathManager.java:639) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.classpath.ClasspathManager.findLocalClassImpl(ClasspathManager.java:607) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.classpath.ClasspathManager.findLocalClassImpl(ClasspathManager.java:587) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.classpath.ClasspathManager.findLocalClass(ClasspathManager.java:566) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.ModuleClassLoader.findLocalClass(ModuleClassLoader.java:335) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.BundleLoader.findLocalClass(BundleLoader.java:397) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.BundleLoader.findClass0(BundleLoader.java:500) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.BundleLoader.findClass(BundleLoader.java:416) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.loader.ModuleClassLoader.loadClass(ModuleClassLoader.java:168) ~[org.eclipse.osgi-3.18.0.jar:?]
	at java.lang.ClassLoader.loadClass(ClassLoader.java:525) ~[?:?]
	at org.apache.logging.log4j.message.AbstractMessageFactory.newMessage(AbstractMessageFactory.java:59) ~[bundleFile:?]
	at org.apache.logging.log4j.spi.AbstractLogger.logMessage(AbstractLogger.java:1980) ~[bundleFile:?]
	at org.apache.logging.log4j.spi.AbstractLogger.logIfEnabled(AbstractLogger.java:1946) ~[bundleFile:?]
	at org.apache.logging.log4j.spi.AbstractLogger.error(AbstractLogger.java:718) ~[bundleFile:?]
	at org.apache.logging.log4j.core.appender.DefaultErrorHandler.error(DefaultErrorHandler.java:93) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AppenderControl.handleAppenderError(AppenderControl.java:170) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AppenderControl.tryCallAppender(AppenderControl.java:165) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AppenderControl.callAppender0(AppenderControl.java:134) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AppenderControl.callAppenderPreventRecursion(AppenderControl.java:125) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AppenderControl.callAppender(AppenderControl.java:89) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.callAppenders(LoggerConfig.java:683) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.processLogEvent(LoggerConfig.java:641) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:624) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.logParent(LoggerConfig.java:674) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.processLogEvent(LoggerConfig.java:643) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:624) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.logParent(LoggerConfig.java:674) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.processLogEvent(LoggerConfig.java:643) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:624) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:531) ~[bundleFile:?]
	at org.apache.logging.log4j.core.config.AwaitCompletionReliabilityStrategy.log(AwaitCompletionReliabilityStrategy.java:63) ~[bundleFile:?]
	at org.apache.logging.log4j.core.Logger.logMessage(Logger.java:155) ~[bundleFile:?]
	at org.ops4j.pax.logging.log4j2.internal.PaxLoggerImpl.doLog0(PaxLoggerImpl.java:1122) ~[bundleFile:?]
	at org.ops4j.pax.logging.log4j2.internal.PaxLoggerImpl.doLog(PaxLoggerImpl.java:1098) ~[bundleFile:?]
	at org.ops4j.pax.logging.log4j2.internal.PaxLoggerImpl.debug(PaxLoggerImpl.java:252) ~[bundleFile:?]
	at org.ops4j.pax.logging.internal.TrackingLogger.debug(TrackingLogger.java:167) ~[bundleFile:?]
	at org.ops4j.pax.logging.slf4j.Slf4jLogger.debug(Slf4jLogger.java:415) ~[bundleFile:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.addSensor(Shelly1CoapHandler.java:385) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.handleDeviceDescription(Shelly1CoapHandler.java:359) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.discover(Shelly1CoapHandler.java:558) ~[?:?]
	at org.openhab.binding.shelly.internal.api1.Shelly1CoapHandler.handleDeviceDescription(Shelly1CoapHandler.java:369) ~[?:?]

I also checked now the descriptors of that Shelly 3EM device, which looks fine to me. However

UID: shelly:shellyem3:244cab4359da
label: Mains Power
thingTypeUID: shelly:shellyem3
configuration:
  eventsCoIoT: true
  deviceIp: 192.168.48.21
  eventsSwitch: false
  updateInterval: 30
  eventsButton: false
  eventsPush: false
channels:
  - id: device#alarm
    channelTypeUID: shelly:alarmTrigger
    label: Alarm
    description: Alarm Trigger, e.g. weak WiFi Signal detected or over heating
    configuration: {}
  - id: device#wifiSignal
    channelTypeUID: system:signal-strength
    label: Signal Strength
    description: Signal strength as with values 0 (worst), 1, 2, 3 or 4 (best)
    configuration: {}
  - id: device#uptime
    channelTypeUID: shelly:uptime
    label: Uptime
    description: Number of seconds since the device was powered up
    configuration: {}
  - id: device#accumulatedWatts
    channelTypeUID: shelly:meterAccuWatts
    label: null
    description: null
    configuration: {}
  - id: device#accumulatedWTotal
    channelTypeUID: shelly:meterAccuTotal
    label: null
    description: null
    configuration: {}
  - id: device#accumulatedReturned
    channelTypeUID: shelly:meterAccuReturned
    label: null
    description: null
    configuration: {}
  - id: device#updateAvailable
    channelTypeUID: shelly:updateAvailable
    label: null
    description: null
    configuration: {}
  - id: device#heartBeat
    channelTypeUID: shelly:heartBeat
    label: null
    description: null
    configuration: {}
  - id: relay#output
    channelTypeUID: system:power
    label: null
    description: null
    configuration: {}
  - id: relay#autoOn
    channelTypeUID: shelly:timerAutoOn
    label: null
    description: null
    configuration: {}
  - id: relay#autoOff
    channelTypeUID: shelly:timerAutoOff
    label: null
    description: null
    configuration: {}
  - id: relay#timerActive
    channelTypeUID: shelly:timerActive
    label: null
    description: null
    configuration: {}
  - id: device#deviceName
    channelTypeUID: shelly:deviceName
    label: null
    description: null
    configuration: {}
  - id: device#statusLed
    channelTypeUID: shelly:ledStatusDisable
    label: null
    description: null
    configuration: {}
  - id: meter1#currentWatts
    channelTypeUID: shelly:meterWatts
    label: null
    description: null
    configuration: {}
  - id: meter1#totalKWH
    channelTypeUID: shelly:meterTotal
    label: null
    description: null
    configuration: {}
  - id: meter1#returnedKWH
    channelTypeUID: shelly:meterReturned
    label: null
    description: null
    configuration: {}
  - id: meter1#voltage
    channelTypeUID: shelly:meterVoltage
    label: null
    description: null
    configuration: {}
  - id: meter1#current
    channelTypeUID: shelly:meterCurrent
    label: null
    description: null
    configuration: {}
  - id: meter1#powerFactor
    channelTypeUID: shelly:meterPowerFactor
    label: null
    description: null
    configuration: {}
  - id: meter1#lastUpdate
    channelTypeUID: shelly:lastUpdate
    label: null
    description: null
    configuration: {}
  - id: meter2#currentWatts
    channelTypeUID: shelly:meterWatts
    label: null
    description: null
    configuration: {}
  - id: meter2#totalKWH
    channelTypeUID: shelly:meterTotal
    label: null
    description: null
    configuration: {}
  - id: meter2#returnedKWH
    channelTypeUID: shelly:meterReturned
    label: null
    description: null
    configuration: {}
  - id: meter2#voltage
    channelTypeUID: shelly:meterVoltage
    label: null
    description: null
    configuration: {}
  - id: meter2#current
    channelTypeUID: shelly:meterCurrent
    label: null
    description: null
    configuration: {}
  - id: meter2#powerFactor
    channelTypeUID: shelly:meterPowerFactor
    label: null
    description: null
    configuration: {}
  - id: meter2#lastUpdate
    channelTypeUID: shelly:lastUpdate
    label: null
    description: null
    configuration: {}
  - id: meter3#currentWatts
    channelTypeUID: shelly:meterWatts
    label: null
    description: null
    configuration: {}
  - id: meter3#totalKWH
    channelTypeUID: shelly:meterTotal
    label: null
    description: null
    configuration: {}
  - id: meter3#returnedKWH
    channelTypeUID: shelly:meterReturned
    label: null
    description: null
    configuration: {}
  - id: meter3#voltage
    channelTypeUID: shelly:meterVoltage
    label: null
    description: null
    configuration: {}
  - id: meter3#current
    channelTypeUID: shelly:meterCurrent
    label: null
    description: null
    configuration: {}
  - id: meter3#powerFactor
    channelTypeUID: shelly:meterPowerFactor
    label: null
    description: null
    configuration: {}
  - id: meter3#lastUpdate
    channelTypeUID: shelly:lastUpdate
    label: null
    description: null
    configuration: {}
  - id: device#resetTotals
    channelTypeUID: shelly:meterResetTotals
    label: Reset Energy Measurements
    description: Resets totals measurement data
    configuration: {}

Hope this context helps you finding the source of the problem.

beanz · January 3, 2024, 10:35pm

Now I found the beginning of the loop.
The device is shutting down for whatever reason … at that UTC time I wasn’t playing around with OH.

2024-01-03 19:18:40.695 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=24085): {"G":[[0,9103,0],[0,1101,0],[0,4105,58.05],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,229.04],[0,4109,0.34],[0,4110,0.74],[0,4205,103.11],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.70],[0,4305,176.93],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.30],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]}
2024-01-03 19:18:40.695 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,58.05],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,229.04],[0,4109,0.34],[0,4110,0.74],[0,4205,103.11],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.70],[0,4305,176.93],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.30],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]} (serial=56099)
2024-01-03 19:18:40.696 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-03 19:18:40.697 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,58.05],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,229.04],[0,4109,0.34],[0,4110,0.74],[0,4205,103.11],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.70],[0,4305,176.93],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.30],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]}
2024-01-03 19:18:40.697 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#currentWatts updated with 58.05 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:40.699 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#voltage updated with 229 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:40.700 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#currentWatts updated with 103.11 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:40.701 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#voltage updated with 227.2 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:40.702 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter3#currentWatts updated with 176.93 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:40.703 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 9 channels updated from CoIoT status, serial=56099
2024-01-03 19:18:40.797 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel device#accumulatedWatts updated with 338.09 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:41.694 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=24086): {"G":[[0,9103,0],[0,1101,0],[0,4105,57.99],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.94],[0,4109,0.34],[0,4110,0.74],[0,4205,111.64],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.75],[0,4305,176.15],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.29],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]}
2024-01-03 19:18:41.694 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,57.99],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.94],[0,4109,0.34],[0,4110,0.74],[0,4205,111.64],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.75],[0,4305,176.15],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.29],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]} (serial=56355)
2024-01-03 19:18:41.695 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-03 19:18:41.696 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,57.99],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.94],[0,4109,0.34],[0,4110,0.74],[0,4205,111.64],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.22],[0,4209,0.65],[0,4210,0.75],[0,4305,176.15],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.29],[0,4309,1.08],[0,4310,0.71],[0,6,0.79],[0,6102,0]]}
2024-01-03 19:18:41.696 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#currentWatts updated with 57.99 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:41.698 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#voltage updated with 228.9 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:41.699 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#currentWatts updated with 111.64 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:41.702 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter3#currentWatts updated with 176.15 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:41.703 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 7 channels updated from CoIoT status, serial=56355
2024-01-03 19:18:42.227 [DEBUG] [bus.handler.ModbusPollerThingHandler] - Thing modbus:poller:smartfox:smartfox03 received response PollResult(result=AsyncModbusReadResult(request = ModbusReadRequestBlueprint [slaveId=1, functionCode=READ_MULTIPLE_REGISTERS, start=41017, length=8, maxTries=3], registers = ModbusRegisterArray(000001480000003700000063000000AE)))
2024-01-03 19:18:42.228 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:6efd0fe043 channels updated: {modbus:data:6efd0fe043:number=328}. readValueType=int32, readIndex=Optional[41017], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 328 and boolValue=true. Registers ModbusRegisterArray(000001480000003700000063000000AE) for request ModbusReadRequestBlueprint [slaveId=1, functionCode=READ_MULTIPLE_REGISTERS, start=41017, length=8, maxTries=3]
2024-01-03 19:18:42.228 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:smartfox03:f1710f1004 channels updated: {modbus:data:smartfox03:f1710f1004:number=174}. readValueType=int32, readIndex=Optional[41023], readSubIndex(or 0)=0, extractIndex=6 -> numeric value 174 and boolValue=true. Registers ModbusRegisterArray(000001480000003700000063000000AE) for request ModbusReadRequestBlueprint [slaveId=1, functionCode=READ_MULTIPLE_REGISTERS, start=41017, length=8, maxTries=3]
2024-01-03 19:18:42.229 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:smartfox:8becae9a8b channels updated: {modbus:data:smartfox:8becae9a8b:number=55}. readValueType=int32, readIndex=Optional[41019], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 55 and boolValue=true. Registers ModbusRegisterArray(000001480000003700000063000000AE) for request ModbusReadRequestBlueprint [slaveId=1, functionCode=READ_MULTIPLE_REGISTERS, start=41017, length=8, maxTries=3]
2024-01-03 19:18:42.230 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:smartfox03:d668a0e358 channels updated: {modbus:data:smartfox03:d668a0e358:number=99}. readValueType=int32, readIndex=Optional[41021], readSubIndex(or 0)=0, extractIndex=4 -> numeric value 99 and boolValue=true. Registers ModbusRegisterArray(000001480000003700000063000000AE) for request ModbusReadRequestBlueprint [slaveId=1, functionCode=READ_MULTIPLE_REGISTERS, start=41017, length=8, maxTries=3]
2024-01-03 19:18:43.692 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=24087): {"G":[[0,9103,0],[0,1101,0],[0,4105,58.30],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.93],[0,4109,0.34],[0,4110,0.74],[0,4205,98.78],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.31],[0,4209,0.64],[0,4210,0.69],[0,4305,176.57],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.32],[0,4309,1.08],[0,4310,0.71],[0,6,0.80],[0,6102,0]]}
2024-01-03 19:18:43.692 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,58.30],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.93],[0,4109,0.34],[0,4110,0.74],[0,4205,98.78],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.31],[0,4209,0.64],[0,4210,0.69],[0,4305,176.57],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.32],[0,4309,1.08],[0,4310,0.71],[0,6,0.80],[0,6102,0]]} (serial=56611)
2024-01-03 19:18:43.692 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-03 19:18:43.693 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,58.30],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,228.93],[0,4109,0.34],[0,4110,0.74],[0,4205,98.78],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.31],[0,4209,0.64],[0,4210,0.69],[0,4305,176.57],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.32],[0,4309,1.08],[0,4310,0.71],[0,6,0.80],[0,6102,0]]}
2024-01-03 19:18:43.694 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#currentWatts updated with 58.30 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:43.695 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#currentWatts updated with 98.78 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:43.697 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#voltage updated with 227.3 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:43.698 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter3#currentWatts updated with 176.57 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:43.699 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 7 channels updated from CoIoT status, serial=56611
2024-01-03 19:18:43.961 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#powerFactor updated with 0.69 % (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:43.964 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel device#accumulatedWatts updated with 333.65 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.029 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#currentWatts updated with 58.43 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.030 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter1#voltage updated with 229 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.031 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#currentWatts updated with 99.49 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.033 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#voltage updated with 227.1 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.034 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter2#powerFactor updated with 0.7 % (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.035 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter3#currentWatts updated with 177.61 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.037 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel meter3#voltage updated with 230.4 V (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:47.038 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel device#accumulatedWatts updated with 335.53 W (type class org.openhab.core.library.types.QuantityType).
2024-01-03 19:18:48.856 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Channel definitions updated.
2024-01-03 19:18:48.856 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Stopping Thing
2024-01-03 19:18:48.856 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Shutting down
2024-01-03 19:18:48.857 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Shelly statusJob stopped
2024-01-03 19:18:49.693 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=24088): {"G":[[0,9103,0],[0,1101,0],[0,4105,58.21],[0,4106,1806601.9],[0,4107,323446.6],[0,4108,229.19],[0,4109,0.34],[0,4110,0.74],[0,4205,108.60],[0,4206,2064785.3],[0,4207,241639.1],[0,4208,227.29],[0,4209,0.65],[0,4210,0.73],[0,4305,176.66],[0,4306,2478830.0],[0,4307,274044.7],[0,4308,230.41],[0,4309,1.09],[0,4310,0.70],[0,6,0.79],[0,6102,0]]}
2024-01-03 19:18:49.694 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Thing is shutting down, ignore CoIOT message
2024-01-03 19:18:50.857 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Using default userId admin from binding config
2024-01-03 19:18:50.858 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Using default password from bindingConfig (userId=admin)
2024-01-03 19:18:50.858 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Device config: Device address=192.168.48.21, HTTP user/password=admin/***, update interval=30
2024-01-03 19:18:50.858 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Configured Events: Button: false, Switch (on/off): false, Push: false, Roller: true, Sensor: true, CoIoT: true, Enable AutoCoIoT: true
2024-01-03 19:18:50.858 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Start initializing for thing Mains Power, type shellyem3, IP address 192.168.48.21, Gen2: false, CoIoT: true
2024-01-03 19:18:51.034 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Auto-CoIoT is enabled, disabling action urls
2024-01-03 19:18:51.035 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Starting CoIoT (autoCoIoT=true/true)
2024-01-03 19:18:51.035 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Stopping CoAP Listener
2024-01-03 19:18:51.035 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoAP Request was canceled
2024-01-03 19:18:51.035 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Starting CoAP Listener
2024-01-03 19:18:51.086 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 19:18:51.086 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 19:18:51.086 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 19:18:51.086 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 19:18:51.087 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-03 19:18:51.088 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-03 19:18:51.089 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 19:18:51.090 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 19:18:51.128 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 19:18:51.128 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 19:18:51.128 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 19:18:51.128 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-03 19:18:51.129 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-03 19:18:51.130 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-03 19:18:51.131 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 19:18:51.132 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 19:18:51.188 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-03 19:18:51.188 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-03 19:18:51.188 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-03 19:18:51.189 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-03 19:18:51.190 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-03 19:18:51.191 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 19:18:51.192 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 19:18:51.229 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d

beanz · January 3, 2024, 10:50pm

Sorry, now I remember what I did at that time: just renamed the thing. Then it got unresponsive for a short while (initializing was indicated on OH).

The loop ends 13 minutes later:

2024-01-03 19:31:29.991 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-03 19:31:29.991 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-03 19:31:29.991 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-03 19:31:29.992 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-03 19:31:30.043 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Initializing device shelly-4359D, type SHEM-3, Hardware: Rev: dev-prototype, batch 0; Firmware: 1.14.0- / 20230913
2024-01-03 19:31:30.044 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Shelly settings info for shelly-4359D: {"device":{"type":"SHEM-3","mac":"244CAB4359DA","hostname":"shellyem3-244CAB4359DA","num_outputs":1,"num_meters":0,"num_emeters":3,"report_period":1},"wifi_ap":{"enabled":false,"ssid":"shellyem3-244CAB4359DA","key":""},"wifi_sta":{"enabled":true,"ssid":"beanzIOT","ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"ap_roaming":{"enabled":true,"threshold":-70},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyem3-244CAB4359DA","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":"Main Power","fw":"20230913-114244/v1.14.0-gcb84623","pon_wifi_reset":false,"discoverable":false,"build_info":{"build_id":"20230913-114244/v1.14.0-gcb84623","build_timestamp":"2023-09-13T11:42:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Zurich","lat":47.490700,"lng":8.738800,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"20:30","unixtime":1704310244,"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":"dev-prototype", "batch_id":0},"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":"Phase 1","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 2","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 3","appliance_type":"General","max_power":0,"range_extender":1}],"emeter_n":{"range_extender":1,"mismatch_threshold":0.00},"eco_mode_enabled":true}
2024-01-03 19:31:30.044 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: 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, BLU Gateway support: true,alwaysOn:70, updatePeriod:falsesec
2024-01-03 19:31:30.044 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Thing successfully initialized.

markus7017 · January 7, 2024, 5:45pm

I already fixed the stack overflow - thanks @wborn for excellent investigation

The 2nd issue is a sensor value “6” (which is very unusual compared to the id schema), obviously added in a newer firmware. @beanz

Could you please send me

a screenshot of the device UI shows this value (neutralCurrent)
the output from http://<device ip>/settings and http://<device ip>/status

I created Issues #16236 and #16237

beanz · January 7, 2024, 7:32pm

Hi markus

Just scanned all the logfiles from last 7 days with debug level turned on.
Unfortunately (for debugging) the error/stack overflow didn’t occur again. Neither I could spot id 6 on neutralCurrent being rejected in any of the log files.
I assume this has to do with the fact that I’ve renamed the device. So not spotting the neutralCurrent rejection could be connected. What do you think?
Further I didn’t do any firmware update nor other changes on the system.

Here’s the screenshot of the Shelly3EM. Does it show what you were looking for?

Settings of the device.

{"device":{"type":"SHEM-3","mac":"244CAB4359DA","hostname":"shellyem3-244CAB4359DA","num_outputs":1,"num_meters":0,"num_emeters":3,"report_period":1},"wifi_ap":{"enabled":false,"ssid":"shellyem3-244CAB4359DA","key":""},"wifi_sta":{"enabled":true,"ssid":"XXX","ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"ap_roaming":{"enabled":true,"threshold":-70},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyem3-244CAB4359DA","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":"Mains Power","fw":"20230913-114244/v1.14.0-gcb84623","pon_wifi_reset":false,"discoverable":false,"build_info":{"build_id":"20230913-114244/v1.14.0-gcb84623","build_timestamp":"2023-09-13T11:42:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Zurich","lat":47.4,"lng":8.7,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"20:15","unixtime":1704654909,"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":"dev-prototype", "batch_id":0},"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":"Phase 1","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 2","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 3","appliance_type":"General","max_power":0,"range_extender":1}],"emeter_n":{"range_extender":1,"mismatch_threshold":0.00},"eco_mode_enabled":true}

and status:

{"wifi_sta":{"connected":true,"ssid":"XXX","ip":"192.168.YY.ZZ","rssi":-51},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"20:16","unixtime":1704655006,"serial":38182,"has_update":false,"mac":"244CAB4359DA","cfg_changed_cnt":3,"actions_stats":{"skipped":0},"relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false,"is_valid":true,"source":"input"}],"emeters":[{"power":180.12,"pf":0.89,"current":0.90,"voltage":225.50,"is_valid":true,"total":1816915.7,"total_returned":323446.6},{"power":511.61,"pf":0.98,"current":2.34,"voltage":223.99,"is_valid":true,"total":2081144.6,"total_returned":241639.1},{"power":255.78,"pf":0.77,"current":1.46,"voltage":227.60,"is_valid":true,"total":2507713.4,"total_returned":274044.7}],"total_power":947.51,"emeter_n":{"current":2.28,"ixsum":2.29,"mismatch":false,"is_valid":true},"fs_mounted":true,"v_data":1,"ct_calst":0,"update":{"status":"idle","has_update":false,"new_version":"20230913-114244/v1.14.0-gcb84623","old_version":"20230913-114244/v1.14.0-gcb84623","beta_version":"20231107-165007/v1.14.1-rc1-g0617c15"},"ram_total":49920,"ram_free":30776,"fs_size":233681,"fs_free":153612,"uptime":460156}

Let me know if your need something more.

markus7017 · January 7, 2024, 8:15pm

strange /status doesn’t report this value

Release log for v1.12.0 (08/2022) says:

[Shelly 3EM ] Add support for measuring on Neutral clamp. Calibration is needed.

Do you need that value?

beanz · January 7, 2024, 9:00pm

Just added that N clamp for curiosity, so no essentially needed. At that time I also did the calibration procedure for neutral.
Today there seems no option to turn off N measurements.

markus7017 · January 8, 2024, 12:15am

the question is if I implement that channel,
problem

the value is not reported by /status
so would only be updated when reported by CoAP
which means will be not filled when OH starts and depends on next update from device

beanz · January 8, 2024, 7:09am

Interestingly the overflow occurred yesterday again shortly after I inspected the logs.

The N value is reported by /status as a standalone property after “emeters” array and “total_power”

"emeter_n":{"current":2.28,"ixsum":2.29,"mismatch":false,"is_valid":true}

Did I get you right that you are thinking about adding the neutral channel to the device binding?

markus7017 · January 8, 2024, 4:51pm

You’ll see the stack overflow any time neutralCurrent is reported via Coap.

Do you have an idea what ixsum is and mismatch? I checked the Gen1 doc, but it doesn’t describe the emeter_n struct.

beanz · January 8, 2024, 5:28pm

The sum of all signed currents should be zero i.e. the mismatch would be the difference (in most cases this will be measurement errors or leaky currents - zzzz).
In my understanding:
current = current in the N wire
ixsum = sum of all signed currents in the 4 wires

On the settings page a mismatch threshold can be configured. I assume that the property “mismatch” becomes true, if abs(ixsum) > threshold

So I will unplug the neutral clamp on the device physically to avoid any reported values.

BTW: I observe the stack overflow after I migrated from OH 4.0.4 to OH 4.1.0.
My indicator is a failing rule, which calculates the difference between solar production and mains consumption. Trigger condition is the change on Total Power from ShellyEM3.
In my observations, values from ShellyEM3.TotalPower stop getting processed in OH after the stack overflow. Phase 1 …3 still are responsive.

beanz · January 8, 2024, 5:39pm

Question: would it help if I disable CoIoT? Found an option in the settings menu.

markus7017 · January 8, 2024, 8:41pm

You could, but then you don’t get events, just polling once per minute.
I’m already working on the change and will provide you a build soon,

markus7017 · January 9, 2024, 8:14pm

ok, here we are
https://github.com/markus7017/myfiles/blob/master/shelly/org.openhab.binding.shelly-4.2.0-SNAPSHOT.kar?raw=true

this build

is based on the current version for the dist
runs only on 4.1 / 4.2-SNAPSHOT
includes the fix for the stack overflow
creates a new channel group “nmeter” (if status.emeter_n != null) and the corresponding channels (see README)
updates ncurrent based on CoAP event
and ncurrent,ixsum,mismatch and nmTreshhold based on polling the device

That’s the theory, I can’t test, because I’m on vacation and don’t have a 3EM here

To install this build

Uninstall Shelly binding form UI
open OH console
– run “bundle:list | grep Shelly” to make sure that there is no Shelly binding anymore
– run “feature:install openhab-transport-coap”
– run “log:set org.openhab.binding.log”
Stop OH
copy org.openhab.binding.shelly-4.2.0-SNAPSHOT.kar into OH’s addons folder
start OH, wait a minute
run discovery
things should be back with restored item linkage
the 3EM thing should have a new channel group nmeter
check openHAB.log for errors

markus7017 · January 13, 2024, 1:02pm

@beanz Did you verified the build?

beanz · January 14, 2024, 1:43pm

hi markus
sorry the delay, quite busy week. but thanks for your work!

Here’s my resume:
Setup new docker container with OH 4.1.1. It runs parallel to my productive OH 4.1.0 instance.
Installed Shelly binding
Check whether new OH can see existing Shellys as things
On console: could see the Shelly bundle
Uninstall Shelly binding
On console: no more Shelly bundle
Feature install → console crashed, login again
openhab> feature:list | grep coap
openhab-core-io-transport-coap x 4.1.1 x x Started x distro-4.1.1 x
openhab-transport-coap x 4.1.1 x x x Started x distro-4.1.1 x CoAP Transport
openhab.tp-coap x 4.1.1 x x Started x distro-4.1.1 x Californium CoAP library
openhab>
openhab> log:set DEBUG org.openhab.binding.log
openhab> log:list

Logger                                             │ Level
───────────────────────────────────────────────────┼──────
ROOT                                               │ WARN
javax.jmdns                                        │ ERROR
javax.mail                                         │ ERROR
openhab.event                                      │ INFO
openhab.event.AddonEvent                           │ ERROR
openhab.event.ChannelDescriptionChangedEvent       │ ERROR
openhab.event.GroupStateUpdatedEvent               │ ERROR
openhab.event.InboxUpdatedEvent                    │ ERROR
openhab.event.ItemAddedEvent                       │ ERROR
openhab.event.ItemChannelLinkAddedEvent            │ ERROR
openhab.event.ItemChannelLinkRemovedEvent          │ ERROR
openhab.event.ItemRemovedEvent                     │ ERROR
openhab.event.ItemStateEvent                       │ ERROR
openhab.event.ItemStateUpdatedEvent                │ ERROR
openhab.event.RuleAddedEvent                       │ ERROR
openhab.event.RuleRemovedEvent                     │ ERROR
openhab.event.RuleStatusInfoEvent                  │ ERROR
openhab.event.StartlevelEvent                      │ ERROR
openhab.event.ThingAddedEvent                      │ ERROR
openhab.event.ThingRemovedEvent                    │ ERROR
openhab.event.ThingStatusInfoEvent                 │ ERROR
openhab.event.ThingUpdatedEvent                    │ ERROR
org.apache.cxf.jaxrs.sse.SseEventSinkImpl          │ ERROR
org.apache.cxf.phase.PhaseInterceptorChain         │ ERROR
org.apache.karaf.jaas.modules.audit                │ INFO
org.apache.karaf.kar.internal.KarServiceImpl       │ ERROR
org.apache.karaf.shell.ssh.SshUtils                │ ERROR
org.apache.karaf.shell.support                     │ OFF
org.apache.sshd                                    │ WARN
org.eclipse.lsp4j                                  │ OFF
org.jupnp                                          │ ERROR
org.openhab                                        │ INFO
org.openhab.binding.log                            │ DEBUG
org.ops4j.pax.url.mvn.internal.AetherBasedResolver │ ERROR
org.ops4j.pax.web.pax-web-runtime                  │ OFF
su.litvak.chromecast.api.v2.Channel                │ ERROR

Stopped OH
Copy the feature into the addon directory
Restart OH & Wait
In the inbox I could see my shelly devices
Tried to add the EM3 as a thing → error
Started to inspect the openhab.log

Installed the Shelly binding now - however I could see two instances in the list but there was no obvious difference. Selected the first one.

openhab> bundle:list | grep Shelly

263 x Active x  80 x 4.2.0.202401091944     x openHAB Add-ons :: Bundles :: Shelly Binding Gen 1,2,3, BLU

Added Shelly 3EM as thing → works

Looking at the available channels, the list looks very short. I’m missing the three power meter channels and your new nmeter etc.

The logfile contains quite some errors. Have a look.

2024-01-14 11:56:19.725 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-14 11:56:30.345 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-14 11:56:34.479 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2024-01-14 11:59:33.547 [INFO ] [p.auth.internal.AuthorizePageServlet] - First user account created: admin
2024-01-14 11:59:45.310 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-14 11:59:45.312 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-14 12:00:24.617 [INFO ] [nternal.manager.ShellyManagerServlet] - Shelly Manager started at http(s)://192.168.48.196:8080/shelly/manager
2024-01-14 12:00:29.191 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/services's Observer 
2024-01-14 12:00:29.194 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons's Observer 
2024-01-14 12:00:29.195 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/inbox's Observer 
2024-01-14 12:00:29.196 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/things's Observer 
2024-01-14 12:00:29.199 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/ui/components/system:sitemap's Observer 
2024-01-14 12:00:29.201 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/transformations's Observer 
2024-01-14 12:00:29.202 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/items's Observer 
2024-01-14 12:00:29.208 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/rules's Observer 
2024-01-14 12:00:31.309 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/js/4.app.3456231afb83cd720478.js's Observer 
2024-01-14 12:00:31.309 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/js/0.app.3456231afb83cd720478.js's Observer 
2024-01-14 12:00:31.310 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/js/1.app.3456231afb83cd720478.js's Observer 
2024-01-14 12:00:31.309 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/js/9.app.3456231afb83cd720478.js's Observer 
2024-01-14 12:00:31.321 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/js/14.app.3456231afb83cd720478.js's Observer 
2024-01-14 12:00:31.321 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/css/14.app.3456231afb83cd720478.css's Observer 
2024-01-14 12:00:36.010 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:00:36.537 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-14 12:00:36.746 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-14 12:00:36.748 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-14 12:00:43.013 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-14 12:00:45.951 [INFO ] [nternal.manager.ShellyManagerServlet] - Shelly Manager started at http(s)://192.168.48.196:8080/shelly/manager
2024-01-14 12:00:46.590 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'shelly:shelly1pm:e09806a9d2e8' to inbox.
2024-01-14 12:00:46.859 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'shelly:shellyplusi4:a8032ab619f0' to inbox.
2024-01-14 12:00:47.080 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'shelly:shellyplus1:3c610572ccc4' to inbox.
2024-01-14 12:00:48.235 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'shelly:shellyem3:244cab4359da' to inbox.
2024-01-14 12:00:52.000 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2024-01-14 12:38:49.997 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:38:50.774 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:38:50.789 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:38:50.788 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:38:51.225 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:38:51.659 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-14 12:38:51.853 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-14 12:38:51.855 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-14 12:38:52.793 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:38:52.806 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:38:52.806 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:38:56.814 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:38:56.837 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:38:56.837 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:38:57.568 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-14 12:39:07.079 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2024-01-14 12:43:39.045 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:43:40.099 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:43:40.112 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:43:40.120 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:43:40.547 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:43:40.982 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-14 12:43:41.154 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-14 12:43:41.155 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-14 12:43:43.148 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:43:48.454 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-14 12:43:56.367 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2024-01-14 12:55:10.903 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:121) ~[bundleFile:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.<init>(RepositoryImpl.java:51) ~[bundleFile:?]
	at org.apache.karaf.features.internal.service.RepositoryCacheImpl.create(RepositoryCacheImpl.java:51) ~[bundleFile:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatureCache(FeaturesServiceImpl.java:611) [bundleFile:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.ensureCacheLoaded(FeaturesServiceImpl.java:582) [bundleFile:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getRepository(FeaturesServiceImpl.java:530) [bundleFile:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.findMissingDependency(KarServiceImpl.java:175) [bundleFile:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:126) [bundleFile:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:100) [bundleFile:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:92) [bundleFile:?]
	at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:87) [bundleFile:?]
	at org.apache.karaf.deployer.kar.KarArtifactInstaller.install(KarArtifactInstaller.java:50) [bundleFile:?]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.install(DirectoryWatcher.java:950) [bundleFile:3.7.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.install(DirectoryWatcher.java:884) [bundleFile:3.7.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:489) [bundleFile:3.7.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [bundleFile:3.7.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [bundleFile:3.7.4]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[bundleFile:?]
	... 16 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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[bundleFile:?]
		at org.apache.karaf.features.internal.service.RepositoryImpl.<init>(RepositoryImpl.java:51) ~[bundleFile:?]
		at org.apache.karaf.features.internal.service.RepositoryCacheImpl.create(RepositoryCacheImpl.java:51) ~[bundleFile:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatureCache(FeaturesServiceImpl.java:611) [bundleFile:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.ensureCacheLoaded(FeaturesServiceImpl.java:582) [bundleFile:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getRepository(FeaturesServiceImpl.java:530) [bundleFile:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.findMissingDependency(KarServiceImpl.java:175) [bundleFile:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:126) [bundleFile:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:100) [bundleFile:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:92) [bundleFile:?]
		at org.apache.karaf.kar.internal.KarServiceImpl.install(KarServiceImpl.java:87) [bundleFile:?]
		at org.apache.karaf.deployer.kar.KarArtifactInstaller.install(KarArtifactInstaller.java:50) [bundleFile:?]
		at org.apache.felix.fileinstall.internal.DirectoryWatcher.install(DirectoryWatcher.java:950) [bundleFile:3.7.4]
		at org.apache.felix.fileinstall.internal.DirectoryWatcher.install(DirectoryWatcher.java:884) [bundleFile:3.7.4]
		at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:489) [bundleFile:3.7.4]
		at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [bundleFile:3.7.4]
		at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [bundleFile:3.7.4]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[bundleFile:?]
	... 16 more
2024-01-14 12:55:12.954 [INFO ] [nternal.manager.ShellyManagerServlet] - Shelly Manager started at http(s)://192.168.48.196:8080/shelly/manager
2024-01-14 12:55:26.913 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:55:26.926 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:55:31.413 [WARN ] [service.internal.HttpServiceDisabled] - Http service has already been stopped
2024-01-14 12:55:31.428 [WARN ] [service.spi.util.WebContainerManager] - Can't get a WebContainer service from {org.osgi.service.http.HttpService, org.ops4j.pax.web.service.WebContainer}={org.ops4j.pax.web.log.ncsa.extended=true, org.ops4j.pax.web.ssl.keystore.password=********, service.scope=bundle, org.ops4j.pax.web.ssl.key.password=********, org.ops4j.pax.web.session.cookie.name=JSESSIONID, org.osgi.service.http.connector.name=default, org.ops4j.pax.web.enc.iterationcount=1000, org.ops4j.pax.web.log.ncsa.file=yyyy_mm_dd.request.log, org.ops4j.pax.web.server.eventDispatcherThreadCount=1, org.osgi.service.http.checkForwardedHeaders=false, org.ops4j.pax.web.enc.suffix=), org.ops4j.pax.web.digestAuth.maxNonceAge=60000, org.ops4j.pax.web.ssl.ciphersuites.included=, org.ops4j.pax.web.session.url=jsessionid, org.ops4j.pax.web.formAuth.errorRedirect=false, org.ops4j.pax.web.ssl.ciphersuites.excluded=^.*_(MD5|SHA|SHA1)$,^TLS_RSA_.*$,^SSL_.*$,^.*_NULL_.*$,^.*_anon_.*, org.ops4j.pax.web.enc.prefix=ENC(, org.ops4j.pax.web.server.maxThreads=50, org.ops4j.pax.web.validatePeerCerts=false, service.id=146, org.ops4j.pax.web.session.cookie.maxAge=-1, org.ops4j.pax.web.ssl.truststore.password=********, org.ops4j.pax.web.ssl.session.cacheSize=-1, org.ops4j.pax.web.ssl.truststore.type=JKS, org.ops4j.pax.web.ssl.keystore.type=JKS, org.ops4j.pax.web.ssl.session.enabled=true, org.ops4j.pax.web.ssl.protocol=TLSv1.3, org.osgi.service.http.port=8080, org.ops4j.pax.web.log.ncsa.file.date.format=yyyy-MM-dd, org.ops4j.pax.web.ssl.renegotiationLimit=-1, org.osgi.service.http.secure.enabled=true, org.osgi.service.http.enabled=true, org.ops4j.pax.web.server.idleTimeout=300000, org.ops4j.pax.web.log.ncsa.retaindays=90, org.ops4j.pax.web.log.ncsa.logtimezone=GMT, org.ops4j.pax.web.enc.algorithm=PBEWithHmacSHA256AndAES_128, org.ops4j.pax.web.validateCerts=false, org.ops4j.pax.web.config.files=/openhab/runtime/etc/jetty.xml, org.ops4j.pax.web.ssl.renegotiationAllowed=true, org.ops4j.pax.web.digestAuth.maxNonceCount=1024, org.ops4j.pax.web.enableOCSP=false, org.ops4j.pax.web.ssl.clientauth.needed=false, org.ops4j.pax.web.enc.enabled=false, org.osgi.service.http.port.secure=8443, javax.servlet.context.tempdir=/openhab/userdata/tmp, org.ops4j.pax.web.enableCRLDP=false, org.ops4j.pax.web.server.connector.idleTimeout=30000, org.ops4j.pax.web.session.timeout=10, org.ops4j.pax.web.ssl.clientauth.wanted=false, org.ops4j.pax.web.ssl.protocols.excluded=SSL,SSLv2,SSLv2Hello,SSLv3, service.bundleid=230, org.ops4j.pax.web.server.minThreads=2, org.ops4j.pax.web.session.cookie.secure=false, org.ops4j.pax.web.enc.masterpassword=********, org.ops4j.pax.web.log.ncsa.append=true, org.osgi.service.http.secure.connector.name=secureDefault, org.ops4j.pax.web.ssl.session.timeout=-1, org.ops4j.pax.web.listening.addresses=0.0.0.0, org.ops4j.pax.web.log.ncsa.buffered=true, org.ops4j.pax.web.session.cookie.sameSite=unset, org.ops4j.pax.web.log.ncsa.enabled=false, org.ops4j.pax.web.ssl.protocols.included=, org.ops4j.pax.web.session.cookie.httpOnly=true, org.ops4j.pax.web.server.showStacks=false}
2024-01-14 12:56:46.562 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.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.listRequiredRepositories(FeaturesServiceImpl.java:514) ~[?:?]
	at org.apache.karaf.deployer.features.FeatureDeploymentListener.bundleChanged(FeatureDeploymentListener.java:247) ~[?:?]
	at org.apache.karaf.deployer.features.FeatureDeploymentListener.init(FeatureDeploymentListener.java:90) ~[?:?]
	at org.apache.karaf.deployer.features.osgi.Activator$DeploymentFinishedListener.deploymentEvent(Activator.java:86) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.registerListener(FeaturesServiceImpl.java:296) ~[?:?]
	at org.apache.karaf.deployer.features.osgi.Activator.doStart(Activator.java:53) ~[?:?]
	at org.apache.karaf.util.tracker.BaseActivator.start(BaseActivator.java:92) ~[?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl$2.run(BundleContextImpl.java:818) ~[org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl$2.run(BundleContextImpl.java:1) ~[org.eclipse.osgi-3.18.0.jar:?]
	at java.security.AccessController.doPrivileged(AccessController.java:569) [?:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.startActivator(BundleContextImpl.java:810) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.framework.BundleContextImpl.start(BundleContextImpl.java:767) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.startWorker0(EquinoxBundle.java:1032) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle$EquinoxModule.startWorker(EquinoxBundle.java:371) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.Module.doStart(Module.java:605) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.Module.start(Module.java:468) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel$2.run(ModuleContainer.java:1847) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor$1$1.execute(EquinoxContainerAdaptor.java:136) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1840) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1783) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1745) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1667) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) [org.eclipse.osgi-3.18.0.jar:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:345) [org.eclipse.osgi-3.18.0.jar:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		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.listRequiredRepositories(FeaturesServiceImpl.java:514) ~[?:?]
		at org.apache.karaf.deployer.features.FeatureDeploymentListener.bundleChanged(FeatureDeploymentListener.java:247) ~[?:?]
		at org.apache.karaf.deployer.features.FeatureDeploymentListener.init(FeatureDeploymentListener.java:90) ~[?:?]
		at org.apache.karaf.deployer.features.osgi.Activator$DeploymentFinishedListener.deploymentEvent(Activator.java:86) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.registerListener(FeaturesServiceImpl.java:296) ~[?:?]
		at org.apache.karaf.deployer.features.osgi.Activator.doStart(Activator.java:53) ~[?:?]
		at org.apache.karaf.util.tracker.BaseActivator.start(BaseActivator.java:92) ~[?:?]
		at org.eclipse.osgi.internal.framework.BundleContextImpl$2.run(BundleContextImpl.java:818) ~[org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.internal.framework.BundleContextImpl$2.run(BundleContextImpl.java:1) ~[org.eclipse.osgi-3.18.0.jar:?]
		at java.security.AccessController.doPrivileged(AccessController.java:569) [?:?]
		at org.eclipse.osgi.internal.framework.BundleContextImpl.startActivator(BundleContextImpl.java:810) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.internal.framework.BundleContextImpl.start(BundleContextImpl.java:767) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.internal.framework.EquinoxBundle.startWorker0(EquinoxBundle.java:1032) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.internal.framework.EquinoxBundle$EquinoxModule.startWorker(EquinoxBundle.java:371) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.Module.doStart(Module.java:605) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.Module.start(Module.java:468) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel$2.run(ModuleContainer.java:1847) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor$1$1.execute(EquinoxContainerAdaptor.java:136) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1840) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1783) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1745) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1667) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) [org.eclipse.osgi-3.18.0.jar:?]
		at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:345) [org.eclipse.osgi-3.18.0.jar:?]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 29 more
2024-01-14 12:56:51.085 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-14 12:56:52.071 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-14 12:56:52.074 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-14 12:56:52.907 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:56:57.165 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:56:57.273 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:56:57.282 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:57:06.094 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/events's Observer 
2024-01-14 12:57:06.126 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/suggestions's Observer 
2024-01-14 12:57:06.132 [WARN ] [.transport.servlet.ServletController] - Can't find the request for https://192.168.48.196:8443/rest/addons/services's Observer 
2024-01-14 12:57:08.880 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.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.getFeaturesById(FeaturesServiceImpl.java:645) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.refreshFeatures(FeaturesServiceImpl.java:1236) ~[?:?]
	at org.openhab.core.karaf.internal.FeatureInstaller.processConfigQueue(FeatureInstaller.java:200) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 12 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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		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.getFeaturesById(FeaturesServiceImpl.java:645) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.refreshFeatures(FeaturesServiceImpl.java:1236) ~[?:?]
		at org.openhab.core.karaf.internal.FeatureInstaller.processConfigQueue(FeatureInstaller.java:200) ~[?:?]
		at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
		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:1136) [?:?]
		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
		at java.lang.Thread.run(Thread.java:840) [?:?]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 12 more
2024-01-14 12:57:10.575 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-14 12:57:17.956 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2024-01-14 13:00:11.963 [WARN ] [g.discovery.internal.PersistentInbox] - Cannot create thing. No binding found that supports creating a thing of type shelly:shellyem3.
2024-01-14 13:00:37.679 [WARN ] [g.discovery.internal.PersistentInbox] - Cannot create thing. No binding found that supports creating a thing of type shelly:shellyem3.
2024-01-14 13:01:08.115 [WARN ] [g.discovery.internal.PersistentInbox] - Cannot create thing. No binding found that supports creating a thing of type shelly:shellyem3.
2024-01-14 13:20:30.351 [INFO ] [nternal.manager.ShellyManagerServlet] - Shelly Manager started at http(s)://192.168.48.196:8080/shelly/manager
2024-01-14 13:20:30.665 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.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.getFeaturesById(FeaturesServiceImpl.java:645) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.refreshFeatures(FeaturesServiceImpl.java:1236) ~[?:?]
	at org.openhab.core.karaf.internal.FeatureInstaller.processConfigQueue(FeatureInstaller.java:200) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 12 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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		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.getFeaturesById(FeaturesServiceImpl.java:645) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.refreshFeatures(FeaturesServiceImpl.java:1236) ~[?:?]
		at org.openhab.core.karaf.internal.FeatureInstaller.processConfigQueue(FeatureInstaller.java:200) ~[?:?]
		at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
		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:1136) [?:?]
		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
		at java.lang.Thread.run(Thread.java:840) [?:?]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 12 more
2024-01-14 13:20:30.664 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.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.getFeatures(FeaturesServiceImpl.java:577) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatures(FeaturesServiceImpl.java:568) ~[?:?]
	at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeature(FeaturesServiceImpl.java:548) ~[?:?]
	at org.openhab.core.karaf.internal.KarafAddonService.getAddon(KarafAddonService.java:112) ~[?:?]
	at org.openhab.core.io.rest.core.internal.addons.AddonResource.getById(AddonResource.java:240) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation(AbstractInvoker.java:179) ~[bundleFile:3.6.2]
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:96) ~[bundleFile:3.6.2]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:201) ~[bundleFile:3.6.2]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:104) ~[bundleFile:3.6.2]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:59) ~[bundleFile:3.6.2]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:96) ~[bundleFile:3.6.2]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:304) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.6.2]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:497) ~[bundleFile:4.0.4]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:279) ~[bundleFile:3.6.2]
	at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service(OsgiInitializedServlet.java:102) ~[bundleFile:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[bundleFile:9.4.52.v20230823]
	at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter(OsgiFilterChain.java:100) ~[bundleFile:?]
	at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle(PaxWebServletHandler.java:320) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[bundleFile:9.4.52.v20230823]
	at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle(PrioritizedHandlerCollection.java:96) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:772) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.52.v20230823]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 71 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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		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.getFeatures(FeaturesServiceImpl.java:577) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeatures(FeaturesServiceImpl.java:568) ~[?:?]
		at org.apache.karaf.features.internal.service.FeaturesServiceImpl.getFeature(FeaturesServiceImpl.java:548) ~[?:?]
		at org.openhab.core.karaf.internal.KarafAddonService.getAddon(KarafAddonService.java:112) ~[?:?]
		at org.openhab.core.io.rest.core.internal.addons.AddonResource.getById(AddonResource.java:240) ~[?:?]
		at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
		at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]
		at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
		at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
		at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation(AbstractInvoker.java:179) ~[bundleFile:3.6.2]
		at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:96) ~[bundleFile:3.6.2]
		at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:201) ~[bundleFile:3.6.2]
		at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:104) ~[bundleFile:3.6.2]
		at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:59) ~[bundleFile:3.6.2]
		at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:96) ~[bundleFile:3.6.2]
		at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:304) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.6.2]
		at javax.servlet.http.HttpServlet.service(HttpServlet.java:497) ~[bundleFile:4.0.4]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:279) ~[bundleFile:3.6.2]
		at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service(OsgiInitializedServlet.java:102) ~[bundleFile:?]
		at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[bundleFile:9.4.52.v20230823]
		at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter(OsgiFilterChain.java:100) ~[bundleFile:?]
		at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle(PaxWebServletHandler.java:320) ~[bundleFile:?]
		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[bundleFile:9.4.52.v20230823]
		at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle(PrioritizedHandlerCollection.java:96) ~[bundleFile:?]
		at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:772) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.52.v20230823]
		at java.lang.Thread.run(Thread.java:840) [?:?]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 71 more
2024-01-14 13:20:30.677 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.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.listFeatures(FeaturesServiceImpl.java:655) ~[?:?]
	at org.openhab.core.karaf.internal.KarafAddonService.getAddons(KarafAddonService.java:95) ~[?:?]
	at org.openhab.core.io.rest.core.internal.addons.AddonResource.lambda$4(AddonResource.java:415) ~[?:?]
	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:197) ~[?:?]
	at java.util.Spliterators$ArraySpliterator.tryAdvance(Spliterators.java:1002) ~[?:?]
	at java.util.stream.StreamSpliterators$WrappingSpliterator.lambda$initPartialTraversalState$0(StreamSpliterators.java:292) ~[?:?]
	at java.util.stream.StreamSpliterators$AbstractWrappingSpliterator.fillBuffer(StreamSpliterators.java:206) ~[?:?]
	at java.util.stream.StreamSpliterators$AbstractWrappingSpliterator.doAdvance(StreamSpliterators.java:161) ~[?:?]
	at java.util.stream.StreamSpliterators$WrappingSpliterator.tryAdvance(StreamSpliterators.java:298) ~[?:?]
	at java.util.Spliterators$1Adapter.hasNext(Spliterators.java:681) ~[?:?]
	at org.openhab.core.io.rest.Stream2JSONInputStream.fillBuffer(Stream2JSONInputStream.java:88) ~[?:?]
	at org.openhab.core.io.rest.Stream2JSONInputStream.read(Stream2JSONInputStream.java:67) ~[?:?]
	at java.io.InputStream.read(InputStream.java:284) ~[?:?]
	at java.io.InputStream.transferTo(InputStream.java:782) ~[?:?]
	at org.openhab.core.io.rest.core.internal.GsonMessageBodyWriter.writeTo(GsonMessageBodyWriter.java:83) ~[?:?]
	at org.openhab.core.io.rest.core.internal.MediaTypeExtension.writeTo(MediaTypeExtension.java:84) ~[?:?]
	at org.apache.cxf.jaxrs.utils.JAXRSUtils.writeMessageBody(JAXRSUtils.java:1651) ~[bundleFile:3.6.2]
	at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.serializeMessage(JAXRSOutInterceptor.java:249) ~[bundleFile:3.6.2]
	at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.processResponse(JAXRSOutInterceptor.java:122) ~[bundleFile:3.6.2]
	at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.handleMessage(JAXRSOutInterceptor.java:84) ~[bundleFile:3.6.2]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
	at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:90) ~[bundleFile:3.6.2]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:304) ~[bundleFile:3.6.2]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.6.2]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:497) ~[bundleFile:4.0.4]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:279) ~[bundleFile:3.6.2]
	at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service(OsgiInitializedServlet.java:102) ~[bundleFile:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[bundleFile:9.4.52.v20230823]
	at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter(OsgiFilterChain.java:100) ~[bundleFile:?]
	at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle(PaxWebServletHandler.java:320) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[bundleFile:9.4.52.v20230823]
	at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle(PrioritizedHandlerCollection.java:96) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:772) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.52.v20230823]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.IOException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 78 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:4.2.0-SNAPSHOT
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:421) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
		at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
		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:1161) ~[?:?]
		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.listFeatures(FeaturesServiceImpl.java:655) ~[?:?]
		at org.openhab.core.karaf.internal.KarafAddonService.getAddons(KarafAddonService.java:95) ~[?:?]
		at org.openhab.core.io.rest.core.internal.addons.AddonResource.lambda$4(AddonResource.java:415) ~[?:?]
		at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:197) ~[?:?]
		at java.util.Spliterators$ArraySpliterator.tryAdvance(Spliterators.java:1002) ~[?:?]
		at java.util.stream.StreamSpliterators$WrappingSpliterator.lambda$initPartialTraversalState$0(StreamSpliterators.java:292) ~[?:?]
		at java.util.stream.StreamSpliterators$AbstractWrappingSpliterator.fillBuffer(StreamSpliterators.java:206) ~[?:?]
		at java.util.stream.StreamSpliterators$AbstractWrappingSpliterator.doAdvance(StreamSpliterators.java:161) ~[?:?]
		at java.util.stream.StreamSpliterators$WrappingSpliterator.tryAdvance(StreamSpliterators.java:298) ~[?:?]
		at java.util.Spliterators$1Adapter.hasNext(Spliterators.java:681) ~[?:?]
		at org.openhab.core.io.rest.Stream2JSONInputStream.fillBuffer(Stream2JSONInputStream.java:88) ~[?:?]
		at org.openhab.core.io.rest.Stream2JSONInputStream.read(Stream2JSONInputStream.java:67) ~[?:?]
		at java.io.InputStream.read(InputStream.java:284) ~[?:?]
		at java.io.InputStream.transferTo(InputStream.java:782) ~[?:?]
		at org.openhab.core.io.rest.core.internal.GsonMessageBodyWriter.writeTo(GsonMessageBodyWriter.java:83) ~[?:?]
		at org.openhab.core.io.rest.core.internal.MediaTypeExtension.writeTo(MediaTypeExtension.java:84) ~[?:?]
		at org.apache.cxf.jaxrs.utils.JAXRSUtils.writeMessageBody(JAXRSUtils.java:1651) ~[bundleFile:3.6.2]
		at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.serializeMessage(JAXRSOutInterceptor.java:249) ~[bundleFile:3.6.2]
		at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.processResponse(JAXRSOutInterceptor.java:122) ~[bundleFile:3.6.2]
		at org.apache.cxf.jaxrs.interceptor.JAXRSOutInterceptor.handleMessage(JAXRSOutInterceptor.java:84) ~[bundleFile:3.6.2]
		at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
		at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:90) ~[bundleFile:3.6.2]
		at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:304) ~[bundleFile:3.6.2]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.6.2]
		at javax.servlet.http.HttpServlet.service(HttpServlet.java:497) ~[bundleFile:4.0.4]
		at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:279) ~[bundleFile:3.6.2]
		at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service(OsgiInitializedServlet.java:102) ~[bundleFile:?]
		at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[bundleFile:9.4.52.v20230823]
		at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter(OsgiFilterChain.java:100) ~[bundleFile:?]
		at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle(PaxWebServletHandler.java:320) ~[bundleFile:?]
		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[bundleFile:9.4.52.v20230823]
		at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle(PrioritizedHandlerCollection.java:96) ~[bundleFile:?]
		at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:772) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.52.v20230823]
		at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.52.v20230823]
		at java.lang.Thread.run(Thread.java:840) [?:?]
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:4.2.0-SNAPSHOT
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolve(DefaultArtifactResolver.java:431) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifacts(DefaultArtifactResolver.java:235) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultArtifactResolver.resolveArtifact(DefaultArtifactResolver.java:212) ~[?:?]
	at shaded.org.eclipse.aether.internal.impl.DefaultRepositorySystem.resolveArtifact(DefaultRepositorySystem.java:272) ~[?:?]
	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:1161) ~[?:?]
	at org.apache.karaf.features.internal.service.RepositoryImpl.load(RepositoryImpl.java:114) ~[?:?]
	... 78 more

beanz · January 17, 2024, 9:01pm

Hi Markus

Gave it another try, did the stuff again … unfortunately with the same results. Looks really that there is something wrong with the loaded addon.
Digging further down in the log and restarting several times, I got a startup sequence which doesn’t complain about a missing feature:

2024-01-16 22:13:40.970 [WARN ] [internal.service.FeaturesServiceImpl] - Can't load features repository mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features
java.lang.RuntimeException: Error resolving artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT: [Could not find artifact org.openhab.core.features.karaf:org.openhab.core.features.karaf.openhab-core:xml:features:4.2.0-SNAPSHOT] : mvn:org.openhab.core.features.karaf/org.openhab.core.features.karaf.openhab-core/4.2.0-SNAPSHOT/xml/features

Or do I have to work with a OH 4.2-SNAPSHOT?

Here’s the startup sequence, shortened in between but at the end you’ll see a problem with the new N current channel:
Channel definition for meter#ncurrent not found!

2024-01-16 22:13:46.552 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.1.1 (build Release Build)
2024-01-16 22:13:47.226 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Zurich'.
2024-01-16 22:13:47.229 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_CH'.
2024-01-16 22:14:01.843 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2024-01-16 22:14:06.877 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.881 [DEBUG] [elly.internal.api.ShellyEventServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.api.ShellyEventServlet] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.885 [DEBUG] [discovery.ShellyDiscoveryParticipant] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.discovery.ShellyDiscoveryParticipant] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.889 [DEBUG] [ly.internal.handler.ShellyThingTable] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.handler.ShellyThingTable] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.895 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.899 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.903 [DEBUG] [vider.ShellyStateDescriptionProvider] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyStateDescriptionProvider] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.906 [DEBUG] [l.provider.ShellyTranslationProvider] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider] : BundleComponentActivator : ComponentHolder created.
2024-01-16 22:14:06.911 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : ConfigurableComponentHolder configuration updated for pid binding.shelly with change count 2
2024-01-16 22:14:06.918 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.net.NetworkAddressService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2024-01-16 22:14:06.923 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Dependency Manager created $001interface=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2024-01-16 22:14:06.924 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Dependency Manager created $002interface=org.openhab.binding.shelly.internal.handler.ShellyThingTable, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=2
2024-01-16 22:14:06.925 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Dependency Manager created $003interface=org.openhab.core.io.net.http.HttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=3
2024-01-16 22:14:06.927 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-01-16 22:14:06.929 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.shelly.internal.ShellyHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.shelly]
2024-01-16 22:14:06.931 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory, org.openhab.binding.shelly.internal.ShellyHandlerFactory]
2024-01-16 22:14:06.932 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-01-16 22:14:06.934 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Querying state disabled
2024-01-16 22:14:06.935 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Querying state disabled
2024-01-16 22:14:06.936 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Component can not be activated since it is in state disabled
2024-01-16 22:14:06.937 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory] : Querying state disabled
2024-01-16 22:14:06.938 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Updating target filters
2024-01-16 22:14:06.940 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : No change in target property for dependency $000: currently registered: false
2024-01-16 22:14:06.941 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] :  No existing service listener to unregister for dependency $000
2024-01-16 22:14:06.942 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Setting target property for dependency $000 to null
2024-01-16 22:14:06.943 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.net.NetworkAddressService), initialReferenceFilter (objectClass=org.openhab.core.net.NetworkAddressService)
2024-01-16 22:14:06.944 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $000 tracker reset (closed)
2024-01-16 22:14:06.946 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=197, service.bundleid=154, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=35} (enter)
2024-01-16 22:14:06.947 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2024-01-16 22:14:06.949 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=197, service.bundleid=154, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=35} (exit)
2024-01-16 22:14:06.950 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $000 tracker opened
2024-01-16 22:14:06.951 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : registering service listener for dependency $000
2024-01-16 22:14:06.953 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : No change in target property for dependency $001: currently registered: false
2024-01-16 22:14:06.954 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] :  No existing service listener to unregister for dependency $001
2024-01-16 22:14:06.955 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Setting target property for dependency $001 to null
2024-01-16 22:14:06.956 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider), initialReferenceFilter (objectClass=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider)
2024-01-16 22:14:06.960 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $001 tracker reset (closed)
2024-01-16 22:14:06.962 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $001 tracker opened
2024-01-16 22:14:06.963 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : registering service listener for dependency $001
2024-01-16 22:14:06.965 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : No change in target property for dependency $002: currently registered: false
2024-01-16 22:14:06.966 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] :  No existing service listener to unregister for dependency $002
2024-01-16 22:14:06.967 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Setting target property for dependency $002 to null
2024-01-16 22:14:06.980 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.shelly.internal.handler.ShellyThingTable), initialReferenceFilter (objectClass=org.openhab.binding.shelly.internal.handler.ShellyThingTable)
2024-01-16 22:14:06.982 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $002 tracker reset (closed)
2024-01-16 22:14:06.983 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $002 tracker opened
2024-01-16 22:14:06.984 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : registering service listener for dependency $002
2024-01-16 22:14:06.985 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : No change in target property for dependency $003: currently registered: false
2024-01-16 22:14:06.987 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] :  No existing service listener to unregister for dependency $003
2024-01-16 22:14:06.988 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Setting target property for dependency $003 to null
2024-01-16 22:14:06.989 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : New service tracker for $003, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)
2024-01-16 22:14:06.990 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $003 tracker reset (closed)
2024-01-16 22:14:06.992 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $003 tracking 2 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=288, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=153} (enter)
2024-01-16 22:14:06.993 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $003 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2024-01-16 22:14:06.995 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $003 tracking 2 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=288, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=153} (exit)
2024-01-16 22:14:06.996 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm $003 tracker opened
2024-01-16 22:14:06.997 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : registering service listener for dependency $003
2024-01-16 22:14:06.998 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-01-16 22:14:06.999 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-01-16 22:14:07.001 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-01-16 22:14:07.002 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-01-16 22:14:07.004 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-01-16 22:14:07.005 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-01-16 22:14:07.007 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : dm osgi.ds.satisfying.condition tracker opened
2024-01-16 22:14:07.008 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-01-16 22:14:07.009 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Changed state from disabled to unsatisfiedReference
2024-01-16 22:14:07.010 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Component enabled
2024-01-16 22:14:07.024 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : ActivateInternal
2024-01-16 22:14:07.029 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Querying state unsatisfiedReference
2024-01-16 22:14:07.035 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Querying state unsatisfiedReference
2024-01-16 22:14:07.039 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Activating component from state unsatisfiedReference
2024-01-16 22:14:07.043 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Querying state unsatisfiedReference
2024-01-16 22:14:07.045 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Querying state unsatisfiedReference
2024-01-16 22:14:07.047 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Dependency not satisfied: $001
2024-01-16 22:14:07.048 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Dependency not satisfied: $002
2024-01-16 22:14:07.049 [DEBUG] [shelly.internal.ShellyHandlerFactory] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.ShellyHandlerFactory(318)] : Not all dependencies satisfied, cannot activate
...
...
2024-01-16 22:14:09.249 [DEBUG] [nternal.manager.ShellyManagerServlet] - ShellyManagerServlet stopped
2024-01-16 22:14:09.250 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : invoked deactivate: deactivate
2024-01-16 22:14:09.251 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 4 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-01-16 22:14:09.252 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: $004 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 6 refpairs: [[RefPair: ref: [{org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=493, service.bundleid=264, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=325}] service: [org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider@5c7e1dd7]]]
2024-01-16 22:14:09.253 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: $003 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 5 refpairs: [[RefPair: ref: [{org.openhab.core.thing.binding.ThingHandlerFactory, org.openhab.binding.shelly.internal.ShellyHandlerFactory}={service.id=494, service.bundleid=264, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), service.pid=binding.shelly, component.name=org.openhab.binding.shelly.internal.ShellyHandlerFactory, component.id=318}] service: [org.openhab.binding.shelly.internal.ShellyHandlerFactory@17d01b0d]]]
2024-01-16 22:14:09.255 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: $002 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 3 refpairs: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=288, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=153}] service: [org.openhab.core.io.net.http.internal.WebClientFactoryImpl@58ec1b23]]]
2024-01-16 22:14:09.256 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 2 refpairs: [[RefPair: ref: [{org.openhab.core.net.NetworkAddressService}={service.id=197, service.bundleid=154, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=35}] service: [org.openhab.core.net.NetUtil@60b8bc98]]]
2024-01-16 22:14:09.257 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@34172dcc at tracking count 1 refpairs: [[RefPair: ref: [{org.osgi.service.cm.ConfigurationAdmin}={osgi.command.scope=cm, service.description=Configuration Admin Service Specification 1.6 Implementation, service.id=40, service.bundleid=12, service.scope=bundle, osgi.command.function=[getConfiguration,getFactoryConfiguration,listConfigurations,createFactoryConfiguration], service.vendor=The Apache Software Foundation, config.plugins=, service.pid=org.apache.felix.cm.ConfigurationAdmin}] service: [org.apache.felix.cm.impl.ConfigurationAdminImpl@6dcc840e]]]
2024-01-16 22:14:09.258 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2024-01-16 22:14:09.259 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : Changed state from active to satisfied
2024-01-16 22:14:09.268 [DEBUG] [nternal.manager.ShellyManagerServlet] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.manager.ShellyManagerServlet(322)] : dm $004 tracking 6 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=493, service.bundleid=264, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=325} (exit)
2024-01-16 22:14:09.269 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : dm $000 tracking 2 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=493, service.bundleid=264, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=325} (enter)
2024-01-16 22:14:09.270 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : ActivateInternal
2024-01-16 22:14:09.271 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Querying state unsatisfiedReference
2024-01-16 22:14:09.271 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Querying state unsatisfiedReference
2024-01-16 22:14:09.272 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Activating component from state unsatisfiedReference
2024-01-16 22:14:09.273 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Querying state unsatisfiedReference
2024-01-16 22:14:09.274 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Querying state unsatisfiedReference
2024-01-16 22:14:09.275 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : Changed state from unsatisfiedReference to satisfied
2024-01-16 22:14:09.276 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : registration change queue [registered]
2024-01-16 22:14:09.278 [DEBUG] [al.provider.ShellyChannelDefinitions] - bundle org.openhab.binding.shelly:4.2.0.202401091944 (264)[org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions(323)] : dm $000 tracking 2 SingleStatic added {org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider}={service.id=493, service.bundleid=264, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.shelly.internal.provider.ShellyTranslationProvider, component.id=325} (exit)
2024-01-16 22:14:09.665 [DEBUG] [shelly.internal.ShellyHandlerFactory] - Mains Power (shellyem3-244cab4359da@192.168.48.21): Create new thing of type shelly:shellyem3 using ShellyRelayHandler
2024-01-16 22:14:09.746 [DEBUG] [shelly.internal.ShellyHandlerFactory] - Thing handler for uid shelly:shellyem3:244cab4359da added, total things = 1
2024-01-16 22:14:11.794 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Using default userId admin from binding config
2024-01-16 22:14:11.795 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Using default password from bindingConfig (userId=admin)
2024-01-16 22:14:11.796 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Device config: Device address=192.168.48.21, HTTP user/password=admin/***, update interval=60
2024-01-16 22:14:11.796 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Configured Events: Button: false, Switch (on/off): false, Push: false, Roller: true, Sensor: true, CoIoT: true, Enable AutoCoIoT: true
2024-01-16 22:14:11.797 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Start initializing for thing Mains Power (shellyem3-244cab4359da@192.168.48.21), type shellyem3, IP address 192.168.48.21, Gen2: false, CoIoT: true
2024-01-16 22:14:12.282 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Auto-CoIoT is enabled, disabling action urls
2024-01-16 22:14:12.282 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Starting CoIoT (autoCoIoT=true/true)
2024-01-16 22:14:12.283 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Starting CoAP Listener
2024-01-16 22:14:12.284 [DEBUG] [elly.internal.api1.Shelly1CoapServer] - Initializing CoIoT listener (local IP=192.168.48.196:5683)
2024-01-16 22:14:12.414 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Using CoAP device description from successful HTTP /cit/d
2024-01-16 22:14:12.415 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Device Description for shellyem3-244cab4359da: {"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":6,"T":"I","D":"neutralCurrent","U":"A","L":5},{"I":6102,"T":"A","D":"overpower","R":["0/1","-1"],"L":1}]}
2024-01-16 22:14:12.418 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=1: relay_0
2024-01-16 22:14:12.419 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=2: emeter_0
2024-01-16 22:14:12.420 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=3: emeter_1
2024-01-16 22:14:12.421 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=4: emeter_2
2024-01-16 22:14:12.422 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id=5: device
2024-01-16 22:14:12.424 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Adding 22 sensor definitions
2024-01-16 22:14:12.426 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 9103: cfgChanged, Type=EVC, Range=U16, Links=5
2024-01-16 22:14:12.428 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 1101: output, Type=S, Range=0/1, Links=1
2024-01-16 22:14:12.429 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4105: power, Type=P, Range=null, Links=2
2024-01-16 22:14:12.430 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4106: energy, Type=E, Range=null, Links=2
2024-01-16 22:14:12.431 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4107: energyReturned, Type=E, Range=null, Links=2
2024-01-16 22:14:12.432 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4108: voltage, Type=V, Range=null, Links=2
2024-01-16 22:14:12.433 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4109: current, Type=I, Range=null, Links=2
2024-01-16 22:14:12.434 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4110: powerFactor, Type=S, Range=0/1;-1, Links=2
2024-01-16 22:14:12.435 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4205: power, Type=P, Range=null, Links=3
2024-01-16 22:14:12.436 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4206: energy, Type=E, Range=null, Links=3
2024-01-16 22:14:12.437 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4207: energyReturned, Type=E, Range=null, Links=3
2024-01-16 22:14:12.438 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4208: voltage, Type=V, Range=null, Links=3
2024-01-16 22:14:12.439 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4209: current, Type=I, Range=null, Links=3
2024-01-16 22:14:12.440 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4210: powerFactor, Type=S, Range=0/1;-1, Links=3
2024-01-16 22:14:12.441 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4305: power, Type=P, Range=null, Links=4
2024-01-16 22:14:12.442 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4306: energy, Type=E, Range=null, Links=4
2024-01-16 22:14:12.443 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4307: energyReturned, Type=E, Range=null, Links=4
2024-01-16 22:14:12.444 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4308: voltage, Type=V, Range=null, Links=4
2024-01-16 22:14:12.445 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4309: current, Type=I, Range=null, Links=4
2024-01-16 22:14:12.446 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 4310: powerFactor, Type=S, Range=0/1;-1, Links=4
2024-01-16 22:14:12.447 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6: neutralCurrent, Type=I, Range=null, Links=5
2024-01-16 22:14:12.448 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Invalid format for sensor defition detected, id=6
2024-01-16 22:14:12.449 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da:    id 6102: overpower, Type=A, Range=0/1;-1, Links=1
2024-01-16 22:14:12.450 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Incompatible device description detected for CoIoT version 2 (id length mismatch), discarding!
2024-01-16 22:14:12.466 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Initializing device shelly-4359D, type SHEM-3, Hardware: Rev: dev-prototype, batch 0; Firmware: 1.14.0- / 20230913
2024-01-16 22:14:12.467 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Shelly settings info for shelly-4359D: {"device":{"type":"SHEM-3","mac":"244CAB4359DA","hostname":"shellyem3-244CAB4359DA","num_outputs":1,"num_meters":0,"num_emeters":3,"report_period":1},"wifi_ap":{"enabled":false,"ssid":"shellyem3-244CAB4359DA","key":""},"wifi_sta":{"enabled":true,"ssid":"beanzIOT","ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"wifi_sta1":{"enabled":false,"ssid":null,"ipv4_method":"dhcp","ip":null,"gw":null,"mask":null,"dns":null},"ap_roaming":{"enabled":true,"threshold":-70},"mqtt": {"enable":false,"server":"192.168.33.3:1883","user":"","id":"shellyem3-244CAB4359DA","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":"Mains Power","fw":"20230913-114244/v1.14.0-gcb84623","pon_wifi_reset":false,"discoverable":false,"build_info":{"build_id":"20230913-114244/v1.14.0-gcb84623","build_timestamp":"2023-09-13T11:42:44Z","build_version":"1.0"},"cloud":{"enabled":false,"connected":false},"timezone":"Europe/Zurich","lat":47.490700,"lng":8.738800,"tzautodetect":true,"tz_utc_offset":3600,"tz_dst":false,"tz_dst_auto":true,"time":"23:14","unixtime":1705443250,"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":"dev-prototype", "batch_id":0},"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":"Phase 1","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 2","appliance_type":"General","max_power":0,"range_extender":1},{"name":"Phase 3","appliance_type":"General","max_power":0,"range_extender":1}],"emeter_n":{"range_extender":1,"mismatch_threshold":0.00},"eco_mode_enabled":true}
2024-01-16 22:14:12.468 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: 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, BLU Gateway support: true,alwaysOn:70, updatePeriod:falsesec
2024-01-16 22:14:12.469 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Thing successfully initialized.
2024-01-16 22:14:12.502 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Update status job started, interval=20*3=60sec.
2024-01-16 22:14:14.801 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Unable to refresh status: Unable to update status
java.lang.IllegalArgumentException: Channel definition for meter#ncurrent not found!
	at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions$ChannelMap.get(ShellyChannelDefinitions.java:789) ~[?:?]
	at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions.getDefinition(ShellyChannelDefinitions.java:297) ~[?:?]
	at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions.addChannel(ShellyChannelDefinitions.java:603) ~[?:?]
	at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions.createEMNCurrentChannels(ShellyChannelDefinitions.java:508) ~[?:?]
	at org.openhab.binding.shelly.internal.handler.ShellyComponents.updateMeters(ShellyComponents.java:269) ~[?:?]
	at org.openhab.binding.shelly.internal.handler.ShellyBaseHandler.refreshStatus(ShellyBaseHandler.java:572) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-16 22:14:15.058 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=32880): {"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]}
2024-01-16 22:14:15.060 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Version 2 detected
2024-01-16 22:14:15.061 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]} (serial=61952)
2024-01-16 22:14:15.063 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-16 22:14:15.074 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]}
2024-01-16 22:14:15.077 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 17 channels updated from CoIoT status, serial=61952
2024-01-16 22:14:15.116 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=10689): {"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]}
2024-01-16 22:14:15.118 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]} (serial=61952)
2024-01-16 22:14:15.119 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-16 22:14:15.123 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,27.71],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.83],[0,4109,0.31],[0,4110,0.40],[0,4205,84.33],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,225.30],[0,4209,0.58],[0,4210,0.66],[0,4305,271.95],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.24],[0,4309,1.52],[0,4310,0.79],[0,6,1.27],[0,6102,0]]}
2024-01-16 22:14:15.125 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 17 channels updated from CoIoT status, serial=61952
2024-01-16 22:14:17.083 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Message from /192.168.48.21:5683 (MID=32881): {"G":[[0,9103,0],[0,1101,0],[0,4105,27.54],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.66],[0,4109,0.31],[0,4110,0.39],[0,4205,86.99],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,224.75],[0,4209,0.56],[0,4210,0.69],[0,4305,266.08],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.13],[0,4309,1.49],[0,4310,0.78],[0,6,1.25],[0,6102,0]]}
2024-01-16 22:14:17.085 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: CoIoT Sensor data {"G":[[0,9103,0],[0,1101,0],[0,4105,27.54],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.66],[0,4109,0.31],[0,4110,0.39],[0,4205,86.99],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,224.75],[0,4209,0.56],[0,4210,0.69],[0,4305,266.08],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.13],[0,4309,1.49],[0,4310,0.78],[0,6,1.25],[0,6102,0]]} (serial=62208)
2024-01-16 22:14:17.086 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 22 CoAP sensor updates received
2024-01-16 22:14:17.092 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: Unable to sensor definition for id=6, payload={"G":[[0,9103,0],[0,1101,0],[0,4105,27.54],[0,4106,1835754.4],[0,4107,323446.6],[0,4108,224.66],[0,4109,0.31],[0,4110,0.39],[0,4205,86.99],[0,4206,2127395.0],[0,4207,241639.1],[0,4208,224.75],[0,4209,0.56],[0,4210,0.69],[0,4305,266.08],[0,4306,2559975.1],[0,4307,274044.7],[0,4308,227.13],[0,4309,1.49],[0,4310,0.78],[0,6,1.25],[0,6102,0]]}
2024-01-16 22:14:17.094 [DEBUG] [lly.internal.api1.Shelly1CoapHandler] - shellyem3-244cab4359da: 17 channels updated from CoIoT status, serial=62208
2024-01-16 22:14:17.998 [DEBUG] [y.internal.handler.ShellyBaseHandler] - shellyem3-244cab4359da: Unable to refresh status: Unable to update status
java.lang.IllegalArgumentException: Channel definition for meter#ncurrent not found!
	at org.openhab.binding.shelly.internal.provider.ShellyChannelDefinitions$ChannelMap.get(ShellyChannelDefinitions.java:789) ~[?:?]

markus7017 · January 18, 2024, 10:53pm

is that with the latest binding?