openHAB 5.0 Release Discussion

Setup, Configuration and Use News & Important Changes
340

After more testing, I’m still unable to get a manual installation of openHAB 5.0.2 working with Ubuntu 24.04.3. I’ve checked the journalctl logs for the openhab.service and found the following error which cause the error messages in the openhab.log file which I previously posted.

Launching the openHAB runtime…
Oct 23 19:11:52 openhab start.sh[20672]: [engine] WARNING: The Truffle API JAR is missing the ‘truffleattach’ resource, likely due to issues when Truffle was repackaged i>
Oct 23 19:11:52 openhab start.sh[20672]: As a result, the optimized Truffle runtime is unavailable, and Truffle cannot provide native access to languages and tools.
Oct 23 19:11:52 openhab start.sh[20672]: To customize the behavior of this warning, use the ‘polyglotimpl.AttachLibraryFailureAction’ system property.
Oct 23 19:11:52 openhab start.sh[20672]: Allowed values are:
Oct 23 19:11:52 openhab start.sh[20672]: - ignore: Do not print this warning.
Oct 23 19:11:52 openhab start.sh[20672]: - warn: Print this warning (default value).
Oct 23 19:11:52 openhab start.sh[20672]: - diagnose: Print this warning along with the exception cause.
Oct 23 19:11:52 openhab start.sh[20672]: - throw: Throw an exception instead of printing this warning.

I did a SNAP install and this seems to work, however it’s not my preferred installation method.

Hopefully someone is able to work out the truffle error.

Thanks

341

What JDK 21 did you install? Was it standard OpenJDK?

342

yes, openJDK 21.0.8 2025-07-15

343

I don’t have much to contribute to this, but I’ve struggled with some Graal errors in the past. My impression is that if anything is “non-standard”, it can go wrong. I even tried using GraalVM as the JRE, and that too failed. So, it seems to me like the way Graal is packaged as a dependency makes some assumptions about the installation, and that it fails if you’re “outside those assumptions”.

Just to make sure, you are trying to start Karaf, not the “demo app”? Because, Graal doesn’t work from the “demo app” at all.

344

Perfekt, diesen Sceen sollte man in die Doku mit aufnehmen!!

345

I’m not using the demo app. What I have now found is that if I do a manual installation (5.0.2) on Ubuntu 22.04 there is no issue, however installing on Ubuntu 24.04 produces the error everytime, so maybe for now I stay on Ubuntu 22.04 and hope the developers address this in coming releases.

1 Like
346

It’s there:

The Z-Wave JS instance has the Webservice enabled (Settings → Home Assistant → WS Server).

347

Suddenly (I assume since the update to 5.0.2) the startup logs have become very chatty:

2025-10-28 08:30:07.614 [INFO ] [port.EventAdminConfigurationNotifier] - Sending Event Admin notification (configuration successful) to org/ops4j/pax/logging/Configuration
2025-10-28 08:30:08.778 [INFO ] [raf.deployer.features.osgi.Activator] - Deployment finished. Registering FeatureDeploymentListener
2025-10-28 08:30:09.247 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.jaas.command/4.4.7
2025-10-28 08:30:09.249 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.4.7
2025-10-28 08:30:09.250 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.4.7
2025-10-28 08:30:09.259 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.shell.commands/4.4.7
2025-10-28 08:30:09.260 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.shell.commands/4.4.7
2025-10-28 08:30:09.260 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.deployer.kar/4.4.7
2025-10-28 08:30:09.263 [INFO ] [af.shell.impl.console.osgi.Activator] - Not starting local console. To activate set karaf.startLocalConsole=true
2025-10-28 08:30:09.273 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.wrapper.core/4.4.7
2025-10-28 08:30:09.290 [INFO ] [ROOT                                ] - bundle org.apache.felix.scr:2.2.6 (74) Starting with globalExtender setting: false
2025-10-28 08:30:09.294 [INFO ] [ROOT                                ] - bundle org.apache.felix.scr:2.2.6 (74)  Version = 2.2.6
2025-10-28 08:30:09.329 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.bundle.core/4.4.7
2025-10-28 08:30:09.341 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.config.command/4.4.7
2025-10-28 08:30:09.429 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.diagnostic.core/4.4.7
2025-10-28 08:30:09.444 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.features.command/4.4.7
2025-10-28 08:30:09.464 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.instance.core/4.4.7
2025-10-28 08:30:09.474 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.kar.core/4.4.7
2025-10-28 08:30:09.488 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.log.core/4.4.7
2025-10-28 08:30:09.502 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.package.core/4.4.7
2025-10-28 08:30:09.578 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.service.core/4.4.7
2025-10-28 08:30:09.614 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.shell.ssh/4.4.7. Missing service: [org.apache.sshd.server.SshServer]
2025-10-28 08:30:09.634 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.system.core/4.4.7
2025-10-28 08:30:09.656 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.alpn.java.client.JDK9ClientALPNProcessor of service org.eclipse.jetty.io.ssl.ALPNProcessor$Client in bundle org.eclipse.jetty.alpn.java.client
2025-10-28 08:30:09.664 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.alpn.java.server.JDK9ServerALPNProcessor of service org.eclipse.jetty.io.ssl.ALPNProcessor$Server in bundle org.eclipse.jetty.alpn.java.server
2025-10-28 08:30:09.714 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.http.Http1FieldPreEncoder of service org.eclipse.jetty.http.HttpFieldPreEncoder in bundle org.eclipse.jetty.http
2025-10-28 08:30:09.726 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.http2.hpack.HpackFieldPreEncoder of service org.eclipse.jetty.http.HttpFieldPreEncoder in bundle org.eclipse.jetty.http2.hpack
2025-10-28 08:30:09.735 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.security.openid.OpenIdAuthenticatorFactory of service org.eclipse.jetty.security.Authenticator$Factory in bundle org.eclipse.jetty.openid
2025-10-28 08:30:09.760 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.common.extensions.identity.IdentityExtension of service org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2025-10-28 08:30:09.762 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.common.extensions.fragment.FragmentExtension of service org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2025-10-28 08:30:09.764 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.common.extensions.compress.PerMessageDeflateExtension of service org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2025-10-28 08:30:09.765 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.common.extensions.compress.DeflateFrameExtension of service org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2025-10-28 08:30:09.767 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.common.extensions.compress.XWebkitDeflateFrameExtension of service org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2025-10-28 08:30:09.771 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.jsr356.JettyClientContainerProvider of service javax.websocket.ContainerProvider in bundle org.eclipse.jetty.websocket.javax.websocket
2025-10-28 08:30:09.780 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.jsr356.server.ContainerDefaultConfigurator of service javax.websocket.server.ServerEndpointConfig$Configurator in bundle org.eclipse.jetty.websocket.javax.websocket.server
2025-10-28 08:30:09.782 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.jsr356.server.deploy.WebSocketServerContainerInitializer of service javax.servlet.ServletContainerInitializer in bundle org.eclipse.jetty.websocket.javax.websocket.server
2025-10-28 08:30:09.786 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider org.eclipse.jetty.websocket.server.NativeWebSocketServletContainerInitializer of service javax.servlet.ServletContainerInitializer in bundle org.eclipse.jetty.websocket.server
2025-10-28 08:30:09.792 [INFO ] [tender.whiteboard.internal.Activator] - Starting Pax Web Whiteboard Extender
2025-10-28 08:30:09.893 [INFO ] [org.eclipse.jetty.util.log          ] - Logging initialized @5047ms to org.eclipse.jetty.util.log.Slf4jLog
2025-10-28 08:30:09.998 [INFO ] [j.pax.web.service.internal.Activator] - Scheduling Pax Web reconfiguration because configuration has changed
2025-10-28 08:30:09.998 [INFO ] [j.pax.web.service.internal.Activator] - EventAdmin support enabled, WAB events will be posted to EventAdmin topics.
2025-10-28 08:30:09.999 [INFO ] [j.pax.web.service.internal.Activator] - Pax Web Runtime started
2025-10-28 08:30:10.004 [INFO ] [j.pax.web.service.internal.Activator] - Scheduling Pax Web reconfiguration because ServerControllerFactory has been registered
2025-10-28 08:30:10.024 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.truffle.js.lang.JavaScriptLanguageProvider of service com.oracle.truffle.api.provider.TruffleLanguageProvider in bundle org.graalvm.js.js-language
2025-10-28 08:30:10.037 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.graal.python.PythonLanguageProvider of service com.oracle.truffle.api.provider.TruffleLanguageProvider in bundle org.graalvm.python.python-language
2025-10-28 08:30:10.046 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.graal.python.resources.PythonResourceProvider of service com.oracle.truffle.api.provider.InternalResourceProvider in bundle org.graalvm.python.python-resources
2025-10-28 08:30:10.054 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.truffle.regex.RegexLanguageProvider of service com.oracle.truffle.api.provider.TruffleLanguageProvider in bundle org.graalvm.regex.regex
2025-10-28 08:30:10.094 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.truffle.polyglot.PolyglotImpl of service org.graalvm.polyglot.impl.AbstractPolyglotImpl in bundle org.graalvm.truffle.truffle-api
2025-10-28 08:30:10.120 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.shell.ssh/4.4.7
2025-10-28 08:30:10.141 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.stax.WstxEventFactory of service javax.xml.stream.XMLEventFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.145 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.stax.WstxInputFactory of service javax.xml.stream.XMLInputFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.150 [INFO ] [j.pax.web.service.internal.Activator] - Configuring server controller org.ops4j.pax.web.service.jetty.internal.JettyServerController
2025-10-28 08:30:10.150 [INFO ] [jetty.internal.JettyServerController] - Configuring JettyServerController{configuration=97fb286e-eadf-48c0-8855-3823c5b9222e,state=UNCONFIGURED}
2025-10-28 08:30:10.151 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.dtd.DTDSchemaFactory of service org.codehaus.stax2.validation.XMLValidationSchemaFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.151 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Creating Jetty server instance using configuration properties.
2025-10-28 08:30:10.154 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.msv.RelaxNGSchemaFactory of service org.codehaus.stax2.validation.XMLValidationSchemaFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.157 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.msv.W3CSchemaFactory of service org.codehaus.stax2.validation.XMLValidationSchemaFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.162 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.ctc.wstx.stax.WstxOutputFactory of service javax.xml.stream.XMLOutputFactory in bundle com.fasterxml.woodstox.woodstox-core
2025-10-28 08:30:10.180 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider de.focus_shift.jollyday.jackson.JacksonConfigurationService of service de.focus_shift.jollyday.core.spi.ConfigurationService in bundle de.focus_shift.jollyday-jackson
2025-10-28 08:30:10.210 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Processing Jetty configuration from files: [/usr/share/openhab/runtime/etc/jetty.xml]
2025-10-28 08:30:10.423 [INFO ] [org.apache.cxf.bus.osgi.CXFActivator] - Adding the extensions from bundle org.apache.cxf.cxf-rt-frontend-jaxrs (69) [org.apache.cxf.jaxrs.JAXRSBindingFactory]
2025-10-28 08:30:10.425 [INFO ] [org.apache.cxf.bus.osgi.CXFActivator] - Adding the extensions from bundle org.apache.cxf.cxf-rt-rs-sse (71) [org.apache.cxf.transport.sse.SseProvidersExtension]
2025-10-28 08:30:10.428 [INFO ] [org.apache.cxf.bus.osgi.CXFActivator] - Adding the extensions from bundle org.apache.cxf.cxf-rt-transports-http (73) [org.apache.cxf.transport.http.HTTPTransportFactory, org.apache.cxf.transport.http.HTTPWSDLExtensionLoader, org.apache.cxf.transport.http.policy.HTTPClientAssertionBuilder, org.apache.cxf.transport.http.policy.HTTPServerAssertionBuilder, org.apache.cxf.transport.http.policy.NoOpPolicyInterceptorProvider]
2025-10-28 08:30:10.484 [INFO ] [blueprint.NamespaceHandlerRegisterer] - Aries Blueprint packages not available. So namespaces will not be registered
2025-10-28 08:30:10.492 [INFO ] [blueprint.NamespaceHandlerRegisterer] - Aries Blueprint packages not available. So namespaces will not be registered
2025-10-28 08:30:10.497 [INFO ] [blueprint.NamespaceHandlerRegisterer] - Aries Blueprint packages not available. So namespaces will not be registered
2025-10-28 08:30:10.565 [INFO ] [rd.activator.CxfJaxrsBundleActivator] - Registered RuntimeDelegate
2025-10-28 08:30:10.570 [INFO ] [rd.activator.CxfJaxrsBundleActivator] - Registered ClientBuilder
2025-10-28 08:30:10.571 [INFO ] [rd.activator.CxfJaxrsBundleActivator] - Registered SseEventSourceFactory
2025-10-28 08:30:10.572 [INFO ] [rd.activator.CxfJaxrsBundleActivator] - Registered SseEventSource.Builder
2025-10-28 08:30:10.691 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.event/4.4.7
2025-10-28 08:30:10.892 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 5.0.2 (Release Build)
2025-10-28 08:30:11.211 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Found configured connector "0.0.0.0:8443": 0.0.0.0:8443
2025-10-28 08:30:11.212 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Creating non secure connector for address 0.0.0.0:8080
2025-10-28 08:30:11.216 [INFO ] [.service.jetty.internal.JettyFactory] - HTTP/2 ClearText support available, adding "h2c" protocol support to default connector
2025-10-28 08:30:11.217 [INFO ] [.service.jetty.internal.JettyFactory] - Default Jetty connector created: default@380997f4{HTTP/1.1, (http/1.1, h2c)}{0.0.0.0:8080}
2025-10-28 08:30:11.220 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Using configured 0.0.0.0:8443@575e39af{SSL, (ssl, alpn, h2, http/1.1)}{0.0.0.0:8443} as secure connector for address: 0.0.0.0:8443
2025-10-28 08:30:11.222 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Eagerly starting Jetty thread pool QueuedThreadPool[qtp1693592745]@64f22ca9{STOPPED,2<=0<=50,i=0,r=-1,q=0}[NO_TRY]
2025-10-28 08:30:11.236 [INFO ] [.service.jetty.internal.JettyFactory] - Adding JMX support to Jetty server
2025-10-28 08:30:11.321 [INFO ] [j.pax.web.service.internal.Activator] - Starting server controller org.ops4j.pax.web.service.jetty.internal.JettyServerController
2025-10-28 08:30:11.322 [INFO ] [jetty.internal.JettyServerController] - Starting JettyServerController{configuration=97fb286e-eadf-48c0-8855-3823c5b9222e,state=STOPPED}
2025-10-28 08:30:11.322 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Starting Server@16cd3437{STOPPED}[9.4.57.v20241219]
2025-10-28 08:30:11.324 [INFO ] [org.eclipse.jetty.server.Server     ] - jetty-9.4.57.v20241219; built: 2025-01-08T21:24:30.412Z; git: df524e6b29271c2e09ba9aea83c18dc9db464a31; jvm 21.0.8+9-Ubuntu-0ubuntu124.04.1
2025-10-28 08:30:11.358 [INFO ] [org.eclipse.jetty.server.session    ] - DefaultSessionIdManager workerName=node0
2025-10-28 08:30:11.366 [INFO ] [org.eclipse.jetty.server.session    ] - No SessionScavenger set, using defaults
2025-10-28 08:30:11.376 [INFO ] [org.eclipse.jetty.server.session    ] - node0 Scavenging every 600000ms
2025-10-28 08:30:11.390 [INFO ] [.jetty.server.handler.ContextHandler] - Started o.e.j.s.h.ContextHandler@7365e2f7{/static,null,AVAILABLE}
2025-10-28 08:30:11.456 [INFO ] [pse.jetty.util.ssl.SslContextFactory] - x509=X509@5b0ce4b6(mykey,h=[openhab.org],a=[],w=[]) for Server@1aff4155[provider=null,keyStore=file:///var/lib/openhab/etc/keystore,trustStore=file:///var/lib/openhab/etc/keystore]
2025-10-28 08:30:11.579 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Brussels'.
2025-10-28 08:30:11.602 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to 'XXXXXXXXXXXXXXXXXXX'.
2025-10-28 08:30:11.603 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'nl_BE'.
2025-10-28 08:30:11.610 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Measurement system set to 'SI'.
2025-10-28 08:30:11.739 [INFO ] [lipse.jetty.server.AbstractConnector] - Started 0.0.0.0:8443@575e39af{SSL, (ssl, alpn, h2, http/1.1)}{0.0.0.0:8443}
2025-10-28 08:30:11.764 [INFO ] [lipse.jetty.server.AbstractConnector] - Started default@380997f4{HTTP/1.1, (http/1.1, h2c)}{0.0.0.0:8080}
2025-10-28 08:30:11.766 [INFO ] [org.eclipse.jetty.server.Server     ] - Started @6930ms
2025-10-28 08:30:11.777 [INFO ] [j.pax.web.service.internal.Activator] - Registering HttpService factory
2025-10-28 08:30:11.789 [INFO ] [j.pax.web.service.internal.Activator] - Registering HttpServiceRuntime
2025-10-28 08:30:11.790 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.ops4j.pax.web.pax-web-extender-whiteboard_8.0.30 [230]]
2025-10-28 08:30:11.790 [INFO ] [rd.activator.CxfJaxrsBundleActivator] - created whiteboard from configuration: {service.pid=org.apache.aries.jax.rs.whiteboard.default}
2025-10-28 08:30:12.002 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.apache.aries.jax.rs.whiteboard_2.0.2 [65]]
2025-10-28 08:30:12.008 [INFO ] [internal.BundleWhiteboardApplication] - No matching target context(s) for Whiteboard element ServletModel{id=ServletModel-3,name='cxf-servlet',urlPatterns=[/*],contexts=[]}. Filter: (osgi.http.whiteboard.context.name=default). Element may be re-registered later, when matching context/s is/are registered.
2025-10-28 08:30:12.034 [INFO ] [ax.web.service.spi.model.ServerModel] - Created new ServletContextModel{id=ServletContextModel-4,contextPath='/'}
2025-10-28 08:30:12.037 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}", size=2}
2025-10-28 08:30:12.040 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Creating new Jetty context for ServletContextModel{id=ServletContextModel-4,contextPath='/'}
2025-10-28 08:30:12.122 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,STOPPED}
2025-10-28 08:30:12.131 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Changing default OSGi context model for o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,STOPPED}
2025-10-28 08:30:12.148 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-3,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:12.149 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-3,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:12.150 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-3,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:12.158 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Starting Jetty context "/" with default Osgi Context OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}
2025-10-28 08:30:12.186 [INFO ] [.jetty.server.handler.ContextHandler] - Started o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:12.187 [INFO ] [rvice.spi.servlet.OsgiServletContext] - Registering OsgiServletContext{model=OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}} as OSGi service for "/" context path
2025-10-28 08:30:12.940 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.audio_5.0.2 [159]]
2025-10-28 08:30:12.943 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-6,name='/audio',urlPatterns=[/audio/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:12.944 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-6,name='/audio',urlPatterns=[/audio/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:12.944 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-6,name='/audio',urlPatterns=[/audio/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:13.987 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.openhab.core.io.console.karaf/5.0.2
2025-10-28 08:30:14.038 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.io.http_5.0.2 [178]]
2025-10-28 08:30:14.044 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of OsgiContextModel{WB,id=OCM-7,name='default',path='/',bundle=org.openhab.core.io.http,ref={org.osgi.service.http.HttpContext, org.openhab.core.io.http.WrappingHttpContext}={service.id=292, osgi.http.whiteboard.context.name=oh-dfl-http-ctx, service.bundleid=178, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.http.internal.OpenHABHttpContext, osgi.http.whiteboard.context.path=/, component.id=156}}", size=1}
2025-10-28 08:30:14.047 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{WB,id=OCM-7,name='default',path='/',bundle=org.openhab.core.io.http,ref={org.osgi.service.http.HttpContext, org.openhab.core.io.http.WrappingHttpContext}={service.id=292, osgi.http.whiteboard.context.name=oh-dfl-http-ctx, service.bundleid=178, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.http.internal.OpenHABHttpContext, osgi.http.whiteboard.context.path=/, component.id=156}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:14.084 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.io.http.auth_5.0.2 [179]]
2025-10-28 08:30:14.087 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-9,name='/auth',urlPatterns=[/auth/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.088 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-9,name='/auth',urlPatterns=[/auth/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:14.089 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-9,name='/auth',urlPatterns=[/auth/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.097 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-10,name='/changePassword',urlPatterns=[/changePassword/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.097 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-10,name='/changePassword',urlPatterns=[/changePassword/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:14.098 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-10,name='/changePassword',urlPatterns=[/changePassword/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.102 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-11,name='/createApiToken',urlPatterns=[/createApiToken/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.103 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-11,name='/createApiToken',urlPatterns=[/createApiToken/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:14.103 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-11,name='/createApiToken',urlPatterns=[/createApiToken/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:14.192 [INFO ] [ax.web.service.spi.model.ServerModel] - Created new ServletContextModel{id=ServletContextModel-14,contextPath='/rest'}
2025-10-28 08:30:14.193 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of OsgiContextModel{WB,id=OCM-12,name='context.foropenhab',path='/rest',bundle=org.apache.aries.jax.rs.whiteboard,ref={org.osgi.service.http.context.ServletContextHelper}={original.service.bundleid=183, osgi.jaxrs.application.base=rest, service.id=308, service.bundleid=65, service.scope=singleton, servlet.init.hide-service-list-page=true, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.rest.internal.RESTApplicationImpl, component.id=169, original.service.id=307, osgi.http.whiteboard.context.name=context.foropenhab, osgi.jaxrs.name=openhab, osgi.http.whiteboard.target=(osgi.http.endpoint=*), service.pid=org.apache.aries.jax.rs.whiteboard.default, osgi.http.whiteboard.context.path=/rest}}", size=2}
2025-10-28 08:30:14.193 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Creating new Jetty context for ServletContextModel{id=ServletContextModel-14,contextPath='/rest'}
2025-10-28 08:30:14.194 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{WB,id=OCM-12,name='context.foropenhab',path='/rest',bundle=org.apache.aries.jax.rs.whiteboard,ref={org.osgi.service.http.context.ServletContextHelper}={original.service.bundleid=183, osgi.jaxrs.application.base=rest, service.id=308, service.bundleid=65, service.scope=singleton, servlet.init.hide-service-list-page=true, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.rest.internal.RESTApplicationImpl, component.id=169, original.service.id=307, osgi.http.whiteboard.context.name=context.foropenhab, osgi.jaxrs.name=openhab, osgi.http.whiteboard.target=(osgi.http.endpoint=*), service.pid=org.apache.aries.jax.rs.whiteboard.default, osgi.http.whiteboard.context.path=/rest}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@20583003{/rest,null,STOPPED}
2025-10-28 08:30:14.197 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Changing default OSGi context model for o.o.p.w.s.j.i.PaxWebServletContextHandler@20583003{/rest,null,STOPPED}
2025-10-28 08:30:14.199 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-15,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-12,context.foropenhab,/rest}]}
2025-10-28 08:30:14.200 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-15,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-12,context.foropenhab,/rest}]}", size=1}
2025-10-28 08:30:14.203 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-15,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-12,context.foropenhab,/rest}]}
2025-10-28 08:30:14.204 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Starting Jetty context "/rest" with default Osgi Context OsgiContextModel{WB,id=OCM-12,name='context.foropenhab',path='/rest',bundle=org.apache.aries.jax.rs.whiteboard,ref={org.osgi.service.http.context.ServletContextHelper}={original.service.bundleid=183, osgi.jaxrs.application.base=rest, service.id=308, service.bundleid=65, service.scope=singleton, servlet.init.hide-service-list-page=true, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.rest.internal.RESTApplicationImpl, component.id=169, original.service.id=307, osgi.http.whiteboard.context.name=context.foropenhab, osgi.jaxrs.name=openhab, osgi.http.whiteboard.target=(osgi.http.endpoint=*), service.pid=org.apache.aries.jax.rs.whiteboard.default, osgi.http.whiteboard.context.path=/rest}}
2025-10-28 08:30:14.205 [INFO ] [.jetty.server.handler.ContextHandler] - Started o.o.p.w.s.j.i.PaxWebServletContextHandler@20583003{/rest,null,AVAILABLE}
2025-10-28 08:30:14.206 [INFO ] [rvice.spi.servlet.OsgiServletContext] - Registering OsgiServletContext{model=OsgiContextModel{WB,id=OCM-12,name='context.foropenhab',path='/rest',bundle=org.apache.aries.jax.rs.whiteboard,ref={org.osgi.service.http.context.ServletContextHelper}={original.service.bundleid=183, osgi.jaxrs.application.base=rest, service.id=308, service.bundleid=65, service.scope=singleton, servlet.init.hide-service-list-page=true, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.rest.internal.RESTApplicationImpl, component.id=169, original.service.id=307, osgi.http.whiteboard.context.name=context.foropenhab, osgi.jaxrs.name=openhab, osgi.http.whiteboard.target=(osgi.http.endpoint=*), service.pid=org.apache.aries.jax.rs.whiteboard.default, osgi.http.whiteboard.context.path=/rest}}} as OSGi service for "/rest" context path
2025-10-28 08:30:14.402 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.484 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.570 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.590 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.653 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.676 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.742 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.772 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.798 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.829 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.861 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.909 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.931 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:14.971 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.007 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.051 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.097 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.164 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.219 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.316 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.io.websocket_5.0.2 [195]]
2025-10-28 08:30:15.317 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-17,name='/ws',urlPatterns=[/ws/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:15.318 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-17,name='/ws',urlPatterns=[/ws/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:15.318 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-17,name='/ws',urlPatterns=[/ws/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:15.388 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:15.413 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.4.7
2025-10-28 08:30:15.415 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.4.7
2025-10-28 08:30:16.006 [INFO ] [port.EventAdminConfigurationNotifier] - Sending Event Admin notification (configuration successful) to org/ops4j/pax/logging/Configuration
2025-10-28 08:30:18.651 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:18.734 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:18.875 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:19.041 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:20.132 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2025-10-28 08:30:20.381 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:20.450 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:20.536 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:20.749 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:20.906 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.010 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.165 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.289 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.367 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.ui_5.0.2 [223]]
2025-10-28 08:30:21.370 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-18,name='/chart',urlPatterns=[/chart/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.372 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-18,name='/chart',urlPatterns=[/chart/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:21.374 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-18,name='/chart',urlPatterns=[/chart/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.463 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.541 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Initialization of HttpContext "default" for org.openhab.core.ui_5.0.2 [223]", size=2}
2025-10-28 08:30:21.543 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{HS,id=OCM-19,name='default',path='/',bundle=org.openhab.core.ui,context=DefaultHttpContext{bundle=org.openhab.core.ui_5.0.2 [223],contextId='default'}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:21.545 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Changing default OSGi context model for o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:21.547 [INFO ] [rvice.spi.servlet.OsgiServletContext] - Unegistering OsgiServletContext{model=OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}} as OSGi service for "/" context path
2025-10-28 08:30:21.549 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Stopping Jetty context "/"
2025-10-28 08:30:21.551 [INFO ] [.jetty.server.handler.ContextHandler] - Stopped o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,STOPPED}
2025-10-28 08:30:21.554 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Starting Jetty context "/" with default Osgi Context OsgiContextModel{HS,id=OCM-19,name='default',path='/',bundle=org.openhab.core.ui,context=DefaultHttpContext{bundle=org.openhab.core.ui_5.0.2 [223],contextId='default'}}
2025-10-28 08:30:21.560 [INFO ] [.jetty.server.handler.ContextHandler] - Started o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:21.561 [INFO ] [rvice.spi.servlet.OsgiServletContext] - Registering OsgiServletContext{model=OsgiContextModel{HS,id=OCM-19,name='default',path='/',bundle=org.openhab.core.ui,context=DefaultHttpContext{bundle=org.openhab.core.ui_5.0.2 [223],contextId='default'}}} as OSGi service for "/" context path
2025-10-28 08:30:21.566 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-22,name='org.openhab.core.ui.internal.proxy.AsyncProxyServlet',alias='/proxy',urlPatterns=[/proxy/*],servlet=org.openhab.core.ui.internal.proxy.AsyncProxyServlet@1f4f921e,contexts=[{HS,OCM-19,default,/}]}
2025-10-28 08:30:21.569 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-22,name='org.openhab.core.ui.internal.proxy.AsyncProxyServlet',alias='/proxy',urlPatterns=[/proxy/*],servlet=org.openhab.core.ui.internal.proxy.AsyncProxyServlet@1f4f921e,contexts=null}", size=1}
2025-10-28 08:30:21.571 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-22,name='org.openhab.core.ui.internal.proxy.AsyncProxyServlet',alias='/proxy',urlPatterns=[/proxy/*],servlet=org.openhab.core.ui.internal.proxy.AsyncProxyServlet@1f4f921e,contexts=[{HS,OCM-19,default,/}]}
2025-10-28 08:30:21.633 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.693 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.core.ui.icon_5.0.2 [224]]
2025-10-28 08:30:21.702 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-23,name='/icon',urlPatterns=[/icon/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.706 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-23,name='/icon',urlPatterns=[/icon/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:21.707 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-23,name='/icon',urlPatterns=[/icon/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.735 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.845 [INFO ] [org.apache.cxf.endpoint.ServerImpl  ] - Setting the server's publish address to be /
2025-10-28 08:30:21.917 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.ui_5.0.2 [226]]
2025-10-28 08:30:21.922 [INFO ] [.service.internal.HttpServiceEnabled] - Registering WelcomeFileModel{id=WelcomeFileModel-24,redirect='false',welcomeFiles=[index.html],contexts=[OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}]}
2025-10-28 08:30:21.924 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of WelcomeFileModel{id=WelcomeFileModel-24,redirect='false',welcomeFiles=[index.html],contexts=[OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}]}", size=1}
2025-10-28 08:30:21.929 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Reconfiguration of welcome files for all resource servlets in context "OsgiContextModel{WB,id=OCM-1,name='default',path='/',bundle=org.ops4j.pax.web.pax-web-extender-whiteboard,context=(supplier)}"
2025-10-28 08:30:21.930 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-25,name='/error-8f5e8577-601f-4d12-a477-f442b0c6390a',urlPatterns=[/error-8f5e8577-601f-4d12-a477-f442b0c6390a],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.933 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-25,name='/error-8f5e8577-601f-4d12-a477-f442b0c6390a',urlPatterns=[/error-8f5e8577-601f-4d12-a477-f442b0c6390a],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:21.935 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-25,name='/error-8f5e8577-601f-4d12-a477-f442b0c6390a',urlPatterns=[/error-8f5e8577-601f-4d12-a477-f442b0c6390a],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.939 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Initialization of HttpContext "default" for org.openhab.ui_5.0.2 [226]", size=2}
2025-10-28 08:30:21.941 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{HS,id=OCM-27,name='default',path='/',bundle=org.openhab.ui,context=DefaultHttpContext{bundle=org.openhab.ui_5.0.2 [226],contextId='default'}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:21.946 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-29,name='/',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.950 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-29,name='/',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}", size=2}
2025-10-28 08:30:21.955 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Removing servlet ServletModel{id=ServletModel-3,name='cxf-servlet',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.957 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-29,name='/',urlPatterns=[/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:21.963 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of OsgiContextModel{WB,id=OCM-30,name='org.openhab.ui.context',path='/',bundle=org.openhab.ui,ref={org.osgi.service.http.context.ServletContextHelper}={context.init.dirAllowed=false, service.id=541, service.bundleid=226, service.scope=bundle, context.init.redirectWelcome=false, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), context.init.preCompressed=true, component.name=org.openhab.ui.internal.UIContext, component.id=333, context.init.etags=true, context.init.acceptRanges=true, osgi.http.whiteboard.context.name=org.openhab.ui.context, osgi.http.whiteboard.context.path=/}}", size=1}
2025-10-28 08:30:21.965 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{WB,id=OCM-30,name='org.openhab.ui.context',path='/',bundle=org.openhab.ui,ref={org.osgi.service.http.context.ServletContextHelper}={context.init.dirAllowed=false, service.id=541, service.bundleid=226, service.scope=bundle, context.init.redirectWelcome=false, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), context.init.preCompressed=true, component.name=org.openhab.ui.internal.UIContext, component.id=333, context.init.etags=true, context.init.acceptRanges=true, osgi.http.whiteboard.context.name=org.openhab.ui.context, osgi.http.whiteboard.context.path=/}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:22.333 [INFO ] [ornium.elements.util.JceProviderUtil] - JCE default setup
2025-10-28 08:30:22.336 [INFO ] [ornium.elements.util.JceProviderUtil] - RSA: true, EC: true, AES: not restricted
2025-10-28 08:30:22.339 [INFO ] [ornium.elements.util.JceProviderUtil] - EdDSA supported by SunEC, Ed25519: true, Ed448: true
2025-10-28 08:30:22.341 [INFO ] [ornium.elements.util.JceProviderUtil] - JCE setup: SunEC version 21, ready.
2025-10-28 08:30:22.453 [INFO ] [ornium.elements.config.Configuration] - defaults added DTLS.
2025-10-28 08:30:22.459 [INFO ] [ornium.elements.config.Configuration] - defaults added SYS.
2025-10-28 08:30:22.467 [INFO ] [ornium.elements.config.Configuration] - loading properties from file /var/lib/openhab/Californium3.properties
2025-10-28 08:30:22.480 [INFO ] [ornium.elements.config.Configuration] - defaults added UDP.
2025-10-28 08:30:22.488 [INFO ] [ornium.elements.config.Configuration] - defaults added COAP.
2025-10-28 08:30:22.489 [INFO ] [um.core.network.RandomTokenGenerator] - using tokens of 8 bytes in length
2025-10-28 08:30:22.524 [INFO ] [org.eclipse.californium.ban         ] - Started.
2025-10-28 08:30:22.527 [INFO ] [alifornium.core.network.CoapEndpoint] - coap CoapEndpoint uses udp context
2025-10-28 08:30:22.549 [INFO ] [um.core.network.stack.BlockwiseLayer] - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
2025-10-28 08:30:22.566 [INFO ] [um.core.network.RandomTokenGenerator] - using tokens of 8 bytes in length
2025-10-28 08:30:22.568 [INFO ] [alifornium.core.network.CoapEndpoint] - coap CoapEndpoint uses udp context
2025-10-28 08:30:22.570 [INFO ] [um.core.network.stack.BlockwiseLayer] - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
2025-10-28 08:30:22.639 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.binding.shelly_5.0.2.202509231052 [248]]
2025-10-28 08:30:22.646 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-32,name='ShellyEventServlet',urlPatterns=[/shelly/event, /shelly/wsevent],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:22.650 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-32,name='ShellyEventServlet',urlPatterns=[/shelly/event, /shelly/wsevent],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:22.652 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-32,name='ShellyEventServlet',urlPatterns=[/shelly/event, /shelly/wsevent],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:22.718 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-33,name='/shelly/manager',urlPatterns=[/shelly/manager/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:22.723 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-33,name='/shelly/manager',urlPatterns=[/shelly/manager/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:22.725 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-33,name='/shelly/manager',urlPatterns=[/shelly/manager/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:22.933 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider com.oracle.truffle.nfi.NFILanguageProvider of service com.oracle.truffle.api.provider.TruffleLanguageProvider in bundle org.graalvm.truffle.truffle-nfi
2025-10-28 08:30:23.335 [INFO ] [nections.transports.TransportBuilder] - Using transport dbus-java-transport-junixsocket for address unix:path=/var/run/dbus/system_bus_socket
2025-10-28 08:30:23.740 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.binding.gpstracker_5.0.2 [322]]
2025-10-28 08:30:23.756 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Initialization of HttpContext "default" for org.openhab.binding.gpstracker_5.0.2 [322]", size=2}
2025-10-28 08:30:23.761 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{HS,id=OCM-34,name='default',path='/',bundle=org.openhab.binding.gpstracker,context=DefaultHttpContext{bundle=org.openhab.binding.gpstracker_5.0.2 [322],contextId='default'}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:23.770 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-36,name='org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet',alias='/gpstracker/owntracks',urlPatterns=[/gpstracker/owntracks/*],servlet=org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet@18e55ca,contexts=[{HS,OCM-34,default,/}]}
2025-10-28 08:30:23.773 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-36,name='org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet',alias='/gpstracker/owntracks',urlPatterns=[/gpstracker/owntracks/*],servlet=org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet@18e55ca,contexts=null}", size=1}
2025-10-28 08:30:23.777 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-36,name='org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet',alias='/gpstracker/owntracks',urlPatterns=[/gpstracker/owntracks/*],servlet=org.openhab.binding.gpstracker.internal.provider.owntracks.OwnTracksCallbackServlet@18e55ca,contexts=[{HS,OCM-34,default,/}]}
2025-10-28 08:30:23.785 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-37,name='org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet',alias='/gpstracker/gpslogger',urlPatterns=[/gpstracker/gpslogger/*],servlet=org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet@1dbd8d4e,contexts=[{HS,OCM-34,default,/}]}
2025-10-28 08:30:23.786 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-37,name='org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet',alias='/gpstracker/gpslogger',urlPatterns=[/gpstracker/gpslogger/*],servlet=org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet@1dbd8d4e,contexts=null}", size=1}
2025-10-28 08:30:23.787 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-37,name='org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet',alias='/gpstracker/gpslogger',urlPatterns=[/gpstracker/gpslogger/*],servlet=org.openhab.binding.gpstracker.internal.provider.gpslogger.GPSLoggerCallbackServlet@1dbd8d4e,contexts=[{HS,OCM-34,default,/}]}
2025-10-28 08:30:23.817 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@681520d0[provider=null,keyStore=null,trustStore=null]
2025-10-28 08:30:23.818 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@681520d0[provider=null,keyStore=null,trustStore=null]
2025-10-28 08:30:24.051 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@17eabf9e[provider=null,keyStore=null,trustStore=null]
2025-10-28 08:30:24.055 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@17eabf9e[provider=null,keyStore=null,trustStore=null]
2025-10-28 08:30:24.147 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.jupnp_3.0.3 [301]]
2025-10-28 08:30:24.191 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-38,name='org.jupnp.transport.impl.async.AsyncServlet',alias='/upnpcallback',urlPatterns=[/upnpcallback/*],servlet=org.jupnp.transport.impl.async.AsyncServlet@34d0bd89,contexts=[{HS,OCM-39,context:520481910,/}]}
2025-10-28 08:30:24.193 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-38,name='org.jupnp.transport.impl.async.AsyncServlet',alias='/upnpcallback',urlPatterns=[/upnpcallback/*],servlet=org.jupnp.transport.impl.async.AsyncServlet@34d0bd89,contexts=null}", size=3}
2025-10-28 08:30:24.195 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{HS,id=OCM-39,name='context:520481910',path='/',bundle=org.jupnp,context=WebContainerContextWrapper{bundle=org.jupnp_3.0.3 [301],contextId='context:520481910',delegate=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1f05ec76}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:24.199 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-38,name='org.jupnp.transport.impl.async.AsyncServlet',alias='/upnpcallback',urlPatterns=[/upnpcallback/*],servlet=org.jupnp.transport.impl.async.AsyncServlet@34d0bd89,contexts=[{HS,OCM-39,context:520481910,/}]}
2025-10-28 08:30:29.655 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.persistence.rrd4j_5.0.2 [364]]
2025-10-28 08:30:29.667 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Initialization of HttpContext "default" for org.openhab.persistence.rrd4j_5.0.2 [364]", size=2}
2025-10-28 08:30:29.668 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding OsgiContextModel{HS,id=OCM-41,name='default',path='/',bundle=org.openhab.persistence.rrd4j,context=DefaultHttpContext{bundle=org.openhab.persistence.rrd4j_5.0.2 [364],contextId='default'}} to o.o.p.w.s.j.i.PaxWebServletContextHandler@2a2b65c9{/,null,AVAILABLE}
2025-10-28 08:30:29.677 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-43,name='org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet',alias='/rrdchart.png',urlPatterns=[/rrdchart.png/*],servlet=org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet@7bc5e3e8,contexts=[{HS,OCM-41,default,/}]}
2025-10-28 08:30:29.678 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-43,name='org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet',alias='/rrdchart.png',urlPatterns=[/rrdchart.png/*],servlet=org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet@7bc5e3e8,contexts=null}", size=1}
2025-10-28 08:30:29.679 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-43,name='org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet',alias='/rrdchart.png',urlPatterns=[/rrdchart.png/*],servlet=org.openhab.persistence.rrd4j.internal.charts.RRD4jChartServlet@7bc5e3e8,contexts=[{HS,OCM-41,default,/}]}
2025-10-28 08:30:29.719 [INFO ] [internal.StoppableHttpServiceFactory] - Binding HTTP Service for bundle: [org.openhab.ui.basic_5.0.2 [366]]
2025-10-28 08:30:29.722 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-44,name='/basicui/CMD',urlPatterns=[/basicui/CMD/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.726 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-44,name='/basicui/CMD',urlPatterns=[/basicui/CMD/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:29.729 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-44,name='/basicui/CMD',urlPatterns=[/basicui/CMD/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.743 [INFO ] [.service.internal.HttpServiceEnabled] - Registering resources with [/basicui/*] mapping(s) and resource base "web"
2025-10-28 08:30:29.751 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-45,name='default-58ef18f3-0349-4c84-968a-3c0deadb090f',urlPatterns=[/basicui/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.755 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-45,name='default-58ef18f3-0349-4c84-968a-3c0deadb090f',urlPatterns=[/basicui/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:29.757 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-45,name='default-58ef18f3-0349-4c84-968a-3c0deadb090f',urlPatterns=[/basicui/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.769 [INFO ] [ty.internal.web.JettyResourceServlet] - Initialized Jetty Resource Servlet for base="web" with cache maxSize=4096kB, maxEntrySize=2048kB, maxEntries=2048
2025-10-28 08:30:29.799 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 3e...42, base URL = http://localhost:8080)
2025-10-28 08:30:29.802 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-46,name='/basicui',urlPatterns=[/basicui/manifest.json],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.804 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-46,name='/basicui',urlPatterns=[/basicui/manifest.json],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:29.807 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-46,name='/basicui',urlPatterns=[/basicui/manifest.json],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.812 [INFO ] [.service.internal.HttpServiceEnabled] - Registering ServletModel{id=ServletModel-47,name='/basicui/app',urlPatterns=[/basicui/app/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:29.815 [INFO ] [jetty.internal.JettyServerController] - Receiving Batch{"Registration of ServletModel{id=ServletModel-47,name='/basicui/app',urlPatterns=[/basicui/app/*],contexts=[{WB,OCM-1,default,/}]}", size=1}
2025-10-28 08:30:29.818 [INFO ] [ce.jetty.internal.JettyServerWrapper] - Adding servlet ServletModel{id=ServletModel-47,name='/basicui/app',urlPatterns=[/basicui/app/*],contexts=[{WB,OCM-1,default,/}]}
2025-10-28 08:30:48.237 [ERROR] [com.github.hypfvieh.DbusHelper      ] - Critical error while reading DBUS response (maybe no bluetoothd daemon running?)
org.freedesktop.dbus.errors.NoReply: No reply within specified time
	at org.freedesktop.dbus.RemoteInvocationHandler.executeRemoteMethod(RemoteInvocationHandler.java:234) ~[?:?]
	at org.freedesktop.dbus.RemoteInvocationHandler.executeRemoteMethod(RemoteInvocationHandler.java:251) ~[?:?]
	at org.freedesktop.dbus.RemoteInvocationHandler.executeRemoteMethod(RemoteInvocationHandler.java:153) ~[?:?]
	at org.freedesktop.dbus.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:97) ~[?:?]
	at jdk.proxy23.$Proxy135.Introspect(Unknown Source) ~[?:?]
	at com.github.hypfvieh.DbusHelper.findNodes(DbusHelper.java:41) ~[?:?]
	at com.github.hypfvieh.bluetooth.DeviceManager.scanForBluetoothAdapters(DeviceManager.java:114) ~[?:?]
	at org.openhab.binding.bluetooth.bluez.internal.DeviceManagerWrapper.scanForBluetoothAdapters(DeviceManagerWrapper.java:45) ~[?:?]
	at org.openhab.binding.bluetooth.bluez.internal.BlueZDiscoveryService.startScan(BlueZDiscoveryService.java:90) ~[?:?]
	at org.openhab.binding.bluetooth.bluez.internal.BlueZDiscoveryService.lambda$0(BlueZDiscoveryService.java:71) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:572) ~[?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:358) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?]
	at java.lang.Thread.run(Thread.java:1583) [?:?]
2025-10-28 08:30:56.037 [INFO ] [port.EventAdminConfigurationNotifier] - Sending Event Admin notification (configuration successful) to org/ops4j/pax/logging/Configuration
2025-10-28 08:31:19.541 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'piholeminipc.things'
2025-10-28 08:31:19.712 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'piholenas.things'
2025-10-28 08:31:19.736 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'esp32-bluetooth-proxy.things'
2025-10-28 08:31:19.746 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'esp32-tuinhuis.things'
2025-10-28 08:31:29.591 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to 'localhost' with clientid 195d4dbd-14ba-49b1-a234-a68ab80b200d
2025-10-28 08:31:30.061 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2025-10-28 08:31:30.907 [INFO ] [on.script.ui.Last_shutdown_timestamp] - openHAB last shutdown at 2025-10-28T08:29:29.710+01:00[Europe/Brussels]
2025-10-28 08:31:30.919 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2025-10-28 08:31:32.065 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-aanOfUit.js'
2025-10-28 08:31:32.519 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Aan of uit
2025-10-28 08:31:32.568 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-procesbescherming.js'
2025-10-28 08:31:33.011 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Procesbescherming
2025-10-28 08:31:33.016 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-statusOpPage.js'
2025-10-28 08:31:33.237 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Status op page
2025-10-28 08:31:33.241 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-sturingstemperatuurBijwerken.js'
2025-10-28 08:31:33.488 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler UniFiProtectG5CameraThingHandler of thing unifiprotect:g5camera:f4b3b5f657:F4E2C676310A tried accessing its bridge although the handler was already disposed.
2025-10-28 08:31:33.581 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Sturingstemperatuur bijwerken
2025-10-28 08:31:33.594 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-temperatuursturingAanzetten.js'
2025-10-28 08:31:34.267 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Temperatuursturing aanzetten
2025-10-28 08:31:34.274 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-vuurAchteraan.js'
2025-10-28 08:31:34.975 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - vuur achteraan
2025-10-28 08:31:34.989 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/haarden-wintermodusTemperatuursturing.js'
2025-10-28 08:31:35.270 [WARN ] [l.internal.handler.UpnpServerHandler] - Cannot serve media from server Sonos UPnP Server, no renderer selected
2025-10-28 08:31:35.939 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Haarden - Wintermodus-temperatuursturing
2025-10-28 08:31:35.954 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/irrigatieRegel.js'
2025-10-28 08:31:36.142 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Irrigatieregel
2025-10-28 08:31:36.145 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/shellyUpdate.js'
2025-10-28 08:31:36.350 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Shelly-update beschikbaar
2025-10-28 08:31:36.354 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/testRegel.js'
2025-10-28 08:31:36.715 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Te negeren test
2025-10-28 08:31:36.719 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/testRegel2.js'
2025-10-28 08:31:36.816 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Te negeren test 2
2025-10-28 08:31:36.819 [INFO ] [ort.loader.AbstractScriptFileWatcher] - (Re-)Loading script '/etc/openhab/automation/js/zonnepanelenDonker.js'
2025-10-28 08:31:36.920 [INFO ] [.openhab.automation.openhab-js.rules] - Adding rule: Donker - zonnepanelen
2025-10-28 08:31:37.141 [INFO ] [um.core.network.RandomTokenGenerator] - using tokens of 8 bytes in length
2025-10-28 08:31:37.141 [INFO ] [alifornium.core.network.CoapEndpoint] - coap CoapEndpoint uses udp context
2025-10-28 08:31:37.142 [INFO ] [um.core.network.stack.BlockwiseLayer] - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
2025-10-28 08:31:37.142 [INFO ] [um.core.network.RandomTokenGenerator] - using tokens of 8 bytes in length
2025-10-28 08:31:37.142 [INFO ] [alifornium.core.network.CoapEndpoint] - coap CoapEndpoint uses udp context
2025-10-28 08:31:37.142 [INFO ] [um.core.network.stack.BlockwiseLayer] - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
2025-10-28 08:31:37.151 [INFO ] [alifornium.core.network.CoapEndpoint] - coap Endpoint [coap://0.0.0.0:5683] requires an executor to start, using default single-threaded daemon executor
2025-10-28 08:31:37.172 [INFO ] [rnium.elements.UdpMulticastConnector] - socket [0:0:0:0:0:0:0:0]:5683, loopback mode false
2025-10-28 08:31:37.173 [INFO ] [rnium.elements.UdpMulticastConnector] - interface 192.168.1.9
2025-10-28 08:31:37.174 [INFO ] [rnium.elements.UdpMulticastConnector] - joined group 224.0.1.187
2025-10-28 08:31:37.174 [INFO ] [se.californium.elements.UDPConnector] - UDPConnector starts up 1 sender threads and 1 receiver threads
2025-10-28 08:31:37.176 [INFO ] [se.californium.elements.UDPConnector] - UDPConnector listening on /[0:0:0:0:0:0:0:0]:5683, recv buf = 106496, send buf = 106496, recv packet size = 2048
2025-10-28 08:31:37.178 [INFO ] [alifornium.core.network.CoapEndpoint] - coap Started endpoint at coap://[0:0:0:0:0:0:0:0]:5683
2025-10-28 08:31:37.178 [INFO ] [.eclipse.californium.core.CoapClient] - started set client endpoint /[0:0:0:0:0:0:0:0]:5683
2025-10-28 08:31:46.834 [INFO ] [um.core.network.RandomTokenGenerator] - using tokens of 8 bytes in length
2025-10-28 08:31:46.834 [INFO ] [alifornium.core.network.CoapEndpoint] - coap CoapEndpoint uses udp context
2025-10-28 08:31:46.834 [INFO ] [um.core.network.stack.BlockwiseLayer] - coap BlockwiseLayer uses MAX_MESSAGE_SIZE=1024, PREFERRED_BLOCK_SIZE=512, BLOCKWISE_STATUS_LIFETIME=300000, MAX_RESOURCE_BODY_SIZE=8192, BLOCKWISE_STRICT_BLOCK2_OPTION=false
2025-10-28 08:31:46.835 [INFO ] [alifornium.core.network.CoapEndpoint] - coap Endpoint [coap://0.0.0.0:0] requires an executor to start, using default single-threaded daemon executor
2025-10-28 08:31:46.836 [INFO ] [se.californium.elements.UDPConnector] - UDPConnector starts up 1 sender threads and 1 receiver threads
2025-10-28 08:31:46.837 [INFO ] [se.californium.elements.UDPConnector] - UDPConnector listening on /[0:0:0:0:0:0:0:0]:59352, recv buf = 106496, send buf = 106496, recv packet size = 2048
2025-10-28 08:31:46.837 [INFO ] [alifornium.core.network.CoapEndpoint] - coap Started endpoint at coap://[0:0:0:0:0:0:0:0]:59352
2025-10-28 08:31:46.837 [INFO ] [fornium.core.network.EndpointManager] - created implicit endpoint coap://[0:0:0:0:0:0:0:0]:59352 for coap

Is that normal? It seems like a lot of these entries are more “DEBUG” than “INFO”?

348

It’s all Karaf log outputs (org.apache) so in that sense out of scope for OH.
Those you usually don’t have on.

349

I had run log:set DEBUG before. But I set the level back to INFO (log:set INFO) later. Maybe something went wrong there?

Any idea how I could fix it?

350

… try: log:set DEFAULT

351 
openhab> log:set DEFAULT
Error executing command: Can not unset the ROOT logger

Maybe I should adjust something in log4j2.xml?

352

You must not set logging globally but only ever for e.g. org.openhab

But please, people, this whole post has been off-topic, particularly for this thread with its special rules. So please continue that elsewhere.

353 
log:set ERROR
log:set DEBUG org.openhab
2 Likes
354

I was just about to open another topic, but you saved the day :wink:

355

Hey folks.

Yesterday I upgraded from latest 4.x to 5.0.2 and I now see some issues in several RuleDSL rules where I used <item>.lastUpdate("mapdb").I’m still debugging but it seems the timestamps I get are somehow “older” now than before. I took a look via persistence REST API using mapdb serviceId and the related Item. E.g. it showed the state OFF with a timestamp of 20 minutes ago. When I triggered the state change of that item (presence detector), it flipped the state to ON but the REST API now showed 21 minutes ago with the state ON instead of the current timestamp. That seems wrong to me, no? After ~40 seconds the presence detector switches OFF again, now having the right/current timestamp set.

My mapdb.persist:

Strategies {
    everyMinute : "0 * * * * ?"
    everyHour   : "0 0 * * * ?"
    everyDay    : "0 0 0 * * ?"
}

Items {
    PersistedStates*              : strategy = everyChange, restoreOnStartup
    Windows*                      : strategy = everyChange, restoreOnStartup
    NotifyContacts*               : strategy = everyChange, restoreOnStartup
}

The Item is assigned to the PersistedStates and PersistedStateSeriesMinute (influxdb) groups.

Anything changed with OH 5 in this regard? I did not see any breaking changes related to this. Only an enhancement. Thanks!

356

One addition is you no longer need persistence for this. We’d need far more details to pinpoint the problem here (open a new thread for that I think) but the better approach is to use <item>.lastStateChange for the timestamp when the Item last changed, item.lastStateUpdate for the timestamp when the Item was last updated whether or not that update resuilted in a change, and <item>.lastState to get the state of the Item before it changed to it’s current state.

OH keeps track of these independetly from persistence, but it uses persistence to repopulate them during restoreOnStartup (which is the enhancement you link to).

One of the things that drove this change was a change in behavior for when the timing doesn’t work out. If the current state of the Item differs from the most recent state stored in persistence, persistence will return null because it can’t know when the lastUpdate occurred. The most recent change to the Item wasn’t stored (yet?). But the properties on the Item will always be correct and not require the timing to be just right to work.

357

Hey Rich, thanks for the detailed answer. Just a quick feedback: lastStateChange seems to be a good replacement for lastUpdate. Works for me. What’s left is: why the old way is broken now :). But let me create a dedicated topic for that as you suggested. Thanks again!