Fine Offset Weather Station Binding: Discussion

OK - here’s a new one, although it’s probably an old one? I don’t know - I’ve done my best to find every relevant post in this thread, and I think I’ve got all the info I can get before just saying “help!”.

I’ve just set up an Ecowitt HP2564C station, which uses the new multi-sensor package and is honestly a very elegant and functional piece of kit. Unfortunately, it has been anything but plug-and-play for the FineOffset binding.

I’ve tried to pre-empt the basic testing requests - initially setting the binding to TRACE in the log, and then running the Java proxy and connecting the openHAB binding to that instead of directly to the station. I really hope the info from those two is enough to track down what is going on.

First of all, here’s the output from the log connecting directly to the weather station (which was auto-discovered beautifully, but that was as far as it went).

16:22:19.684 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:22:24.694 [TRACE] [ery.FineOffsetGatewayDiscoveryService] - found 0 measured values via protocol ELV
16:22:24.695 [DEBUG] [ery.FineOffsetGatewayDiscoveryService] - Thing discovered 'DiscoveryResult [thingUID=fineoffsetweatherstation:gateway:813930661, properties={macAddress=30:83:98:A5:54:46, port=45000, ip=192.168.11.149}, representationProperty=macAddress, flag=NEW, label=Weather Station, bridgeUID=null, ttl=-1, timestamp=1669872144694]'
16:22:35.691 [TRACE] [ternal.service.ELVGatewayQueryService] - executeCommand(CMD_WS980_LIVEDATA): send request: FFFF0B0006040419
16:22:35.692 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:22:40.696 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:22:53.090 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to UNKNOWN
16:22:53.095 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SENSOR_ID_NEW): send request: FFFF3C033F
16:22:53.096 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from UNKNOWN to ONLINE
16:22:58.098 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:22:58.102 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_FIRMWARE_VERSION): send request: FFFF500353
16:22:58.103 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:22:58.127 [DEBUG] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_FIRMWARE_VERSION), invalid response: FFFF501915456173795765617468657250726F5F56352E312E30
16:22:58.127 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SSSS): send request: FFFF300333
16:23:03.133 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:23:03.133 [DEBUG] [service.FineOffsetGatewayQueryService] - Unexpected response to System Info!
16:23:03.143 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SENSOR_ID_NEW): send request: FFFF3C033F
16:23:03.143 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:23:08.148 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:23:08.154 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_GW1000_LIVEDATA): send request: FFFF27032A
16:23:08.154 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:23:13.158 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:23:13.163 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_RAIN): send request: FFFF57035A
16:23:13.163 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:23:18.168 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:23:34.181 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_GW1000_LIVEDATA): send request: FFFF27032A
16:23:34.181 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:23:39.182 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:23:39.188 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_RAIN): send request: FFFF57035A
16:23:39.188 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:23:44.192 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:24:00.199 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_GW1000_LIVEDATA): send request: FFFF27032A
16:24:00.199 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:24:05.201 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
16:24:05.207 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_RAIN): send request: FFFF57035A
16:24:05.207 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
16:24:10.210 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:813930661' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out

No channels ever appear or are updated - the Thing state just keeps flopping on and offline.

Now the proxy output:

Try to discover on /192.168.11.255:46000
Found device 'EasyWeatherPro-A55446 V5.1.0' at 192.168.11.149:45000 via /192.168.11.5
Broadcast received FFFF12000416
Req  5	FFFF27032A
Broadcast received FFFF120315
Req  5	FFFF57035A
Req  5	FFFF27032A
Req  5	FFFF57035A
Req  8	FFFF0B0006040419
Req  5	FFFF3C033F
Req  5	FFFF500353
Resp 26	FFFF501915456173795765617468657250726F5F56352E312E30
Req  5	FFFF300333
Req  5	FFFF3C033F
Req  5	FFFF27032A
Req  5	FFFF57035A
Req  5	FFFF27032A
Broadcast received FFFF12000416
Req  5	FFFF27032A

And the corresponding log data (basically identical):

21:40:40.946 [INFO ] [openhab.event.InboxRemovedEvent      ] - Discovery Result with UID 'fineoffsetweatherstation:gateway:0' has been removed.
21:40:40.948 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from UNINITIALIZED to INITIALIZING
21:40:40.950 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from INITIALIZING to UNKNOWN
21:40:40.950 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SENSOR_ID_NEW): send request: FFFF3C033F
21:40:40.951 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from UNKNOWN to ONLINE
21:40:45.956 [WARN ] [.core.thing.internal.ThingManagerImpl] - Initializing handler for thing 'fineoffsetweatherstation:gateway:0' takes more than 5000ms.
21:40:45.956 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:40:45.956 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_FIRMWARE_VERSION): send request: FFFF500353
21:40:45.958 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
21:40:50.958 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:40:50.958 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SSSS): send request: FFFF300333
21:40:50.958 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
21:40:55.962 [DEBUG] [service.FineOffsetGatewayQueryService] - Unexpected response to System Info!
21:40:55.962 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:40:55.963 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_SENSOR_ID_NEW): send request: FFFF3C033F
21:40:55.964 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
21:41:00.968 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:41:00.969 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_GW1000_LIVEDATA): send request: FFFF27032A
21:41:00.969 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
21:41:05.970 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:41:05.970 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_READ_RAIN): send request: FFFF57035A
21:41:05.971 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE
21:41:10.973 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Read timed out
21:41:26.977 [TRACE] [service.FineOffsetGatewayQueryService] - executeCommand(CMD_GW1000_LIVEDATA): send request: FFFF27032A
21:41:26.977 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'fineoffsetweatherstation:gateway:0' changed from OFFLINE (COMMUNICATION_ERROR): Read timed out to ONLINE

I’m a bit worried TBH - it feels like maybe for all my care in trying to get the right kind of station, I may have ended up with one that doesn’t actually communicate properly? Or is it just something that needs some further tweaks to the binding (or my setup) to get it running?