Disconnected from the openHAB Cloud service

ssalonen · October 27, 2022, 5:03pm

@helljol yeah, this is how it was before any cloud changes. Client is surely connected, verified by ping/pong messages. But cloud had mixed up online/offline status (there is separate bookkeeping for that due to technical details) for one reason or another

LordLiverpool · October 28, 2022, 11:36pm

Mine has lost the connection to the cloud around four or five times this week. Very unusual

LordLiverpool · October 28, 2022, 11:53pm

And a restart seems always to fix it. So it’s consistent with the idea that it’s disconnecting and failing to reconnect for some reason.

rpwong · October 29, 2022, 12:21am

FYI, you can restart the cloud connector simply by going into the openHAB Cloud settings in Main UI and clicking “Save (CTRL-S)”. You don’t have to edit anything on the page.

Saving automatically disconnects and reconnects.

deswong · October 29, 2022, 1:14am

I’ve extracted some of my debug here but not sure if there is anything obvious besides the
Socket.IO Disconnected: ping timeout
messages that are useful for debugging/troubleshooting?

I’ve noticed through the days this is happening, despite the message that it is disconnected in myopenhab.org, the message notifications still come through.

The last notification in this log extract shows that I am connected to the cloud service, but on myopenhab.org it is reporting that my instance of openHAB is offline.

Let me know if there is more detailed information you need to help diagnose the issue.

2022-10-28 18:43:24.415 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-10-28 18:43:24.418 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : ConfigurableComponentHolder configuration updated for pid org.openhab.openhabcloud with change count 9
2022-10-28 18:43:24.418 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : 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-10-28 18:43:24.419 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Dependency Manager created $001interface=org.openhab.core.items.ItemRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1
2022-10-28 18:43:24.419 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Dependency Manager created $002interface=org.openhab.core.events.EventPublisher, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=2
2022-10-28 18:43:24.419 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Component created: DS=DS14, implementation=org.openhab.io.openhabcloud.internal.CloudService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=modified configuration-pid=[org.openhab.openhabcloud]
2022-10-28 18:43:24.420 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Component Services: scope=singleton, services=[org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService]
2022-10-28 18:43:24.421 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Component Properties: {service.config.label=openHAB Cloud, service.config.category=io, service.config.description.uri=io:openhabcloud, service.config.factory=false, service.pid=org.openhab.openhabcloud}
2022-10-28 18:43:24.421 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Querying state disabled
2022-10-28 18:43:24.422 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Querying state disabled
2022-10-28 18:43:24.422 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Component can not be activated since it is in state disabled
2022-10-28 18:43:24.423 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService] : Querying state disabled
2022-10-28 18:43:24.423 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Updating target filters
2022-10-28 18:43:24.424 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : No change in target property for dependency $000: currently registered: false
2022-10-28 18:43:24.424 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] :  No existing service listener to unregister for dependency $000
2022-10-28 18:43:24.425 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Setting target property for dependency $000 to null
2022-10-28 18:43:24.425 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : 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-10-28 18:43:24.426 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $000 tracker reset (closed)
2022-10-28 18:43:24.427 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=272, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=149} (enter)
2022-10-28 18:43:24.427 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-10-28 18:43:24.428 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=272, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=149} (exit)
2022-10-28 18:43:24.429 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $000 tracker opened
2022-10-28 18:43:24.429 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : registering service listener for dependency $000
2022-10-28 18:43:24.430 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : No change in target property for dependency $001: currently registered: false
2022-10-28 18:43:24.430 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] :  No existing service listener to unregister for dependency $001
2022-10-28 18:43:24.431 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Setting target property for dependency $001 to null
2022-10-28 18:43:24.431 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.items.ItemRegistry), initialReferenceFilter (objectClass=org.openhab.core.items.ItemRegistry)
2022-10-28 18:43:24.432 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $001 tracker reset (closed)
2022-10-28 18:43:24.433 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=186, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=29} (enter)
2022-10-28 18:43:24.433 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-10-28 18:43:24.434 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=186, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=29} (exit)
2022-10-28 18:43:24.437 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $001 tracker opened
2022-10-28 18:43:24.448 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : registering service listener for dependency $001
2022-10-28 18:43:24.449 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : No change in target property for dependency $002: currently registered: false
2022-10-28 18:43:24.455 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] :  No existing service listener to unregister for dependency $002
2022-10-28 18:43:24.459 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Setting target property for dependency $002 to null
2022-10-28 18:43:24.460 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.events.EventPublisher), initialReferenceFilter (objectClass=org.openhab.core.events.EventPublisher)
2022-10-28 18:43:24.461 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $002 tracker reset (closed)
2022-10-28 18:43:24.462 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.events.EventPublisher}={service.id=174, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25} (enter)
2022-10-28 18:43:24.463 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2022-10-28 18:43:24.464 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.events.EventPublisher}={service.id=174, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25} (exit)
2022-10-28 18:43:24.464 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : dm $002 tracker opened
2022-10-28 18:43:24.465 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : registering service listener for dependency $002
2022-10-28 18:43:24.465 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Changed state from disabled to unsatisfiedReference
2022-10-28 18:43:24.465 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Component enabled
2022-10-28 18:43:24.466 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : ActivateInternal
2022-10-28 18:43:24.466 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.467 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.467 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Activating component from state unsatisfiedReference
2022-10-28 18:43:24.469 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.469 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.470 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Changed state from unsatisfiedReference to satisfied
2022-10-28 18:43:24.470 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : registration change queue [registered]
2022-10-28 18:43:24.472 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Checking constructor public org.openhab.io.openhabcloud.internal.CloudService(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.items.ItemRegistry,org.openhab.core.events.EventPublisher)
2022-10-28 18:43:24.472 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getReferenceClass: Looking for interface class org.openhab.core.io.net.http.HttpClientFactory through loader of org.openhab.io.openhabcloud.internal.CloudService
2022-10-28 18:43:24.498 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getParameterClass: Found class org.openhab.core.io.net.http.HttpClientFactory
2022-10-28 18:43:24.498 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getReferenceClass: Looking for interface class org.openhab.core.items.ItemRegistry through loader of org.openhab.io.openhabcloud.internal.CloudService
2022-10-28 18:43:24.498 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getParameterClass: Found class org.openhab.core.items.ItemRegistry
2022-10-28 18:43:24.500 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getReferenceClass: Looking for interface class org.openhab.core.events.EventPublisher through loader of org.openhab.io.openhabcloud.internal.CloudService
2022-10-28 18:43:24.500 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getParameterClass: Found class org.openhab.core.events.EventPublisher
2022-10-28 18:43:24.501 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Found constructor with 3 arguments : public org.openhab.io.openhabcloud.internal.CloudService(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.items.ItemRegistry,org.openhab.core.events.EventPublisher)
2022-10-28 18:43:24.518 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : This thread collected dependencies
2022-10-28 18:43:24.518 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getService (single component manager) dependencies collected.
2022-10-28 18:43:24.519 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state satisfied
2022-10-28 18:43:24.529 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Querying state satisfied
2022-10-28 18:43:24.530 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : 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=272, service.bundleid=173, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=149}] service: [null]]]
2022-10-28 18:43:24.531 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.items.ItemRegistry}={service.id=186, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=29}] service: [null]]]
2022-10-28 18:43:24.541 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.events.EventPublisher}={service.id=174, service.bundleid=148, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25}] service: [null]]]
2022-10-28 18:43:24.544 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : getting activate: activate
2022-10-28 18:43:24.545 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Locating method activate in class org.openhab.io.openhabcloud.internal.CloudService
2022-10-28 18:43:24.547 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Declared Method org.openhab.io.openhabcloud.internal.CloudService.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.548 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Found activate method: protected void org.openhab.io.openhabcloud.internal.CloudService.activate(org.osgi.framework.BundleContext,java.util.Map)
2022-10-28 18:43:24.554 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : invoking activate: activate: parameters [org.eclipse.osgi.internal.framework.BundleContextImpl, org.apache.felix.scr.impl.helper.ReadOnlyDictionary]
2022-10-28 18:43:24.554 [DEBUG] [o.openhabcloud.internal.CloudService] - openHAB Cloud connector activated
2022-10-28 18:43:24.556 [DEBUG] [o.openhabcloud.internal.CloudService] - Using secret at '/var/lib/openhab/openhabcloud/secret' with content '<secretkey>'
2022-10-28 18:43:24.556 [DEBUG] [o.openhabcloud.internal.CloudService] - UUID = <UUID>, secret = <secretkey>
2022-10-28 18:43:24.593 [DEBUG] [o.openhabcloud.internal.CloudService] - Using secret at '/var/lib/openhab/openhabcloud/secret' with content '<secretkey>'
2022-10-28 18:43:24.622 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : invoked activate: activate
2022-10-28 18:43:24.623 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Set implementation object for component
2022-10-28 18:43:24.624 [DEBUG] [o.openhabcloud.internal.CloudService] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.CloudService(301)] : Changed state from satisfied to active
2022-10-28 18:43:24.625 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Component created: DS=DS13, implementation=org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider]
2022-10-28 18:43:24.631 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Component Services: scope=singleton, services=[org.openhab.core.automation.type.ModuleTypeProvider]
2022-10-28 18:43:24.631 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Component Properties: {}
2022-10-28 18:43:24.631 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Querying state disabled
2022-10-28 18:43:24.631 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Querying state disabled
2022-10-28 18:43:24.631 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Component can not be activated since it is in state disabled
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider] : Querying state disabled
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Updating target filters
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Changed state from disabled to unsatisfiedReference
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Component enabled
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : ActivateInternal
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Activating component from state unsatisfiedReference
2022-10-28 18:43:24.632 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.639 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.639 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Changed state from unsatisfiedReference to satisfied
2022-10-28 18:43:24.639 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : registration change queue [registered]
2022-10-28 18:43:24.640 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Checking constructor public org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider()
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Found constructor with 0 arguments : public org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider()
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : This thread collected dependencies
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : getService (single component manager) dependencies collected.
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state satisfied
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Querying state satisfied
2022-10-28 18:43:24.641 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : getting activate: activate
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Locating method activate in class org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Declared Method org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Locating method activate in class java.lang.Object
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : activate method [activate] not found, ignoring
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Set implementation object for component
2022-10-28 18:43:24.642 [DEBUG] [tions.NotificationActionTypeProvider] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationActionTypeProvider(302)] : Changed state from satisfied to active
2022-10-28 18:43:24.643 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Dependency Manager created $000interface=org.openhab.io.openhabcloud.internal.CloudService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2022-10-28 18:43:24.643 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory]
2022-10-28 18:43:24.643 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.automation.handler.ModuleHandlerFactory]
2022-10-28 18:43:24.643 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Component Properties: {}
2022-10-28 18:43:24.643 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Querying state disabled
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Querying state disabled
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Component can not be activated since it is in state disabled
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory] : Querying state disabled
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Updating target filters
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : No change in target property for dependency $000: currently registered: false
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] :  No existing service listener to unregister for dependency $000
2022-10-28 18:43:24.644 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Setting target property for dependency $000 to null
2022-10-28 18:43:24.645 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.io.openhabcloud.internal.CloudService), initialReferenceFilter (objectClass=org.openhab.io.openhabcloud.internal.CloudService)
2022-10-28 18:43:24.645 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : dm $000 tracker reset (closed)
2022-10-28 18:43:24.645 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : dm $000 tracking 1 SingleStatic added {org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService}={service.id=495, mode=remote, service.bundleid=270, service.scope=bundle, expose=[backyardlightpower, DesRoomFan_PowerSwitch, DesBedLight_PowerSwitch, Hallway_DimmerHallway, KitchenLight_DimmerKitchen, FrontStairLight, LoungeFloorFan_PowerSwitch, LoungeLight_PowerSwitch, MillieRoomFan_PowerSwitch, MillieBedLight_PowerSwitch, PatioController_PatioFan, PatioController_PatioLight], component.name=org.openhab.io.openhabcloud.internal.CloudService, service.config.label=openHAB Cloud, component.id=301, service.config.factory=false, baseURL=https://myopenhab.org, service.config.category=io, service.config.description.uri=io:openhabcloud, service.pid=[org.openhab.openhabcloud, org.openhab.openhabcloud]} (enter)
2022-10-28 18:43:24.645 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-10-28 18:43:24.646 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : dm $000 tracking 1 SingleStatic added {org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService}={service.id=495, mode=remote, service.bundleid=270, service.scope=bundle, expose=[backyardlightpower, DesRoomFan_PowerSwitch, DesBedLight_PowerSwitch, Hallway_DimmerHallway, KitchenLight_DimmerKitchen, FrontStairLight, LoungeFloorFan_PowerSwitch, LoungeLight_PowerSwitch, MillieRoomFan_PowerSwitch, MillieBedLight_PowerSwitch, PatioController_PatioFan, PatioController_PatioLight], component.name=org.openhab.io.openhabcloud.internal.CloudService, service.config.label=openHAB Cloud, component.id=301, service.config.factory=false, baseURL=https://myopenhab.org, service.config.category=io, service.config.description.uri=io:openhabcloud, service.pid=[org.openhab.openhabcloud, org.openhab.openhabcloud]} (exit)
2022-10-28 18:43:24.646 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : dm $000 tracker opened
2022-10-28 18:43:24.646 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : registering service listener for dependency $000
2022-10-28 18:43:24.646 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Changed state from disabled to unsatisfiedReference
2022-10-28 18:43:24.646 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Component enabled
2022-10-28 18:43:24.647 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : ActivateInternal
2022-10-28 18:43:24.648 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.648 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.666 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Activating component from state unsatisfiedReference
2022-10-28 18:43:24.667 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.668 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state unsatisfiedReference
2022-10-28 18:43:24.668 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Changed state from unsatisfiedReference to satisfied
2022-10-28 18:43:24.669 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : registration change queue [registered]
2022-10-28 18:43:24.670 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Checking constructor public org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(org.openhab.io.openhabcloud.internal.CloudService)
2022-10-28 18:43:24.671 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : getReferenceClass: Looking for interface class org.openhab.io.openhabcloud.internal.CloudService through loader of org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory
2022-10-28 18:43:24.671 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : getParameterClass: Found class org.openhab.io.openhabcloud.internal.CloudService
2022-10-28 18:43:24.672 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Found constructor with 1 arguments : public org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(org.openhab.io.openhabcloud.internal.CloudService)
2022-10-28 18:43:24.672 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : This thread collected dependencies
2022-10-28 18:43:24.673 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : getService (single component manager) dependencies collected.
2022-10-28 18:43:24.673 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state satisfied
2022-10-28 18:43:24.674 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Querying state satisfied
2022-10-28 18:43:24.674 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService}={service.id=495, mode=remote, service.bundleid=270, service.scope=bundle, expose=[backyardlightpower, DesRoomFan_PowerSwitch, DesBedLight_PowerSwitch, Hallway_DimmerHallway, KitchenLight_DimmerKitchen, FrontStairLight, LoungeFloorFan_PowerSwitch, LoungeLight_PowerSwitch, MillieRoomFan_PowerSwitch, MillieBedLight_PowerSwitch, PatioController_PatioFan, PatioController_PatioLight], component.name=org.openhab.io.openhabcloud.internal.CloudService, service.config.label=openHAB Cloud, component.id=301, service.config.factory=false, baseURL=https://myopenhab.org, service.config.category=io, service.config.description.uri=io:openhabcloud, service.pid=[org.openhab.openhabcloud, org.openhab.openhabcloud]}] service: [null]]]
2022-10-28 18:43:24.675 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : getting activate: activate
2022-10-28 18:43:24.676 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Locating method activate in class org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory
2022-10-28 18:43:24.676 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Declared Method org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.677 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Locating method activate in class org.openhab.core.automation.handler.BaseModuleHandlerFactory
2022-10-28 18:43:24.678 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Declared Method org.openhab.core.automation.handler.BaseModuleHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.678 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Locating method activate in class java.lang.Object
2022-10-28 18:43:24.679 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-10-28 18:43:24.679 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : activate method [activate] not found, ignoring
2022-10-28 18:43:24.680 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Set implementation object for component
2022-10-28 18:43:24.680 [DEBUG] [ons.NotificationModuleHandlerFactory] - bundle org.openhab.io.openhabcloud:3.3.0 (270)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(303)] : Changed state from satisfied to active
2022-10-28 18:43:24.795 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connecting
2022-10-28 18:43:26.951 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connected
2022-10-28 18:43:26.963 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-28 18:43:28.065 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'LoungeLight_PowerSwitch'
2022-10-28 18:43:28.066 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'FrontStairLight'
2022-10-28 18:57:52.331 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2022-10-28 18:57:52.331 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-28 18:57:53.099 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 1)
2022-10-28 18:57:54.725 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connected successfully (attempt 1)
2022-10-28 18:57:55.050 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connected
2022-10-28 18:57:55.050 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-28 19:13:27.924 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'KitchenLight_DimmerKitchen'
2022-10-28 19:13:27.963 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'KitchenLight_DimmerKitchen'
2022-10-28 19:15:12.300 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 19:15:12.316 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 19:18:27.923 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-28 19:18:27.932 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-28 19:19:12.299 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '3' for item 'Hallway_DimmerHallway'
2022-10-28 19:19:12.316 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '3' for item 'Hallway_DimmerHallway'
2022-10-28 19:20:22.160 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeFloorFan_PowerSwitch'
2022-10-28 19:20:22.307 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeFloorFan_PowerSwitch'
2022-10-28 19:20:26.433 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-28 19:20:26.495 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-28 19:22:40.077 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioFan'
2022-10-28 19:22:40.210 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioFan'
2022-10-28 19:31:08.990 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'DesBedLight_PowerSwitch'
2022-10-28 19:31:08.996 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 19:31:09.013 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 19:31:09.110 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'DesBedLight_PowerSwitch'
2022-10-28 20:10:50.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-28 20:10:50.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1080602
2022-10-28 20:10:50.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/KitchenLight_DimmerKitchen
2022-10-28 20:10:50.034 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method GET
2022-10-28 20:10:50.034 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"host":"myopenhab.org","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-28 20:10:50.034 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query {"metadata":"ga,synonyms"}
2022-10-28 20:10:50.034 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is GET
2022-10-28 20:10:50.035 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-28 20:10:50.035 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-28 20:10:50.035 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-28 20:10:50.058 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1080602
2022-10-28 20:10:50.059 [DEBUG] [io.openhabcloud.internal.CloudClient] - onResponseContent: 1080602, content size 409
2022-10-28 20:10:50.061 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1080602
2022-10-28 20:10:50.062 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1080602
2022-10-28 20:52:33.790 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-28 20:52:33.791 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-28 20:52:48.875 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 20:52:48.905 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 20:52:56.187 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'LoungeLight_PowerSwitch'
2022-10-28 20:52:56.381 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'LoungeLight_PowerSwitch'
2022-10-28 20:56:48.874 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 20:56:48.896 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 20:57:27.535 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 20:57:27.555 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 21:03:05.601 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1101784
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/MillieRoomFan_PowerSwitch
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method POST
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"content-length":"2","host":"myopenhab.org","content-type":"text/plain","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query null
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is POST
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-length = 2
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-28 21:03:05.602 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-type = text/plain
2022-10-28 21:03:05.603 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-28 21:03:05.603 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-28 21:03:05.613 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1101784
2022-10-28 21:03:05.614 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1101784
2022-10-28 21:03:05.614 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1101784
2022-10-28 21:03:05.616 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'MillieRoomFan_PowerSwitch'
2022-10-28 21:03:05.808 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'MillieRoomFan_PowerSwitch'
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1101915
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/PatioController_PatioLight
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method POST
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"content-length":"2","host":"myopenhab.org","content-type":"text/plain","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query null
2022-10-28 21:03:27.312 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is POST
2022-10-28 21:03:27.313 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-length = 2
2022-10-28 21:03:27.313 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-28 21:03:27.313 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-type = text/plain
2022-10-28 21:03:27.313 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-28 21:03:27.313 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-28 21:03:27.316 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1101915
2022-10-28 21:03:27.318 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'PatioController_PatioLight'
2022-10-28 21:03:27.323 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1101915
2022-10-28 21:03:27.323 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1101915
2022-10-28 21:03:27.398 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'PatioController_PatioLight'
2022-10-28 21:05:09.097 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 21:05:09.109 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 21:41:02.790 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 21:41:02.838 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '80' for item 'Hallway_DimmerHallway'
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1121523
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/MillieBedLight_PowerSwitch
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method POST
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"content-length":"3","host":"myopenhab.org","content-type":"text/plain","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query null
2022-10-28 21:43:54.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is POST
2022-10-28 21:43:54.583 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-length = 3
2022-10-28 21:43:54.583 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-28 21:43:54.583 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-type = text/plain
2022-10-28 21:43:54.583 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-28 21:43:54.583 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-28 21:43:54.587 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1121523
2022-10-28 21:43:54.588 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1121523
2022-10-28 21:43:54.588 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1121523
2022-10-28 21:43:54.590 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieBedLight_PowerSwitch'
2022-10-28 21:43:54.596 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeLight_PowerSwitch'
2022-10-28 21:43:54.601 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-28 21:43:54.603 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeFloorFan_PowerSwitch'
2022-10-28 21:43:54.606 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 21:43:54.609 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-28 21:43:54.615 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 21:43:54.642 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-28 21:43:54.664 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-28 21:43:54.667 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeFloorFan_PowerSwitch'
2022-10-28 21:43:54.668 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeLight_PowerSwitch'
2022-10-28 21:43:54.683 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieBedLight_PowerSwitch'
2022-10-28 21:45:02.791 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-28 21:45:02.814 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1172390
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/KitchenLight_DimmerKitchen
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method GET
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"host":"myopenhab.org","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query {"metadata":"ga,synonyms"}
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is GET
2022-10-29 00:08:15.541 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-29 00:08:15.542 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-29 00:08:15.542 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-29 00:08:15.548 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1172390
2022-10-29 00:08:15.549 [DEBUG] [io.openhabcloud.internal.CloudClient] - onResponseContent: 1172390, content size 409
2022-10-29 00:08:15.549 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1172390
2022-10-29 00:08:15.549 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1172390
2022-10-29 01:51:08.135 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2022-10-29 01:51:08.136 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 01:51:09.594 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 1)
2022-10-29 01:51:19.976 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 01:51:19.976 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 01:51:22.513 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 2)
2022-10-29 01:51:32.524 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 01:51:32.525 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 01:51:34.806 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 3)
2022-10-29 01:51:44.190 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connected successfully (attempt 3)
2022-10-29 01:51:44.518 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connected
2022-10-29 01:51:44.518 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 04:15:20.155 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2022-10-29 04:15:20.155 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 04:15:21.319 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 1)
2022-10-29 04:15:31.538 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 04:15:31.539 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 04:15:32.733 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 2)
2022-10-29 04:15:42.744 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 04:15:42.744 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 04:15:47.745 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 3)
2022-10-29 04:15:55.028 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connected successfully (attempt 3)
2022-10-29 04:15:55.354 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connected
2022-10-29 04:15:55.354 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 04:52:00.005 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieBedLight_PowerSwitch'
2022-10-29 04:52:00.025 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'FrontStairLight'
2022-10-29 04:52:00.031 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'DesBedLight_PowerSwitch'
2022-10-29 04:52:00.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeLight_PowerSwitch'
2022-10-29 04:52:00.034 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-29 04:52:00.037 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-29 04:52:00.041 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-29 04:52:00.066 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'KitchenLight_DimmerKitchen'
2022-10-29 04:52:00.088 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update '0' for item 'Hallway_DimmerHallway'
2022-10-29 04:52:00.101 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'PatioController_PatioLight'
2022-10-29 04:52:00.128 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieBedLight_PowerSwitch'
2022-10-29 04:52:00.129 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'DesBedLight_PowerSwitch'
2022-10-29 04:52:00.131 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'FrontStairLight'
2022-10-29 04:52:00.182 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'LoungeLight_PowerSwitch'
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - on(): request
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - Got request 1308241
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - Path /rest/items/MillieRoomFan_PowerSwitch
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - Method POST
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - Headers: {"content-length":"3","host":"myopenhab.org","content-type":"text/plain","accept":"application/json","user-agent":"openhab-cloud/0.0.1"}
2022-10-29 08:10:43.977 [DEBUG] [io.openhabcloud.internal.CloudClient] - Query null
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Request method is POST
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-length = 3
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header host = myopenhab.org
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header content-type = text/plain
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header accept = application/json
2022-10-29 08:10:43.978 [DEBUG] [io.openhabcloud.internal.CloudClient] - Jetty set header user-agent = openhab-cloud/0.0.1
2022-10-29 08:10:43.981 [DEBUG] [io.openhabcloud.internal.CloudClient] - onHeaders 1308241
2022-10-29 08:10:43.982 [DEBUG] [io.openhabcloud.internal.CloudClient] - onComplete: 1308241
2022-10-29 08:10:43.982 [DEBUG] [io.openhabcloud.internal.CloudClient] - Finished responding to request 1308241
2022-10-29 08:10:43.984 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieRoomFan_PowerSwitch'
2022-10-29 08:10:44.140 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'OFF' for item 'MillieRoomFan_PowerSwitch'
2022-10-29 08:13:43.016 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2022-10-29 08:13:43.016 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 08:13:44.088 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 1)
2022-10-29 08:13:55.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:13:55.582 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:13:57.918 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 2)
2022-10-29 08:14:09.185 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:14:09.185 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:14:14.186 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 3)
2022-10-29 08:14:25.462 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:14:25.462 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:14:30.463 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 4)
2022-10-29 08:14:41.759 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:14:41.760 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:14:46.759 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 5)
2022-10-29 08:14:58.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:14:58.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:15:03.033 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 6)
2022-10-29 08:15:14.346 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:15:14.347 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:15:19.346 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 7)
2022-10-29 08:15:30.627 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:15:30.627 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:15:35.628 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 8)
2022-10-29 08:15:46.908 [DEBUG] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2022-10-29 08:15:46.909 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connect attempt error: SocketIOException Connection error
2022-10-29 08:15:51.910 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connecting (attempt 9)
2022-10-29 08:15:53.511 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO re-connected successfully (attempt 9)
2022-10-29 08:15:53.835 [DEBUG] [io.openhabcloud.internal.CloudClient] - Socket.IO connected
2022-10-29 08:15:53.835 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = <UUID>, base URL = http://localhost:8080)
2022-10-29 08:35:42.296 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'LoungeFloorFan_PowerSwitch'
2022-10-29 08:35:42.471 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'LoungeFloorFan_PowerSwitch'
2022-10-29 08:52:14.041 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-29 08:52:14.041 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-29 09:15:10.745 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'PatioController_PatioFan'
2022-10-29 09:15:10.868 [DEBUG] [io.openhabcloud.internal.CloudClient] - Sending update 'ON' for item 'PatioController_PatioFan'
2022-10-29 10:33:24.125 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Garage has been opened.' to all users
2022-10-29 10:33:24.125 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Garage has been opened.' to all users
2022-10-29 10:34:08.131 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:34:08.131 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:34:50.743 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Garage is now closed.' to all users
2022-10-29 10:34:50.744 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Garage is now closed.' to all users
2022-10-29 10:35:20.351 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:35:20.352 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:35:48.347 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:35:48.347 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:37:21.831 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:37:21.831 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'Alert: Front Gate has been opened.' to all users
2022-10-29 10:50:38.828 [DEBUG] [b.io.openhabcloud.NotificationAction] - sending broadcast notification 'The washing machine requires attention.' to all users
2022-10-29 10:50:38.828 [DEBUG] [o.openhabcloud.internal.CloudService] - Sending broadcast message 'The washing machine requires attention.' to all users

ssalonen · October 29, 2022, 6:37am

Based on the reports here, I am seeing few things here

Cloud tracking the online/offline status seems to have issues still: connected• clients might be treated as “offline”. Not completely resolved by [WIP] updated disconnection logic and clean up unused noise by digitaldan · Pull Request #381 · openhab/openhab-cloud · GitHub
Cloud seems to have load issues Check, if openHAB cloud Connector is online? - #14 by nh905 could this explain some of the glitches with 1)
We seem to have more ping timeouts on client end? Probably not an issue if we would not have the issue above?

• they are truly connected since cloud notifications work

LordLiverpool · October 29, 2022, 8:50am

The problem is, when I go to myopenhab.org, my openHab appears as offline.

LordLiverpool · October 29, 2022, 9:00am

I’m getting lots of disconnect/reconnect entries in openhab.log, although each disconnect is apparently followed by a reconnect. This is just from 40 minutes this morning. The thing is, after the last reconnect entry (the last entry referring to the cloud until now), I was left without a cloud connection. If I reboot, it will undoubtedly regain the connection.

2022-10-29 07:27:00.178 [ERROR] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance. Reconnecting.
2022-10-29 07:27:03.599 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:28:53.874 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:30:26.105 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed
2022-10-29 07:30:52.690 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:30:53.574 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed
2022-10-29 07:32:42.281 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:35:43.894 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 07:37:12.957 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:38:59.017 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:40:25.774 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed
2022-10-29 07:41:47.892 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 07:42:43.795 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:44:05.538 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:46:20.308 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:46:49.891 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:46:56.893 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@8f2e54::DecryptedEndPoint@152fe4b{l=/192.168.0.19:48442,r=script.google.com/216.58.209.78:443,OPEN,fill=-,flush=-,to=1545304/0}
2022-10-29 07:47:51.893 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 07:48:39.510 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:53:55.949 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 07:54:22.991 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:56:25.887 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:58:20.373 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:58:57.375 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 07:59:59.911 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 08:00:25.873 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed
2022-10-29 08:01:13.508 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:02:07.681 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:03:25.052 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@1e08dbe::DecryptedEndPoint@1e913f6{l=/192.168.0.19:43786,r=script.googleusercontent.com/216.58.209.65:443,OPEN,fill=-,flush=-,to=2533018/0}
2022-10-29 08:04:47.783 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:06:03.970 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 08:07:31.657 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:08:26.408 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:08:45.275 [ERROR] [io.openhabcloud.internal.CloudClient] - Error connecting to the openHAB Cloud instance. Reconnecting.
2022-10-29 08:10:28.453 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed
2022-10-29 08:11:56.784 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxxx, base URL = http://localhost:8080)
2022-10-29 08:18:12.890 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2022-10-29 08:20:27.895 [ERROR] [enhab.core.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 1000 ms elapsed

Is there a binding I could restart from the code (say, io.openhabcloud.internal.CloudClient)?

sparky70 · October 29, 2022, 10:55am

Hi my openHab keeps disconnecting,what is the issue with the Cloud service?

LordLiverpool · October 29, 2022, 5:27pm

It lost the cloud connection again at 15:47

sparky70 · October 29, 2022, 5:36pm

Mines been doing it for a few days ,maybe a week

At different times .

Openhabian on Raspberry pi 4

Reboot resets it

mancer · October 29, 2022, 7:28pm

I’m running into those issues as well since the last couple of days.

2022-10-29 05:12:45.950 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-10-29 05:12:45.954 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:12:53.118 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:15:10.675 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:16:34.830 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:21:18.657 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:23:35.050 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:34:07.670 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:34:28.963 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:39:15.248 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:43:14.783 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = f80xxxx, base URL = http://localhost:8080)
2022-10-29 05:44:38.936 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:46:35.561 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:51:43.634 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:52:26.780 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:55:53.704 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 05:58:57.466 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 06:01:06.727 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 06:02:36.379 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 06:04:47.293 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)
2022-10-29 06:08:14.286 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxxx, base URL = http://localhost:8080)

@LordLiverpool as stated above you could simply save the openHAB Cloud Connector in MainUI. Or - as I did before knowing this - restart it via the OH console
I’ll set up a rule to send me a push notification whenever the connection is lost (and found again) so I know that I need to restart it manually before the wife notices

LordLiverpool · October 29, 2022, 8:49pm

I tried restarting io.openhabcloud.internal.CloudClient using bundle:restart in a rule when it detects that the cloud is down, but that didn’t seem to do anything. Maybe there’s some other bundle that has to be restarted. Or perhaps only a reboot will do it.

ssalonen · October 30, 2022, 4:00am

@mancer would be interesting to see DEBUG level logs. That will tell reason for disconnect

ssalonen · October 30, 2022, 4:01am

@LordLiverpool there is no such bundle. I believe the bundle is called org.openhab.io.openhabcloud?

LordLiverpool · October 30, 2022, 10:40am

Ahh, I was just going by the log. I’ll try that one.

It disconnected again at 1:03. Again, it incorrectly reports that it reconnected 10 seconds later.

2022-10-30 01:03:23.591 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 27fe6435-e6fb-4d91-9d06-fa1aa97f2bcb, base URL = http://localhost:8080)
2022-10-30 01:03:33.978 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 27fe6435-e6fb-4d91-9d06-fa1aa97f2bcb, base URL = http://localhost:8080)

I’ll see if I can activate debug logging.

supersjel · October 30, 2022, 11:27am

Are the servers a bit more unstable the last weeks? I’ve noticed the last month (estimate) that google will say that ‘openhab is unreachable’ (not often, one or two times a week). Usually after a few minutes it responds again. Before this happened rarely…

(edit: ah, seems I wasn’t looking at the last post … so let me rephrase the above: also experience this )

nh905 · October 30, 2022, 3:59pm

My monitoring flagged 17 issues with the cloud connector starting after 19:30 EDT on 2022/10/28 and ending before 00:45 on 2022/10/29. During that time, I was able to login to myopenhab.org - it indicated that I was offline. Most of the issues were timeouts (timeout was five seconds) - my Internet monitor showed no issues from my end. There were some error 500 “Server Error: openHAB is offline for url: https://myopenhab.org/rest/items/…” issues, which I saw when I disabled the cloud connector at my end during testing.

I monitor every 15 minutes. Of the 20 samples, three succeeded without errors, two after a single error attempting to reach myopenhab.org, and eight after one restart of the cloud connector. In seven attempts, two restarts were attempted - there were four consecutive double restart attempts between 22:15 and 23:00. The issues disappeared sometime after 00:30 - there was nothing in my openhab logs between 00:30 and 00:45 that suggested anything changed at my end. Since then, no further issues have been detected.

nh905 · October 30, 2022, 4:09pm

I am not able to change the log level to DEBUG. From openhab-cli console:

openhab> log:get org.openhab.io.openhabcloud
INFO
openhab> log:set DEBUG  org.openhab.io.openhabcloud
Error executing command: Unable to set level for logger

I am running OpenHAB 3.3.0 upgraded from OpenHAB 3.2.0. It might be related to log:set doesn't work when manually edited log4j2.xml exists · Issue #1217 · openhab/openhab-distro · GitHub (possibly fixed in OH 3.4.0-SNAPSHOT) or a permissions issues (have not had the time to dig further).

LordLiverpool · October 31, 2022, 9:32am

Yesterday and overnight I didn’t see any further problems, so the bundle restart hasn’t had a chance to be tested.