[SOLVED] HTTP binding just stopped working

CrazyElectron · July 19, 2022, 2:54pm

Running OH 3.3.0 in Docker with the HTTP binding to extract information from a Nefit Easy room thermostat (via a node.js server also running in a container).
Today the HTTP binding somehow stopped retrieving data from the specified URLs. I turned on DEBUG logging for the binding but nothing appears in the log after the initial loading.

The log settings:

openhab> log:list
Logger                                             │ Level
───────────────────────────────────────────────────┼──────
ROOT                                               │ WARN
javax.jmdns                                        │ ERROR
javax.mail                                         │ ERROR
openhab.event                                      │ INFO
 ...
org.openhab.binding.http                           │ DEBUG
 ...

The bundle status:

openhab> bundle:list | grep -i http
 67 x Active x  80 x 3.4.5                  x Apache CXF Runtime HTTP Transport
169 x Active x  80 x 3.3.0                  x openHAB Core :: Bundles :: HTTP Interface
170 x Active x  80 x 3.3.0                  x openHAB Core :: Bundles :: HTTP Interface Authentication
239 x Active x  80 x 4.1.72.Final           x Netty/Codec/HTTP
263 x Active x  80 x 3.3.0                  x openHAB Add-ons :: Bundles :: HTTP Binding

The http.cfg file:


Thing http:url:nefitstat "Nefit Easy Status" [baseURL="http://nefiteasy:3000/api/status",refresh=30] {
    Channels:
        Type number : CVTemp "Livingroom Temp" [
            mode="READONLY",
            stateTransformation="JSONPATH:$.['outdoor temp'].IHT"
        ]
}

Thing http:url:nefitbridge "Nefit Easy bridge" [baseURL="http://nefiteasy:3000/bridge/ecus/rrc/uiStatus",refresh=30] {
    Channels:
        Type switch : HotWater "Hot water active" [
           mode="READONLY",
           stateTransformation="JSONPATH:$.value.BAI∩MAP:nefithotwater.map",
           onValue="ON", offValue="OFF"
       ]
       Type switch : CVHeater "Central heater active" [
           mode="READONLY",
           stateTransformation="JSONPATH:$.value.BAI∩MAP:nefitheather.map",
           onValue="ON", offValue="OFF"
       ]
}

And finally the logging:

2022-07-19 16:36:24.986 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'http.things'
2022-07-19 16:36:24.994 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mqtt.things'
2022-07-19 16:36:25.000 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'ntp.things'
2022-07-19 16:36:25.004 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'daikin.things'
2022-07-19 16:36:25.010 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'netatmo.things'
2022-07-19 16:36:26.111 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2022-07-19 16:36:27.003 [INFO ] [org.openhab.ui.internal.UIService   ] - Started UI on port 8080
2022-07-19 16:36:29.579 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@475560ba[provider=null,keyStore=null,trustStore=null]
2022-07-19 16:36:29.580 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@475560ba[provider=null,keyStore=null,trustStore=null]
2022-07-19 16:36:29.604 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : BundleComponentActivator : ComponentHolder created.
2022-07-19 16:36:29.604 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-07-19 16:36:29.605 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Component created: DS=DS13, implementation=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider, immediate=true, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider]
2022-07-19 16:36:29.605 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Component Services: scope=singleton, services=[org.openhab.core.thing.type.DynamicStateDescriptionProvider, org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider]
2022-07-19 16:36:29.605 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Component Properties: {}
2022-07-19 16:36:29.605 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Querying state disabled
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Querying state disabled
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Component can not be activated since it is in state disabled
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider] : Querying state disabled
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Updating target filters
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Changed state from disabled to unsatisfiedReference
2022-07-19 16:36:29.606 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Component enabled
2022-07-19 16:36:29.607 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : ActivateInternal
2022-07-19 16:36:29.607 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.607 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.608 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Activating component from state unsatisfiedReference
2022-07-19 16:36:29.608 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.608 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.608 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Changed state from unsatisfiedReference to satisfied
2022-07-19 16:36:29.608 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : registration change queue [registered]
2022-07-19 16:36:29.609 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Checking constructor public org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider()
2022-07-19 16:36:29.609 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Found constructor with 0 arguments : public org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider()
2022-07-19 16:36:29.610 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : This thread collected dependencies
2022-07-19 16:36:29.610 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : getService (single component manager) dependencies collected.
2022-07-19 16:36:29.610 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state satisfied
2022-07-19 16:36:29.610 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state satisfied
2022-07-19 16:36:29.610 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : getting activate: activate
2022-07-19 16:36:29.611 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Locating method activate in class org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider
2022-07-19 16:36:29.611 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Declared Method org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-07-19 16:36:29.611 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Locating method activate in class java.lang.Object
2022-07-19 16:36:29.611 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-07-19 16:36:29.611 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : activate method [activate] not found, ignoring
2022-07-19 16:36:29.612 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Set implementation object for component
2022-07-19 16:36:29.612 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Changed state from satisfied to active
2022-07-19 16:36:29.612 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Dependency Manager created $000interface=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=null, parameter=0
2022-07-19 16:36:29.612 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Dependency Manager created $001interface=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1
2022-07-19 16:36:29.612 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.http.internal.HttpHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.http]
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Component Properties: {}
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Querying state disabled
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Querying state disabled
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Component can not be activated since it is in state disabled
2022-07-19 16:36:29.613 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory] : Querying state disabled
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Updating target filters
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : No change in target property for dependency $000: currently registered: false
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] :  No existing service listener to unregister for dependency $000
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Setting target property for dependency $000 to null
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)
2022-07-19 16:36:29.614 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $000 tracker reset (closed)
2022-07-19 16:36:29.615 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=283, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=156} (enter)
2022-07-19 16:36:29.615 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-07-19 16:36:29.615 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=283, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=156} (exit)
2022-07-19 16:36:29.615 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $000 tracker opened
2022-07-19 16:36:29.616 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : registering service listener for dependency $000
2022-07-19 16:36:29.616 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : No change in target property for dependency $001: currently registered: false
2022-07-19 16:36:29.616 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] :  No existing service listener to unregister for dependency $001
2022-07-19 16:36:29.626 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Setting target property for dependency $001 to null
2022-07-19 16:36:29.626 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider), initialReferenceFilter (objectClass=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider)
2022-07-19 16:36:29.638 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $001 tracker reset (closed)
2022-07-19 16:36:29.639 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.type.DynamicStateDescriptionProvider, org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider}={service.id=498, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider, component.id=310} (enter)
2022-07-19 16:36:29.639 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-07-19 16:36:29.639 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.type.DynamicStateDescriptionProvider, org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider}={service.id=498, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider, component.id=310} (exit)
2022-07-19 16:36:29.639 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : dm $001 tracker opened
2022-07-19 16:36:29.640 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : registering service listener for dependency $001
2022-07-19 16:36:29.640 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Changed state from disabled to unsatisfiedReference
2022-07-19 16:36:29.640 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Component enabled
2022-07-19 16:36:29.640 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : ActivateInternal
2022-07-19 16:36:29.640 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.641 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.641 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Activating component from state unsatisfiedReference
2022-07-19 16:36:29.641 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.641 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state unsatisfiedReference
2022-07-19 16:36:29.641 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Changed state from unsatisfiedReference to satisfied
2022-07-19 16:36:29.642 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : registration change queue [registered]
2022-07-19 16:36:29.643 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Checking constructor public org.openhab.binding.http.internal.HttpHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider)
2022-07-19 16:36:29.643 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getReferenceClass: Looking for interface class org.openhab.core.io.net.http.HttpClientFactory through loader of org.openhab.binding.http.internal.HttpHandlerFactory
2022-07-19 16:36:29.643 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getParameterClass: Found class org.openhab.core.io.net.http.HttpClientFactory
2022-07-19 16:36:29.644 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getReferenceClass: Looking for interface class org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider through loader of org.openhab.binding.http.internal.HttpHandlerFactory
2022-07-19 16:36:29.644 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getParameterClass: Found class org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider
2022-07-19 16:36:29.644 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Found constructor with 2 arguments : public org.openhab.binding.http.internal.HttpHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider)
2022-07-19 16:36:29.644 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : This thread collected dependencies
2022-07-19 16:36:29.644 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getService (single component manager) dependencies collected.
2022-07-19 16:36:29.645 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state satisfied
2022-07-19 16:36:29.645 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state satisfied
2022-07-19 16:36:29.645 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=283, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=156}] service: [null]]]
2022-07-19 16:36:29.647 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.DynamicStateDescriptionProvider, org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider}={service.id=498, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider, component.id=310}] service: [null]]]
2022-07-19 16:36:29.685 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@39bdf31[provider=null,keyStore=null,trustStore=null]
2022-07-19 16:36:29.693 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@39bdf31[provider=null,keyStore=null,trustStore=null]
2022-07-19 16:36:29.699 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : getting activate: activate
2022-07-19 16:36:29.699 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Locating method activate in class org.openhab.binding.http.internal.HttpHandlerFactory
2022-07-19 16:36:29.699 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Declared Method org.openhab.binding.http.internal.HttpHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-07-19 16:36:29.700 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2022-07-19 16:36:29.700 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2022-07-19 16:36:29.701 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2022-07-19 16:36:29.701 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : invoked activate: activate
2022-07-19 16:36:29.701 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Set implementation object for component
2022-07-19 16:36:29.701 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Changed state from satisfied to active
2022-07-19 16:36:29.763 [INFO ] [nding.http.internal.HttpThingHandler] - Using the secure client for thing 'http:url:nefitbridge'.
2022-07-19 16:36:29.764 [DEBUG] [nding.http.internal.HttpThingHandler] - No authentication configured for thing 'http:url:nefitbridge'
2022-07-19 16:36:29.865 [INFO ] [nding.http.internal.HttpThingHandler] - Using the secure client for thing 'http:url:nefitstat'.
2022-07-19 16:36:29.866 [DEBUG] [nding.http.internal.HttpThingHandler] - No authentication configured for thing 'http:url:nefitstat'
2022-07-19 16:36:29.911 [INFO ] [nding.http.internal.HttpThingHandler] - Using the secure client for thing 'http:url:weather'.
2022-07-19 16:36:29.911 [DEBUG] [nding.http.internal.HttpThingHandler] - No authentication configured for thing 'http:url:weather'
 ...
2022-07-19 16:45:57.785 [DEBUG] [ing.http.internal.HttpHandlerFactory] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpHandlerFactory(311)] : Querying state active
2022-07-19 16:45:57.785 [DEBUG] [.HttpDynamicStateDescriptionProvider] - bundle org.openhab.binding.http:3.3.0 (263)[org.openhab.binding.http.internal.HttpDynamicStateDescriptionProvider(310)] : Querying state active
 ...

Restarting OH did not solve it either. How can I test/debug this?

CrazyElectron · July 28, 2022, 6:29am

OK, it is mosty solved by rechecking the http.things configuration and making some minor adjustments to the URL’s. The items do get a value now but there is nothing in the logfiles beyond those few lines ‘Querying state active’ and no indication that anythins is being retrieved.