Hallo @Trainer,
have you tried the serial driver jSerialComm driver?
But your log shows an issue with the low level handling. That is hard to reproduce. Here is is a temporary test version that could help. Can you check the other driver and/or the test version?
Thank you. I will try it tomorrow.
hi, I just update to last version, now on log every 30 sec. I see:
2020-01-06 01:37:12.587 [ERROR] [nding.ebus.handler.EBusBridgeHandler] - error!
java.lang.NullPointerException: null
at org.openhab.binding.ebus.handler.EBusBridgeHandler$1.run(EBusBridgeHandler.java:203) [bundleFile:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_222]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:1.8.0_222]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_222]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_222]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
I’m using ebusd protocol. thanks
another question: when do you update the configuration files ? my Unresolved telegrams ratio is over 78%. thanks
Thank you. i tried your new version with jSerialComm but with the same result 
13:00:53.843 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit’ received command 1
13:00:53.851 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1
13:00:53.865 [TRACE] [ebus.service.parser.EBusParserService] - No command method matches the telegram 10 08 B5 10 09 00 00 00 78 FF FF 05 FF 00 A0 00 01 01 9A 00 …
13:00:53.888 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
13:00:53.898 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 …
13:00:53.932 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
13:00:53.978 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
13:00:53.983 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt
13:00:53.987 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
13:00:53.992 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
13:00:54.002 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
13:00:54.010 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
13:00:54.014 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
13:00:54.018 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
13:00:54.024 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
13:00:54.031 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
13:00:54.036 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
13:00:54.040 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
13:00:54.044 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
13:00:54.047 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
13:00:54.051 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
13:00:54.055 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
13:00:54.058 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
13:00:54.061 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 4
13:00:54.065 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
13:00:54.068 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3
13:00:54.071 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2
13:00:54.075 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
13:00:54.078 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
13:00:54.083 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to UNKNOWN
13:00:54.651 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
13:00:54.657 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt
13:00:54.660 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
13:00:54.666 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
13:00:54.677 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
13:00:54.681 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
13:00:54.686 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
13:00:54.691 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
13:00:54.696 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
13:00:54.701 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
13:00:54.705 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
13:00:54.709 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
13:00:54.713 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
13:00:54.719 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
13:00:54.722 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
13:00:54.728 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
13:00:54.731 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
13:00:54.736 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 4
13:00:54.741 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
13:00:54.746 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3
13:00:54.750 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2
13:00:54.755 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
13:00:54.758 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
13:00:54.762 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to UNKNOWN
13:00:54.768 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
13:00:55.325 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
13:00:55.369 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
13:00:55.379 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt
13:00:55.383 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
13:00:55.390 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
13:00:55.401 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
13:00:55.407 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
13:00:55.412 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
13:00:55.418 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
13:00:55.423 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
13:00:55.428 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
13:00:55.434 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
13:00:55.438 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
13:00:55.446 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
13:00:55.452 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
13:00:55.456 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
13:00:55.461 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
13:00:55.466 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
13:00:55.471 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9
13:00:55.476 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
13:00:55.482 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
13:00:55.485 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
13:00:55.490 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
13:00:55.496 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
13:00:55.501 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
13:00:55.507 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …
13:00:55.511 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
13:00:56.055 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
13:00:56.062 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …
Hello @modenet,
it is currently not planed to move all generated configurations to the binding. The quality of the configurations are not good enough. But every one can enhance a configuration file and I will add them later on. The IDs are not standard conform, the identification section is missing for auto discovery etc…
But to your issue, which version do you use? The org.openhab.binding.ebus-2.5.1-3pre.kar version?
Hallo @Trainer,
your log looks like the previous version. Do you really updated to the 3pre version. But maybe my release is not complete.
yes, i stop openhab service, delete the old addon an copy the new one in my addon folder. After that i restart the openhab service again.
Should i also clean the cache ?
No, then I’ve to check my sources. But you run openHAB on Linux?
yes, i run Raspbian on my RPI3
no, I’m using the ebus-2.5.1-2 .
how can I enhance the configuration file in your project to see more data of my devices (VR90 and BAI)?
Hello @Trainer,
the last 3pre version was wrong, it used some older versions in the bundle. Here a newer version with some improvements.
You said that you use a Pi, do you use it with the onboard UART and a direct connected adapter? Or over USB?
I use a serial converter adapter (USB->RS232) on my RPI.
i try your new update, but the problem still exist
here is my log:
19:21:19.116 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit’ received command 1
19:21:19.146 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1
19:21:19.159 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:19.199 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 …
19:21:19.204 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:19.248 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:19.260 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt
19:21:19.270 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:19.282 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:19.293 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:19.302 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision with SYN detected!
19:21:19.310 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …
19:21:19.319 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …
19:21:19.338 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:19.348 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt
19:21:19.357 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:19.367 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:19.381 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:19.389 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
19:21:19.397 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
19:21:19.404 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
19:21:19.417 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
19:21:19.426 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
19:21:19.435 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
19:21:19.444 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
19:21:19.452 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
19:21:19.461 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
19:21:19.470 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
19:21:19.478 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:19.488 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
19:21:19.496 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
19:21:19.507 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
19:21:19.517 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 14
19:21:19.526 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
19:21:19.535 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 13
19:21:19.544 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 12
19:21:19.554 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 11
19:21:19.564 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 10
19:21:19.979 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to UNKNOWN
19:21:20.023 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:20.033 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt
19:21:20.040 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:20.047 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:20.062 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:20.072 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
19:21:20.080 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
19:21:20.087 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
19:21:20.095 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
19:21:20.103 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
19:21:20.110 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
19:21:20.117 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
19:21:20.123 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
19:21:20.129 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
19:21:20.134 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
19:21:20.142 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:20.148 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
19:21:20.153 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
19:21:20.158 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
19:21:20.164 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 14
19:21:20.172 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
19:21:20.181 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 13
19:21:20.188 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 12
19:21:20.195 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 11
19:21:20.210 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 10
19:21:20.646 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to UNKNOWN
19:21:20.690 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:20.699 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 3. attempt
19:21:20.705 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:20.713 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:20.726 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:20.735 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
19:21:20.743 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
19:21:20.752 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
19:21:20.759 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
19:21:20.768 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
19:21:20.775 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
19:21:20.783 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
19:21:20.790 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
19:21:20.796 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
19:21:20.803 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
19:21:20.808 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:20.811 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
19:21:20.814 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
19:21:20.818 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
19:21:20.822 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 14
19:21:20.826 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
19:21:20.829 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 13
19:21:20.832 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 12
19:21:20.836 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 11
19:21:20.840 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 10
19:21:21.314 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to UNKNOWN
19:21:21.360 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:21.370 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 4. attempt
19:21:21.383 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:21.393 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:21.404 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
19:21:21.990 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
19:21:22.000 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 5. attempt
19:21:22.009 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:22.019 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:22.033 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
19:21:22.042 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
19:21:22.627 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:22.636 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 6. attempt
19:21:22.644 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:22.652 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:22.666 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:22.676 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
19:21:22.684 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
19:21:22.694 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
19:21:22.702 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
19:21:22.708 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
19:21:22.718 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
19:21:22.727 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
19:21:22.736 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
19:21:22.745 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
19:21:22.755 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
19:21:22.763 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:22.770 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
19:21:22.778 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
19:21:22.785 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
19:21:22.791 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 14
19:21:22.799 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
19:21:22.806 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 13
19:21:22.813 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 12
19:21:22.821 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 11
19:21:22.827 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 10
19:21:23.272 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to UNKNOWN
19:21:23.813 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:23.825 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 7. attempt
19:21:23.833 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:23.840 [DEBUG] [ore.connection.AbstractEBusConnection] - InputBuffer is not empty before sending: 5 bytes waiting !
19:21:23.849 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:23.856 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
19:21:23.863 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision detected! 0x01
19:21:23.869 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …
19:21:23.876 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …
19:21:23.885 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
19:21:24.422 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:24.427 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 8. attempt
19:21:24.432 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
19:21:24.436 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
19:21:24.445 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
Mh, it still looks like the old version. Do you have restarted openhab? The main difference is the
toggle between Send XX and Data X, so every byte is directly processed instead the complete byte array afterwards.
So my log looks now like:
20:38:53.669 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: 00 35 50 22 03 A3 75 27 79 @ 0. attempt
20:38:53.672 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
20:38:53.676 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
20:38:53.684 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
20:38:53.686 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 35
20:38:53.694 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
20:38:53.697 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 50
20:38:53.705 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
20:38:53.708 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 22
20:38:53.716 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
20:38:53.718 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 03
20:38:53.726 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 3
20:38:53.728 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
20:38:53.731 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send A3
20:38:53.739 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2
20:38:53.741 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 75
20:38:53.749 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
20:38:53.751 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 27
20:38:53.759 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
20:38:53.761 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 79
20:38:53.769 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct
20:38:53.771 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1
20:38:53.773 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Waiting for slave answer ...
20:38:53.775 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1
20:38:53.779 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 2
20:38:53.781 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2
20:38:53.783 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
20:38:53.788 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2
20:38:53.792 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2
20:38:53.793 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Slave answer received ...
20:38:53.794 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send Master ACK to Slave ...
20:38:53.802 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2
20:38:53.803 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send SYN to bus ...
20:38:53.810 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN
20:38:53.811 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable ...
20:38:53.813 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Succesful send: 00 35 50 22 03 A3 75 27 79 00 02 01 00 B7 00
i restart the hole system, but now i don’t can see any data in my paper UI
i also try to pool the signal, but with the same result
i also try nrjavaserial and jSerialComm
here is a small shortcut from my log
21:01:51.411 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
21:01:51.422 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
21:01:51.428 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
21:01:51.434 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
21:01:51.439 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9
21:01:51.446 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
21:01:51.453 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
21:01:51.457 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
21:01:51.463 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
21:01:51.468 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
21:01:51.473 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
21:01:51.478 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3
21:01:51.484 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2
21:01:51.490 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
21:01:51.497 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
21:01:51.503 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct
21:01:51.508 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1
21:01:51.512 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer …
21:01:51.517 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1
21:01:51.521 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 1
21:01:51.524 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2
21:01:51.527 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2
21:01:51.530 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2
21:01:51.533 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2
21:01:51.535 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN
21:01:51.537 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable …
21:01:51.540 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
21:01:51.549 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
21:01:51.551 [TRACE] [ebus.service.parser.EBusParserService] - No command method matches the telegram 10 08 B5 10 09 00 00 00 78 FF FF 05 FF 00 A0 00 01 01 9A 00 …
My change is only relevant for sending data, your log shows just receiving one command.
that’s true, because i don’t receive data from my VRT392, so that i can see it at PaperUI.
i also don’t know why i receive B5 10, because my VRT392 has also B509 for my receiving data.
here is the sending log:
21:10:26.624 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit’ received command 1
21:10:26.631 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1
21:10:26.638 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.670 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.684 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 …
21:10:26.718 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.766 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.778 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt
21:10:26.784 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
21:10:26.792 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
21:10:26.815 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.820 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision with SYN detected!
21:10:26.826 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …
21:10:26.832 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …
21:10:26.863 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:26.869 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt
21:10:26.875 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
21:10:26.881 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
21:10:26.895 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
21:10:27.486 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
21:10:27.493 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt
21:10:27.499 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
21:10:27.506 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
21:10:27.517 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
21:10:27.524 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
21:10:27.529 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
21:10:27.536 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09
21:10:27.542 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04
21:10:27.547 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
21:10:27.553 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
21:10:27.558 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
21:10:27.564 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
21:10:27.570 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
21:10:27.577 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
21:10:27.583 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
21:10:27.588 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
21:10:27.593 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9
21:10:27.598 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
21:10:27.607 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
21:10:27.612 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
21:10:27.617 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
21:10:27.625 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
21:10:27.633 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
21:10:27.640 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …
21:10:27.646 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
21:10:28.104 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
21:10:28.109 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …
Mh, I’ll try the 3pre2 version in my environment. Here is you log with the issue explained. It looks like the first FF values is double in the queue and moves all following values +1 . So your Target address is not 10 anymore, it is FF and later on it crashes on the master data length. It should be 4 but is 9.
21:10:27.506 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
21:10:27.517 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
<--- MAYBE something like an additional FF in the byte buffer -->
21:10:27.524 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10
21:10:27.529 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5
21:10:27.536 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09 <- WRONG USED LENGTH VALUE
21:10:27.542 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04 <- REAL LENGTH VALUE
21:10:27.547 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E
21:10:27.553 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F
21:10:27.558 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
21:10:27.564 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01
21:10:27.570 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6
21:10:27.577 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
21:10:27.583 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
21:10:27.588 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
21:10:27.593 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9 <-- WRONG, SHOULD BE 4
21:10:27.598 [TRACE] [dev.ebus.core
And could you use ``` characters in your forum replies before and after your log in a separate line. It formats the logs much better.