KNX Dimmers - slow response to dimmer-commands

Alfa347 · August 21, 2021, 9:25am

Hi

I have a really annoying problem in my setup. Everything works as intended with the integration between Openhab to KNX and Openhab to Apple Homekit. I can see all the lights and dimmers in Homekit exactly as intended and can control everything.

Now to my problem!

When I turn on a lamp via switch, the lamp reacts immediately. If I turn on a lamp via dimmer, it lights up immediately, but when I dim the lamp, it takes a couple of seconds before something happens. (3-10sec) Applies even if I control the dimmer directly from Openhab.

Is there a solution to this problem? Feels like I’ve tried most things to come up with a solution.

Openhab-setup

Platform information:
Hardware: Qnap Nas TS-332x, Alpine AL324 64-bit ARM® Cortex-A57 quad-core 1.7GHz,
16Gb ram, 128gb ssd.
OS: official OH3 image V3.1.0
Java Runtime Environment: latest
openHAB version: 3.1.0
Home-kit integration via openhab.

KNX-setup

IP-KNX interface from Schneider, Dimming actuator from Schneider, Switch actuator from Schneider.

UID: knx:device:32127f1551:d0df9c73ea
label: KNX Dimmer 1 - Kitchen
thingTypeUID: knx:device
configuration:
pingInterval: 600
address: 1.1.5
readInterval: 0
fetch: false
bridgeUID: knx:ip:32127f1551
location: Dimmer Kitchen
channels:

id: Channel1
channelTypeUID: knx:dimmer
label: D1_Kanal1
description: “”
configuration:
increaseDecrease: 2/2/1
position: 2/4/1+<2/3/1
switch: 2/0/1+<2/1/1

Kindest regards
Johan

J-N-K · August 21, 2021, 10:35am

What do you mean by „dim the lamp“? Using increase/decrease or by sending a brightness value?

Alfa347 · August 21, 2021, 10:53am

Sorry, my bad. Sending a brightness value to 5.0001 via Openhab. Values to 1.0001 works instantly.

J-N-K · August 21, 2021, 11:14am

That‘s very interesting. Please set the binding to DEBUG (log:set DEBUG org.openhab.binding.knx) and check a message similar to

13:12:48.800 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value '0' to datapoint 'command DP 4/0/170 'knx:ip:b22a68b7', DPT 5.010, low priority' (0. attempt).

in the log and if the delay is there, too.

Alfa347 · August 21, 2021, 12:04pm

Is there a way to acces debug log-level without useing the console?
I can´t reach the console on my qnap nas för openhab. =(

J-N-K · August 21, 2021, 12:40pm

No, I don‘t think so. But what is the problem with the console? You should be able to ssh with the admin account into the qnap nas (use „admin“ as username, not root).

Alfa347 · August 21, 2021, 12:47pm

After many different attempts, I managed to find a way to get into the console. yay =)

This is what the log says when I turn on / off or send brightness-value.

14:40:08.610 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ received command ON
14:40:08.617 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ predicted to become ON
14:40:08.622 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ changed from OFF to ON
14:40:08.642 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 1/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:08.699 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:9211b58418’ received a GroupValueWrite telegram from ‘1.1.1’ for destination ‘1/1/1’
14:40:10.700 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ received command OFF
14:40:10.707 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ predicted to become OFF
14:40:10.712 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXAktor1Kallare_AK1_Kanal1’ changed from ON to OFF
14:40:10.729 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘OFF’ to datapoint ‘command DP 1/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:10.785 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:9211b58418’ received a GroupValueWrite telegram from ‘1.1.1’ for destination ‘1/1/1’
14:40:13.814 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXAktor1Kallare_AK1Kanal6’ received command ON
14:40:13.819 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXAktor1Kallare_AK1Kanal6’ predicted to become ON
14:40:13.825 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXAktor1Kallare_AK1Kanal6’ changed from OFF to ON
14:40:13.843 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 1/0/6 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:13.900 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:9211b58418’ received a GroupValueWrite telegram from ‘1.1.1’ for destination ‘1/1/6’
14:40:16.129 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXAktor1Kallare_AK1Kanal6’ received command OFF
14:40:16.132 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXAktor1Kallare_AK1Kanal6’ predicted to become OFF
14:40:16.141 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXAktor1Kallare_AK1Kanal6’ changed from ON to OFF
14:40:16.163 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘OFF’ to datapoint ‘command DP 1/0/6 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:16.222 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:9211b58418’ received a GroupValueWrite telegram from ‘1.1.1’ for destination ‘1/1/6’
14:40:23.605 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:40:23.610 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 100
14:40:23.612 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:40:23.618 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 100
14:40:23.620 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 0 to 100
14:40:23.633 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:23.660 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘100’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:40:23.796 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/1/1’
14:40:28.798 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command OFF
14:40:28.807 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become OFF
14:40:28.813 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 0
14:40:28.831 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘OFF’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:29.494 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:40:29.532 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/1/1’
14:40:33.731 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:40:33.733 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 42
14:40:33.736 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:40:33.739 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 42
14:40:33.742 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 0 to 100
14:40:33.743 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 42
14:40:33.757 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:33.783 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘42’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:40:33.911 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:40:33.913 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 47
14:40:33.918 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/1/1’
14:40:33.919 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:40:33.923 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 47
14:40:33.927 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 42 to 100
14:40:33.930 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 47
14:40:33.932 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 47 to 100
14:40:33.938 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:33.966 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘47’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:40:41.512 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:40:41.515 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 47
14:40:41.835 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:40:41.846 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 56
14:40:41.848 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:40:41.852 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 56
14:40:41.856 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 47 to 100
14:40:41.858 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 56
14:40:41.861 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:41.888 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘56’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:40:42.108 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:40:42.111 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 45
14:40:42.114 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:40:42.118 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 45
14:40:42.122 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 56 to 100
14:40:42.124 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 45
14:40:42.136 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:40:42.162 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘45’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:40:42.839 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:41:16.448 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:16.450 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 76
14:41:16.453 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:16.457 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 76
14:41:16.462 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 45 to 100
14:41:16.464 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 76
14:41:16.480 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:16.508 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘76’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:41:16.709 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:16.712 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 83
14:41:16.715 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:16.719 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 83
14:41:16.723 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 76 to 100
14:41:16.725 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 83
14:41:16.736 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:16.763 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘83’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:41:20.166 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:41:44.340 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command OFF
14:41:44.351 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become OFF
14:41:44.355 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 83 to 0
14:41:44.366 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘OFF’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:45.601 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/1/1’
14:41:45.643 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:41:50.966 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:50.968 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 100
14:41:50.970 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:50.972 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 100
14:41:50.975 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 0 to 100
14:41:50.994 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:51.022 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘100’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:41:51.149 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/1/1’
14:41:59.326 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:59.328 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 69
14:41:59.331 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:59.333 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 69
14:41:59.336 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 69
14:41:59.352 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:59.379 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘69’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:41:59.599 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:59.602 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 48
14:41:59.607 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:59.611 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 48
14:41:59.615 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 69 to 100
14:41:59.616 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 48
14:41:59.625 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:59.651 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘48’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:41:59.771 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:41:59.773 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 47
14:41:59.775 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:41:59.781 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 47
14:41:59.784 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 48 to 100
14:41:59.789 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 47
14:41:59.800 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:41:59.827 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘47’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:00.834 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:42:10.264 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:10.266 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 73
14:42:10.268 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:42:10.270 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 73
14:42:10.274 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 47 to 100
14:42:10.276 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 73
14:42:10.290 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:42:10.317 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘73’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:10.466 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:10.469 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 77
14:42:10.471 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:42:10.475 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 77
14:42:10.478 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 73 to 100
14:42:10.480 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 77
14:42:10.492 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:42:10.519 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘77’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:13.347 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’
14:42:15.857 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:15.859 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 46
14:42:15.861 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:42:15.866 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 46
14:42:15.869 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 77 to 100
14:42:15.871 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 46
14:42:15.885 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:42:15.912 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘46’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:16.126 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:16.133 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 32
14:42:16.136 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:42:16.139 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 32
14:42:16.143 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 46 to 100
14:42:16.145 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 32
14:42:16.153 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:42:16.179 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘32’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:16.320 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:16.322 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 30
14:42:16.324 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become ON
14:42:16.326 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 30
14:42:16.329 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 32 to 100
14:42:16.331 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 100 to 30
14:42:16.349 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘ON’ to datapoint ‘command DP 2/0/1 ‘knx:ip:32127f1551’, DPT id 1.001, low priority’ (0. attempt).
14:42:16.376 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘30’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).
14:42:21.344 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’

J-N-K · August 21, 2021, 1:12pm

Looks like there is only a delay of less than 100ms between the item command and the send message.

Do you own an ETS? If so, do you see a delay in the reaction of the light when you send a brightness telegram via the ETS?

rossko57 · August 21, 2021, 1:36pm

Alfa347:

14:42:15.857 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command ON
14:42:15.859 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 46

Where are these doubled commands coming from, is this a rule or your UI?

Alfa347 · August 21, 2021, 1:44pm

J-N-K -

There is no delay from the time the log writes until the command appears in the ETS log.
It seems that the delay is in Openhab

If you look in the log, you will see a delay of about 3-4 seconds between the sound value and the telegram for the groupadress

15:40:01.693 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘76’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).

15:40:04.743 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination ‘2/3/1’

Alfa347 · August 21, 2021, 1:51pm

Would think it’s due to Appel Homekit.
I need to use both “Lighting and Lighting.Brightness” for the dimmer control to appear in the Apple Homekit.
When you quickly click on the controller, it sends on / off. If you hold down, you can set a value for the brightnesslevel for the channel.

J-N-K · August 21, 2021, 2:07pm

Do you see the same delay if you use

openhab:send KNXDimmer1Kok_Kanal1 60

on the console or use the openHAB UI? Otherwise it’s probably Homekit related, not KNX.

Alfa347 · August 21, 2021, 2:41pm

I typed in the console with the result of about 2 seconds delay within the penultimate and last command sent from Openhab. Once it was sent, it immediately appeared in the ETS log

16:35:33.463 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ received command 60

16:35:33.466 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item ‘KNXDimmer1Kok_Kanal1’ predicted to become 60

16:35:33.468 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘KNXDimmer1Kok_Kanal1’ changed from 40 to 60

16:35:33.491 [DEBUG] [knx.internal.client.AbstractKNXClient] - Wrote value ‘60’ to datapoint ‘command DP 2/4/1 ‘knx:ip:32127f1551’, DPT id 5.001, low priority’ (0. attempt).

16:35:35.978 [DEBUG] [x.internal.handler.DeviceThingHandler] - onGroupWrite Thing ‘knx:device:32127f1551:d0df9c73ea’ received a GroupValueWrite telegram from ‘1.1.5’ for destination '2/3/1’

mikaelgu · August 21, 2021, 3:13pm

What’s the configuration of the dimmer actuator in KNX? What I’m aiming is that in my KNX (I have MDT hardware as dimmers) there is a setting that controls how quickly the lights “fade” when a value is sent. So for me the value is transmitted from OpenHAB instantly but it takes a while for the lights to reach the desired value. This is in my case the intended behavior.

Mikael

J-N-K · August 21, 2021, 3:20pm

That’s what I think. From the openHAB side it looks like the command is send immediately but the telegram with the status is received two seconds later. This hints at the KNX side as source of the delay. This is especially true if the ETS Busmonitor shows the telegram without delay.

It could be that there is a slight delay (100 ms or so) from the log message until the command is actually send, but I’ve never seen larger delays (even if I use a customized version of the binding, I have never seen that in the before).

Alfa347 · August 21, 2021, 4:40pm

You were right. It was the setting “Dimming time reduction object for dimming curve” that was turned off.

Activated the function and changed the delay.
Now it works much better without delay. =)

Thank you so much for the help to both of you. =)