Hi all,
openhab 3.3.0 and cloud connector configured.
Sometime, in the past very often, now a couple of time a week, i got cloud connector not working.
Here is the last log collected. This morning around 10:29 connector stop to work:
2023-01-12 10:29:37.388 INFO io.openhabcloud.internal.CloudClient Disconnected from the openHAB Cloud service (UUID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, base URL = http://localhost:8080)
2023-01-12 10:29:38.889 DEBUG io.openhabcloud.internal.CloudClient Socket.IO re-connecting (attempt 1)
2023-01-12 10:29:49.013 DEBUG io.openhabcloud.internal.CloudClient Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Should reconnect automatically.
2023-01-12 10:29:49.014 DEBUG io.openhabcloud.internal.CloudClient Socket.IO re-connect attempt error: SocketIOException Connection error
2023-01-12 10:29:50.566 DEBUG io.openhabcloud.internal.CloudClient Socket.IO re-connecting (attempt 2)
2023-01-12 10:29:57.590 DEBUG io.openhabcloud.internal.CloudClient Socket.IO re-connected successfully (attempt 2)
2023-01-12 10:30:07.597 ERROR io.openhabcloud.internal.CloudClient Error connecting to the openHAB Cloud instance: EngineIOException xhr poll error. Reconnecting after 1064 ms.
2023-01-12 10:30:08.664 DEBUG io.openhabcloud.internal.CloudClient Socket.IO connecting
2023-01-12 10:30:17.816 DEBUG io.openhabcloud.internal.CloudClient Socket.IO connected
2023-01-12 10:30:17.816 INFO io.openhabcloud.internal.CloudClient Connected to the openHAB Cloud service (UUID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, base URL = http://localhost:8080)
Even if log say Connected, connector does not work. Google Home says me “Openhab is not available” and on https://myopenhab.org/ i see that is offline.
To restore the situation I have to access to Interface->System Service->OpenHab Cloud and Do Save
At this point all go back work again and I got this into logs:
2023-01-12 12:04:41.973 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : ConfigurableComponentHolder configuration updated for pid org.openhab.openhabcloud with change count 7
2023-01-12 12:04:41.974 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Querying state active
2023-01-12 12:04:41.975 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Querying state active
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : getting modified: modified
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Locating method modified in class org.openhab.io.openhabcloud.internal.CloudService
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Declared Method org.openhab.io.openhabcloud.internal.CloudService.modified([interface org.osgi.service.component.ComponentContext]) not found
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Found modified method: protected void org.openhab.io.openhabcloud.internal.CloudService.modified(java.util.Map)
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : invoking modified: modified: parameters [org.apache.felix.scr.impl.helper.ReadOnlyDictionary]
2023-01-12 12:04:41.976 DEBUG o.openhabcloud.internal.CloudService remoteAccessEnabled is not set, keeping value 'true'
2023-01-12 12:04:41.977 DEBUG o.openhabcloud.internal.CloudService Using secret at '/openhab/userdata/openhabcloud/secret' with content 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXX'
2023-01-12 12:04:41.977 DEBUG o.openhabcloud.internal.CloudService UUID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, secret = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX
2023-01-12 12:04:41.978 INFO io.openhabcloud.internal.CloudClient Shutting down openHAB Cloud service connection
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService Using secret at '/openhab/userdata/openhabcloud/secret' with content 'XXXXXXXXXXXXXXXXXXXXXXXXXXXXX'
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : invoked modified: modified
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : No change in target property for dependency $000: currently registered: true
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : No change in target property for dependency $001: currently registered: true
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : No change in target property for dependency $002: currently registered: true
2023-01-12 12:04:41.978 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : Querying state active
2023-01-12 12:04:41.979 DEBUG io.openhabcloud.internal.CloudClient Socket.IO disconnected: io client disconnect
2023-01-12 12:04:41.979 INFO io.openhabcloud.internal.CloudClient Disconnected from the openHAB Cloud service (UUID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, base URL = http://localhost:8080)
2023-01-12 12:04:41.983 DEBUG ons.NotificationModuleHandlerFactory bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(334)] : dm $000 tracking 2 SingleStatic modified {org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService}={service.config.factory=false, service.id=572, service.bundleid=297, service.scope=bundle, service.config.category=io, service.pid=[org.openhab.openhabcloud, org.openhab.openhabcloud], service.config.label=openHAB Cloud, component.name=org.openhab.io.openhabcloud.internal.CloudService, service.config.description.uri=io:openhabcloud, component.id=332} (enter)
2023-01-12 12:04:41.983 DEBUG ons.NotificationModuleHandlerFactory bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.actions.NotificationModuleHandlerFactory(334)] : dm $000 tracking 2 SingleStatic modified {org.openhab.io.openhabcloud.internal.CloudService, org.openhab.core.events.EventSubscriber, org.openhab.core.model.script.engine.action.ActionService}={service.config.factory=false, service.id=572, service.bundleid=297, service.scope=bundle, service.config.category=io, service.pid=[org.openhab.openhabcloud, org.openhab.openhabcloud], service.config.label=openHAB Cloud, component.name=org.openhab.io.openhabcloud.internal.CloudService, service.config.description.uri=io:openhabcloud, component.id=332} (exit)
2023-01-12 12:04:41.983 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : ImmediateComponentHolder Finished configuring the dependency managers for component for pid org.openhab.openhabcloud
2023-01-12 12:04:41.983 DEBUG io.openhabcloud.internal.CloudClient Socket.IO connecting
2023-01-12 12:04:41.983 DEBUG o.openhabcloud.internal.CloudService bundle org.openhab.io.openhabcloud:3.3.0 (297)[org.openhab.io.openhabcloud.internal.CloudService(332)] : ImmediateComponentHolder Will not enable component for pid org.openhab.openhabcloud: holder enabled state: true, metadata enabled: true
2023-01-12 12:04:42.277 DEBUG io.openhabcloud.internal.CloudClient Socket.IO connected
2023-01-12 12:04:42.277 INFO io.openhabcloud.internal.CloudClient Connected to the openHAB Cloud service (UUID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXX, base URL = http://localhost:8080)
Thanks in advance for looking into this.
Marco