[KNX] Thing random offline

broliyoung · January 22, 2020, 2:47pm

Hi all, I have a problem with two motion sensor on my configuration. I have no problem with dimmers or other actuator but with two motion sensor I have periodic offline.
That’s the log:

2020-01-22 15:24:15.349 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-22 15:25:15.650 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

2020-01-22 15:28:21.403 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-22 15:29:21.702 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

2020-01-22 15:31:27.153 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-22 15:32:27.454 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

2020-01-22 15:35:33.211 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-22 15:36:33.555 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

in this link my configuration:

Thanks for your help

H102 · January 22, 2020, 5:22pm

Set the log to DEBUG and see if you get any additional info that may help determine the issue.

broliyoung · January 23, 2020, 9:11am

Hi H102, thanks for your support. Seems the sensors don’t respond every time openhab send the polling.
Below the logs:

2020-01-23 09:41:12.242 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:41:17.393 [hingStatusInfoChangedEvent] - 'knx:device:603d40a4' changed from ONLINE to OFFLINE

2020-01-23 09:41:19.141 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:41:24.292 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-23 09:42:17.392 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:42:17.694 [hingStatusInfoChangedEvent] - 'knx:device:603d40a4' changed from OFFLINE to ONLINE

2020-01-23 09:42:24.292 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:42:24.593 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

2020-01-23 09:43:17.693 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:43:24.593 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:44:17.994 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:44:24.893 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:44:30.044 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

2020-01-23 09:45:18.294 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:45:30.044 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:45:30.344 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from OFFLINE to ONLINE

2020-01-23 09:46:18.594 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:46:30.344 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:47:18.895 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

2020-01-23 09:47:30.644 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-23 09:47:35.796 [hingStatusInfoChangedEvent] - 'knx:device:76a6ba89' changed from ONLINE to OFFLINE

H102 · January 23, 2020, 2:37pm

Debug didn’t give much to work with so try setting log to TRACE.

broliyoung · January 24, 2020, 7:17am

Hi, thanks for reply!
So I did set to trace and actually I receive some trace log, but for polling event it still a DEBUG log.
I leaved

2020-01-24 08:11:27.036 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

==> /var/log/openhab2/events.log <==

2020-01-24 08:11:27.337 [hingStatusInfoChangedEvent] - 'knx:device:603d40a4' changed from OFFLINE to ONLINE

==> /var/log/openhab2/openhab.log <==

2020-01-24 08:11:34.386 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/16' with value '[0, 9]'

2020-01-24 08:11:34.436 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/18' with value '[0, 0]'

2020-01-24 08:11:34.487 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/20' with value '[0, 1]'

2020-01-24 08:11:34.489 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/17' with value '[45, -108]'

2020-01-24 08:11:34.536 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/19' with value '[12, 126]'

2020-01-24 08:11:34.586 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/21' with value '[20, -105]'

2020-01-24 08:11:44.437 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

2020-01-24 08:11:49.386 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/16' with value '[0, 9]'

2020-01-24 08:11:49.437 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/18' with value '[0, 0]'

2020-01-24 08:11:49.486 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/20' with value '[0, 1]'

2020-01-24 08:11:49.488 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/17' with value '[45, -124]'

2020-01-24 08:11:49.536 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/19' with value '[12, 126]'

2020-01-24 08:11:49.586 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/21' with value '[20, -105]'

2020-01-24 08:12:04.387 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/16' with value '[0, 9]'

2020-01-24 08:12:04.436 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/18' with value '[0, 0]'

2020-01-24 08:12:04.486 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/20' with value '[0, 0]'

2020-01-24 08:12:04.488 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/17' with value '[45, 120]'

2020-01-24 08:12:04.536 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/19' with value '[12, 126]'

2020-01-24 08:12:04.586 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/21' with value '[20, 26]'

2020-01-24 08:12:19.387 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/16' with value '[0, 4]'

2020-01-24 08:12:19.436 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/18' with value '[0, 0]'

2020-01-24 08:12:19.487 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/20' with value '[0, 1]'

2020-01-24 08:12:19.489 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/17' with value '[36, -43]'

2020-01-24 08:12:19.536 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/19' with value '[12, 126]'

2020-01-24 08:12:19.586 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/21' with value '[20, -105]'

2020-01-24 08:12:27.337 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.99'

==> /var/log/openhab2/events.log <==

2020-01-24 08:12:32.490 [hingStatusInfoChangedEvent] - 'knx:device:603d40a4' changed from ONLINE to OFFLINE

==> /var/log/openhab2/openhab.log <==

2020-01-24 08:12:34.386 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/16' with value '[0, 4]'

2020-01-24 08:12:34.436 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/18' with value '[0, 0]'

2020-01-24 08:12:34.487 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/20' with value '[0, 1]'

2020-01-24 08:12:34.490 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/17' with value '[36, -18]'

2020-01-24 08:12:34.537 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/19' with value '[12, 126]'

2020-01-24 08:12:34.587 [TRACE] [nx.internal.client.AbstractKNXClient] - Received a Group Write telegram from '1.1.2' to '4/0/21' with value '[20, -105]'

2020-01-24 08:12:44.737 [DEBUG] [rnal.handler.AbstractKNXThingHandler] - Polling individual address '1.1.85'

H102 · January 24, 2020, 3:08pm

What version of OH and KNX binding are you using?

broliyoung · January 27, 2020, 7:28am

openHAB 2.5.1-2 on openhabian
KNX 2.5.1

I ever had this problem since 2.4

H102 · January 27, 2020, 2:46pm

Hard to tell what’s happening with the logs and thing config. Here is a link to issues on git that may help. You can open an issue if you do not see one similar to yours.