-
Notifications
You must be signed in to change notification settings - Fork 1
New issue
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.
Already on GitHub? Sign in to your account
[Bosch Smart Home Binding] Status of Bosch-Items not updated when Bosch-Devices triggert #109
Comments
@csprang Thanks for your report! :) Could you try the release candidate of our binding? https://github.com/stefan-kaestle/openhab2-addons/releases/tag/v1.2-rc1 There seemed to be an issue with version 1.1 of our binding and openHAB 3.1. It should be fixed with version 1.2. |
@coeing, thanks for your quick answer and help!! I installed your release candidate V1.2 on my openHAB 3.1 ... my actual test experience is that the WindowContact-Devices are working now, even the Twinguard, I deleted the "old items" and created new ones which I bind to the new Bosch Binding (V1.2). That are my first observations but I will have a deeper look why/when the Items stopps working . best regards |
Hi @csprang, Thanks for your update. Glad that it at least kind of works for you :) Could you describe concretely what you mean with "getting out of sync". E.g. which device, which channel, what value does it show and which value would you expect? That might help with reproducing the error. |
Hi @coeing, A Short cut from my testszenario... Environment: Test-Setup: Test-Szenario: 2021-08-17 21:25:01.626 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'BSHSwitch01_Betrieb' received command ON 2.) Toggle the "Light" Item to off, the physical light gets dark and 2021-08-17 21:41:54.556 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'BSHSwitch01_Betrieb' received command OFF Can be found in the debuglist of openhab. 3.) using the physical switch at the Wall => the physical lights turned on/off but NO EVEN.ITEM Statement can be found in the debuglist 4.) using the Orginal BOSCH-APP on my iPhone => Same Result as 3.) Szenario 1 & 2 are working as expected ... in szenario 3 & 4 the updated Status of the Channel "BSHSwitch01_Betrieb" is missing - the Channel-Status ist still "OFF" Even the Lights are already on (that what I mean with out of Sync). It Looks that the Communication between BOSCH-Binding and BOSCH-Controller is just unidirectional (CHANNEL to BRIDGE) but no bidirectional. Same Observation I also did for other BOSCH-Binding-Things, like "Shutter-Control". Moving the Shutter with the Shutter-Item on PaperUI, I found in the Debug-entries 2021-08-17 21:53:13.416 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'BSHShutter01_Level' received command DOWN And the shutter is closing. But using the physical Button at the wall, the shutter is also closing but on Entry in the OpenHAB debug. Hope this Description helps more to find the issue Regards |
Hi @csprang Thanks for the details about your setup! It's good that it also occurs for the shutter controls, as I have some in my home as well. So I can try to reproduce your issue. I hope that I find time for it soon. Cheers |
@csprang I had time today to test my rollershutters and they still work fine with version 1.2. Could you make sure to enable the logs for our binding? On the console this should be possible with:
That should give you many, many information about what is happening in our binding in the openHAB log. Please do your test again then and post your logs. That should give us a good idea what goes wrong in your setup :) |
Hi @coeing, I set the trace and used the shutter-control and in-wall-switch with the OpenHAB-PaperUI and directly after with the physical button at the wall. While you can find entries in the trace below for the PaperUI-Driving actions (Shutter Down/Up, Switch On/Off), there are no entries created for the Commands triggered by the physical Buttons ... it still looks, that the Bosch-binding is Communicating with the Bosch-Controller, But Not vice versa ... Below the traces for the trial Travel using OpenHAB-UI to control the shutter ... no entries for pushing the physical button 13:25:38.252 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'BSHShutter01_Level' received command DOWN Trace using OpenHAB-UI to switch on/off in-wall-switch ... no entries for pushing the physcial button 13:29:34.093 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'HUEMotionSensor03_LetzteAktualisierung' changed from 2021-08-22T13:28:59.000+0200 to 2021-08-22T13:29:33.000+0200 |
@csprang That's very strange, you should have long polls for the Bosch devices all over the log (every 20 seconds), something like this:
But the long polling (which is the way the things get state updates from the Bosch devices works) does not seem to be active. Could you check if you find any logs from |
Hi @coeing, openhabian@openhabian:~ $ grep -i long /var/log/openhab/*log it seems that there are no other longpollings are active ... |
@csprang Alright, thanks. Could you post the whole logs from those locations? Right now you only posted the locations from where the logs where posted. The log messages are probably in the next lines after the ones you posted. By the way: You can rename or delete the log files before you start openHAB to get smaller log files to search in :) |
@coeing, I tried to attached a ZIP-file with the logs from Openhab - hope you have access to the logs. |
@csprang That helped, thank you! :) Here is the part where the error occurs:
It happens right at the start of the long polling, so it does not surprise that you get no state updates. I will have to investigate what the error means, I have not seen such an error before. |
@csprang Unfortunately you do not seem to have the TRACE logging for our binding enabled ( |
…ling Signed-off-by: Christian Oeing <[email protected]>
Hi there, first of all thanks for the binding and keeping the development for it alive. A great contribution to openHAB. @coeing I would really like to help here. I got the same problem as @csprang , my roller shutters can be controlled from openHAB, but no states are updated when controlled from the in-wall switch itself or the Bosch app. The states of my other devices like window contacts and the wall thermostat are not updated, too. After a reboot it works for approx. 24h and then it suddenly stops. Any further ideas? |
@coeing Ok, I just rebooted my OpenHAB, tracing is enabled, I get log entries like this every 20 seconds like you said. Here the log entry while I applied the in-wall switch of my office shutters: 2021-10-03 17:32:16.124 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-10-03 17:32:16.126 [TRACE] [ernal.devices.bridge.BoschHttpClient] - Create request for http client BoschHttpClient@736fa5{STARTED} 2021-10-03 17:32:16.128 [TRACE] [ernal.devices.bridge.BoschHttpClient] - create request for https://192.168.178.96:8444/remote/json-rpc and content {"jsonrpc":"2.0","method":"RE/longPoll","params":["ejlfh4gbd-537","20"]} ==> /var/log/openhab/events.log <== 2021-10-03 17:32:16.078 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'dOfficeKarstenShutters' changed from 0 to 100 ==> /var/log/openhab/openhab.log <== 2021-10-03 17:32:17.852 [DEBUG] [.internal.devices.bridge.LongPolling] - Long poll response: {"result":[{"path":"/devices/hdm:HomeMaticIP:3014F711A00018DA1858B66F/services/ShutterControl","operations":["decrementOpenLevel","incrementOpenLevel"],"@type":"DeviceServiceData","id":"ShutterControl","state":{"delayCompensationTime":0.7,"referenceMovingTimes":{"movingTimeBottomToTopInMillis":16300,"movingTimeTopToBottomInMillis":15700},"endPositionAutoDetect":true,"delayCompensationSupported":true,"level":0.9,"@type":"shutterControlState","operationState":"STOPPED","endPositionSupported":true,"slatsRunningTimeInMillis":0,"automaticDelayCompensation":false,"calibrated":true},"deviceId":"hdm:HomeMaticIP:3014F711A00018DA1858B66F"}],"jsonrpc":"2.0"} 2021-10-03 17:32:17.855 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Got update for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.857 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA1858B66F - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.861 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Found child: org.openhab.binding.boschshc.internal.devices.shuttercontrol.ShutterControlHandler@7ead25 - calling processUpdate (id: ShutterControl) with {"delayCompensationTime":0.7,"referenceMovingTimes":{"movingTimeBottomToTopInMillis":16300,"movingTimeTopToBottomInMillis":15700},"endPositionAutoDetect":true,"delayCompensationSupported":true,"level":0.9,"@type":"shutterControlState","operationState":"STOPPED","endPositionSupported":true,"slatsRunningTimeInMillis":0,"automaticDelayCompensation":false,"calibrated":true} 2021-10-03 17:32:17.864 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018D87859B117 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.867 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA1858B29B - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.869 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA1858B33E - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.872 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA1858B335 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.874 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA1859150B - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.876 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA18591105 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.879 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA185914EA - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.881 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00018DA185B41CF - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.884 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A18592ABF - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.886 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A185903D6 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.888 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A18590185 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.890 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00000987858913A - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.892 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009BB8595EE4 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.894 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A18592AFD - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.896 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00000987858905B - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.898 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00000987858916F - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.900 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A185A0DFE - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.902 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A0000096D858976B - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.904 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A185A0DFC - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.906 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A18592AF9 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.908 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000009A18592AAE - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.910 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A0000098785891F0 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.911 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A0000098785891C6 - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.913 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A00000987858916E - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.915 [DEBUG] [nternal.devices.bridge.BridgeHandler] - Registered device: hdm:HomeMaticIP:3014F711A000289A185B1D8D - looking for hdm:HomeMaticIP:3014F711A00018DA1858B66F 2021-10-03 17:32:17.917 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-10-03 17:32:17.919 [TRACE] [ernal.devices.bridge.BoschHttpClient] - Create request for http client BoschHttpClient@736fa5{STARTED} 2021-10-03 17:32:17.922 [TRACE] [ernal.devices.bridge.BoschHttpClient] - create request for https://192.168.178.96:8444/remote/json-rpc and content {"jsonrpc":"2.0","method":"RE/longPoll","params":["ejlfh4gbd-537","20"]} ==> /var/log/openhab/events.log <== 2021-10-03 17:32:17.870 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'dOfficeKarstenShutters' changed from 100 to 10 ==> /var/log/openhab/openhab.log <== 2021-10-03 17:32:37.975 [DEBUG] [.internal.devices.bridge.LongPolling] - Long poll response: {"result":[],"jsonrpc":"2.0"} 2021-10-03 17:32:37.978 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-10-03 17:32:37.980 [TRACE] [ernal.devices.bridge.BoschHttpClient] - Create request for http client BoschHttpClient@736fa5{STARTED} 2021-10-03 17:32:37.983 [TRACE] [ernal.devices.bridge.BoschHttpClient] - create request for https://192.168.178.96:8444/remote/json-rpc and content {"jsonrpc":"2.0","method":"RE/longPoll","params":["ejlfh4gbd-537","20"]} |
Sorry, I am not able to properly format my log file content here ... 🤔 @coeing Now we wait until something bad happens? |
Hi @xxthunder, Thanks a lot for your help! :) Your logs look promising. It looks like the info about the moving of your shutters arrived:
It's stranged that it did nothing to the state of the handler. I will look into it. |
Signed-off-by: Christian Oeing <[email protected]>
@csprang @xxthunder It would be great if you could try again with this version: https://github.com/stefan-kaestle/openhab2-addons/releases/tag/v1.2-bugfix.109.1 I added some logs to get more information about where the state update gets stuck. |
@coeing Wow, thanks for the quick update ... here my latest log, I opened and close a window and applied the shutter via in-wall switch again: |
@Coing, Sorry for Not responding long time… I don‘t have access to my OpenHAB Environment at the Moment, but try ist as soon I have the access reestablished, best regards |
@coeing Of course, when you measure and trace nothing happens, till now everything looks fine. OpenHAB receives a lot of data from my SHC and is polling every 20 seconds. Stay tuned. 😄 |
…e to double Signed-off-by: Christian Oeing <[email protected]>
@csprang No problem for the delay, I know we all do it in our free time. And sometimes there's just to little of it :) @xxthunder Thanks a lot for testing, this really helps! I changed a tiny piece which might be the root of the bug. Please try out the new version and let me know if the bug still occurs: https://github.com/stefan-kaestle/openhab2-addons/releases/tag/v1.2-bugfix.109.2 |
@coeing you are welcome, I would love to do more, but as you said, so much to code, so little time. Will try your patch release this weekend. |
@coeing Sorry, vacation and family time ... so far everything worked fine, even without your latest bugfix snapshot. Updated 5 seconds ago, stay tuned. 😄 |
@xxthunder No problem, thanks for testing! I will close the issue for now, please let us know if the bug still occurs :) |
Hey there - im not sure if that issue is identical with this resolved one - so please let me know if i should open a new one.. Afterwards you'll see the logs: 2021-11-05 00:29:01.772 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-11-05 00:29:21.805 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-11-05 00:29:41.843 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-11-05 00:30:00.617 [DEBUG] [.internal.devices.bridge.LongPolling] - Sending long poll request 2021-11-05 00:30:00.691 [DEBUG] [devices.bridge.BoschSHCBridgeHandler] - Got update for null So after this null-pointer bosch does not update any item within the OH-System, until i restart the binding.. |
Dear all,
even I have some of the BOSCH and other Smart Home Equipment for a longer time, I'm quite new with Openhab. I like the Bosch Binding for openhab on my Raspi for implementation of more complex automation.
As @endsideX I also have the problem, that the items of the Bosch Bindings are frozen after a while (#98), so the status of the items is not changed anymore if the Bosch devices (inwall-switch, door-contacts, motion detectors etc.) are triggert outside the openhab-environment.
I use OH 3.1 and updated it twice without a success - while I'm looking for a solution, I found this blog and therefore left this comment. Unfortunately I'm still a beginner with openhab therefore I do not have much experiences with the internals ... but will improve ;-)
regards
Christoph
Expected Behavior
When Bosch-Devices are triggered, like switching on lights manually per in-wall-Switch or open windows with windowcontacts
the respectively status of the Bosch Binding items in openhab 3.1 should be updated
Current Behavior
the status of the Bosch binding items are unchanged, even the status of the Bosch devices was changed manually for example by using the physical in-wall-switch or even with the bosch-app
Your Environment
Raspi 2
openhab 3.1
The text was updated successfully, but these errors were encountered: