KNX problem in 0.53

MGaeremynck · February 13, 2021, 4:32pm

Back to tunneling, removed all routing options on the EibPort.

Seems we have more information now in the log:

2021-02-13 17:23:00 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2021-02-13 17:23:14 INFO (MainThread) [xknx.log] XKNX v0.16.2 starting automatic connection to KNX bus.
2021-02-13 17:23:14 DEBUG (MainThread) [xknx.log] Searching on lo / 127.0.0.1
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of media_source is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of timer is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of verisure is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of input_datetime is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of counter is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of tag is taking over 10 seconds.
2021-02-13 17:23:18 WARNING (MainThread) [homeassistant.setup] Setup of group is taking over 10 seconds.
2021-02-13 17:23:19 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:19 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on dummy0
2021-02-13 17:23:19 DEBUG (MainThread) [xknx.log] Searching on eth0 / 10.0.0.16
2021-02-13 17:23:21 DEBUG (MainThread) [xknx.raw_socket] Received from ('127.0.0.1', 3671): 06100201000e0801e000170c0e57
2021-02-13 17:23:21 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:21 DEBUG (MainThread) [xknx.knx] Unhandled KNXIPServiceType.SEARCH_REQUEST: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:21 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:21 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on wlan0
2021-02-13 17:23:21 DEBUG (MainThread) [xknx.log] Searching on docker0 / 172.17.0.1
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100202004c08010a0000110e5736010200220f0000ff66f9050000e000170c00e04b2d086d656962506f727420656962506f727420486f6d65204d47000000000000000802020103010401
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_RESPONSE" Reserve="0" TotalLength="76" />
body="<SearchResponse control_endpoint="<HPAI 10.0.0.17:3671 />" dibs="[
<DIBDeviceInformation
knx_medium="KNXMedium.TP1"
programming_mode="False"
individual_address="2.2.15"
installation_number="0"
project_number="0"
serial_number="ff:66:f9:05:00:00"
multicast_address="224.0.23.12"
mac_address="00:e0:4b:2d:08:6d"
name="eibPort eibPort Home MG" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100202004c08010a0000110e5736010200220f0000ff66f9050000e000170c00e04b2d086d656962506f727420656962506f727420486f6d65204d47000000000000000802020103010401
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_RESPONSE" Reserve="0" TotalLength="76" />
body="<SearchResponse control_endpoint="<HPAI 10.0.0.17:3671 />" dibs="[
<DIBDeviceInformation
knx_medium="KNXMedium.TP1"
programming_mode="False"
individual_address="2.2.15"
installation_number="0"
project_number="0"
serial_number="ff:66:f9:05:00:00"
multicast_address="224.0.23.12"
mac_address="00:e0:4b:2d:08:6d"
name="eibPort eibPort Home MG" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />
2021-02-13 17:23:23 WARNING (MainThread) [homeassistant.setup] Setup of input_select is taking over 10 seconds.
2021-02-13 17:23:23 WARNING (MainThread) [homeassistant.setup] Setup of zone is taking over 10 seconds.
2021-02-13 17:23:23 WARNING (MainThread) [homeassistant.components.scene] Setup of scene platform homeassistant is taking over 10 seconds.
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:23 DEBUG (MainThread) [xknx.log] Searching on hassio / 172.30.32.1
2021-02-13 17:23:26 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AdGuard Home' for adguard integration not ready yet. Retrying in 5 seconds
2021-02-13 17:23:26 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth9838e7e
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth2a8e207
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth47d5b8a
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth5f58605
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethb07cf6e
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethb32b1f0
2021-02-13 17:23:26 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth164e37f
2021-02-13 17:23:26 WARNING (MainThread) [homeassistant.setup] Setup of knx is taking over 10 seconds.
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Could not find IPv4 address on interface dummy0
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Using interface: eth0
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Starting tunnel from 10.0.0.16 to 10.0.0.17:3671
2021-02-13 17:23:29 WARNING (MainThread) [homeassistant.setup] Setup of input_number is taking over 10 seconds.
2021-02-13 17:23:29 WARNING (MainThread) [homeassistant.setup] Setup of input_boolean is taking over 10 seconds.
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:29 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:29 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned
2021-02-13 17:23:29 ERROR (MainThread) [homeassistant.components.accuweather] Timeout fetching accuweather data
2021-02-13 17:23:29 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AccuWeather' for accuweather integration not ready yet. Retrying in 5 seconds
2021-02-13 17:23:32 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:40753 />" data_endpoint="<HPAI 10.0.0.16:40753 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:23:32 ERROR (SyncWorker_3) [homeassistant.components.dyson] Not connected to Dyson account. Unable to add devices
2021-02-13 17:23:32 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100206001a2a0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:32 DEBUG (MainThread) [xknx.knx] Unsupported KNXIPFrame: KNXIP data has wrong length in 06100206001a2a0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:36 ERROR (MainThread) [homeassistant.setup] Setup failed for dyson: Integration failed to initialize.
2021-02-13 17:23:36 WARNING (MainThread) [homeassistant.components.binary_sensor] Setup of binary_sensor platform ping is taking over 10 seconds.
2021-02-13 17:23:38 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:23:38 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:23:38 DEBUG (MainThread) [xknx.state_updater] StateUpdater initializing values
2021-02-13 17:23:38 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AdGuard Home' for adguard integration not ready yet. Retrying in 10 seconds
2021-02-13 17:23:39 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:42 ERROR (MainThread) [homeassistant.components.hue] Error connecting to the Hue bridge at 192.168.1.2
2021-02-13 17:23:42 WARNING (MainThread) [homeassistant.config_entries] Config entry 'Philips hue' for hue integration not ready yet. Retrying in 5 seconds
2021-02-13 17:23:43 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:50956 />" data_endpoint="<HPAI 10.0.0.16:50956 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:23:43 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100206001a2b0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:43 DEBUG (MainThread) [xknx.knx] Unsupported KNXIPFrame: KNXIP data has wrong length in 06100206001a2b0008010a0000110e570404470bd8e33b401200

2021-02-13 17:23:46 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:23:46 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:23:46 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:48 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AdGuard Home' for adguard integration not ready yet. Retrying in 20 seconds
2021-02-13 17:23:49 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:45624 />" data_endpoint="<HPAI 10.0.0.16:45624 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:23:49 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100206001a2c0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:49 DEBUG (MainThread) [xknx.knx] Unsupported KNXIPFrame: KNXIP data has wrong length in 06100206001a2c0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:52 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.ring_doorbell is taking over 10 seconds
2021-02-13 17:23:52 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.ring_floodlight_garage is taking over 10 seconds
2021-02-13 17:23:52 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:23:52 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:23:52 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:53 WARNING (MainThread) [homeassistant.components.sensor] Setup of sensor platform opensky is taking over 10 seconds.
2021-02-13 17:23:55 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:40018 />" data_endpoint="<HPAI 10.0.0.16:40018 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:23:55 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100206001a2d0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:55 DEBUG (MainThread) [xknx.knx] Unsupported KNXIPFrame: KNXIP data has wrong length in 06100206001a2d0008010a0000110e570404470bd8e33b401200
2021-02-13 17:23:56 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:23:56 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:23:56 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:23:58 WARNING (MainThread) [zigpy_znp.api] Received an unhandled command: ZDO.PermitJoinInd.Callback(Duration=0)
2021-02-13 17:23:59 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:37421 />" data_endpoint="<HPAI 10.0.0.16:37421 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:00 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:00 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:00 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:03 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:48934 />" data_endpoint="<HPAI 10.0.0.16:48934 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:04 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:04 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:04 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:07 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:50745 />" data_endpoint="<HPAI 10.0.0.16:50745 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:08 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AdGuard Home' for adguard integration not ready yet. Retrying in 40 seconds
2021-02-13 17:24:08 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:08 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:08 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:11 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:47958 />" data_endpoint="<HPAI 10.0.0.16:47958 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:13 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:13 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:13 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse temperature sensor sensor.master_bedroom_dyson_pure_cool_temperature with state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse humidity sensor sensor.master_bedroom_dyson_pure_cool_humidity, state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse temperature sensor sensor.cgs_dyson_pure_hot_cool_temperature with state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse humidity sensor sensor.cgs_dyson_pure_hot_cool_humidity, state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse humidity sensor sensor.cgs_dyson_pure_hot_cool_humidity, state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse temperature sensor sensor.cgs_dyson_pure_hot_cool_temperature with state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse temperature sensor sensor.master_bedroom_dyson_pure_cool_temperature with state: unavailable
2021-02-13 17:24:13 ERROR (MainThread) [homeassistant.components.mold_indicator.sensor] Unable to parse humidity sensor sensor.master_bedroom_dyson_pure_cool_humidity, state: unavailable
2021-02-13 17:24:16 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:58886 />" data_endpoint="<HPAI 10.0.0.16:58886 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:18 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:18 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:18 ERROR (MainThread) [homeassistant.helpers.event] Error while processing template: Template("{% if (states.fan.cgs_dyson_pure_hot_cool.attributes.speed|int) != (states.input_number.dyson_pure_hot_cool_integration_fan_speed.state|int) %}true{%endif%}")
Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 353, in async_render
render_result = compiled.render(kwargs)
File "/usr/local/lib/python3.8/site-packages/jinja2/environment.py", line 1090, in render
self.environment.handle_exception()
File "/usr/local/lib/python3.8/site-packages/jinja2/environment.py", line 832, in handle_exception
reraise(*rewrite_traceback_stack(source=source))
File "/usr/local/lib/python3.8/site-packages/jinja2/_compat.py", line 28, in reraise
raise value.with_traceback(tb)
File "<template>", line 1, in top-level template code
File "/usr/local/lib/python3.8/site-packages/jinja2/sandbox.py", line 407, in getattr
value = getattr(obj, attribute)
jinja2.exceptions.UndefinedError: 'None' has no attribute 'attributes'
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 462, in async_render_to_info
render_info._result = self.async_render(variables, **kwargs)
File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 355, in async_render
raise TemplateError(err) from err
homeassistant.exceptions.TemplateError: UndefinedError: 'None' has no attribute 'attributes'
2021-02-13 17:24:18 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:21 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:35854 />" data_endpoint="<HPAI 10.0.0.16:35854 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:22 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:22 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:22 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:24 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_moisture is taking over 10 seconds
2021-02-13 17:24:25 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:40044 />" data_endpoint="<HPAI 10.0.0.16:40044 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:26 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:26 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:26 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:29 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:42656 />" data_endpoint="<HPAI 10.0.0.16:42656 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:30 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:30 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:30 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:31 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.opensky is taking over 10 seconds
2021-02-13 17:24:33 WARNING (MainThread) [homeassistant.components.sensor] Updating opensky sensor took longer than the scheduled update interval 0:00:12
2021-02-13 17:24:33 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:35192 />" data_endpoint="<HPAI 10.0.0.16:35192 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:34 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:34 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:34 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:37 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:38980 />" data_endpoint="<HPAI 10.0.0.16:38980 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:38 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:38 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:38 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:39 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_battery is taking over 10 seconds
2021-02-13 17:24:41 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:45566 />" data_endpoint="<HPAI 10.0.0.16:45566 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:42 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:42 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:42 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:45 WARNING (MainThread) [homeassistant.components.sensor] Updating opensky sensor took longer than the scheduled update interval 0:00:12
2021-02-13 17:24:45 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:56058 />" data_endpoint="<HPAI 10.0.0.16:56058 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:46 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:46 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:46 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:48 WARNING (MainThread) [homeassistant.config_entries] Config entry 'AdGuard Home' for adguard integration not ready yet. Retrying in 80 seconds
2021-02-13 17:24:49 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:45107 />" data_endpoint="<HPAI 10.0.0.16:45107 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:50 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:50 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:50 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:50 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_2_moisture is taking over 10 seconds
2021-02-13 17:24:53 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:59260 />" data_endpoint="<HPAI 10.0.0.16:59260 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:54 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:54 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:54 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:24:57 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:36255 />" data_endpoint="<HPAI 10.0.0.16:36255 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:24:58 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:24:58 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:24:58 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:25:01 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:43996 />" data_endpoint="<HPAI 10.0.0.16:43996 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:25:02 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:25:02 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:25:02 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:25:03 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_2_battery is taking over 10 seconds
2021-02-13 17:25:05 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:34910 />" data_endpoint="<HPAI 10.0.0.16:34910 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:25:06 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:25:06 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:25:06 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:25:10 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:35159 />" data_endpoint="<HPAI 10.0.0.16:35159 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:25:11 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-13 17:25:11 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-13 17:25:11 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-13 17:25:13 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_3_moisture is taking over 10 seconds
2021-02-13 17:25:14 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:52641 />" data_endpoint="<HPAI 10.0.0.16:52641 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-13 17:25:15 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'

Does this helps?

farmio · February 13, 2021, 5:53pm

There are many

I guess everything is just taking too long so the requests time out. Maybe some integration is going wild here or your system is just overloaded by something.
Good thing: the knx interface is found by the search request (this has longer Timeouts ) so when you eliminate the source of this I guess it will just work.

MGaeremynck · February 14, 2021, 2:41pm

even a clean system in a Virtualbox has this timeout. Can I increase this time-out somewhere to check if this works?

2021-02-14 15:30:04 INFO (MainThread) [xknx.log] XKNX v0.16.2 starting tunneling connection to KNX bus.
2021-02-14 15:30:04 DEBUG (MainThread) [xknx.log] Could not find IPv4 address on interface dummy0
2021-02-14 15:30:04 DEBUG (MainThread) [xknx.log] Using interface: enp0s3
2021-02-14 15:30:04 DEBUG (MainThread) [xknx.log] Starting tunnel from 10.0.0.110 to 10.0.0.17:1735
2021-02-14 15:30:04 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:43765 />" data_endpoint="<HPAI 10.0.0.110:43765 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:06 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:06 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:06 DEBUG (MainThread) [xknx.state_updater] StateUpdater initializing values
2021-02-14 15:30:06 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:09 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:41486 />" data_endpoint="<HPAI 10.0.0.110:41486 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:10 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:10 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:10 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:13 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:40516 />" data_endpoint="<HPAI 10.0.0.110:40516 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:14 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:14 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:14 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:15 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:43485 />" data_endpoint="<HPAI 10.0.0.110:43485 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:16 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:16 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:16 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:16 ERROR (SyncWorker_16) [homeassistant.components.zeroconf] Home Assistant instance with identical name present in the local network
2021-02-14 15:30:19 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:55001 />" data_endpoint="<HPAI 10.0.0.110:55001 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:20 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:20 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:20 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:23 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:56605 />" data_endpoint="<HPAI 10.0.0.110:56605 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:24 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:24 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:24 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:27 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:37951 />" data_endpoint="<HPAI 10.0.0.110:37951 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:28 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:28 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:28 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:31 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:47103 />" data_endpoint="<HPAI 10.0.0.110:47103 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:32 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:32 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:32 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:35 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:46963 />" data_endpoint="<HPAI 10.0.0.110:46963 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:36 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:36 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:36 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:39 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:36204 />" data_endpoint="<HPAI 10.0.0.110:36204 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:40 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:40 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:40 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 15:30:43 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.110:55941 />" data_endpoint="<HPAI 10.0.0.110:55941 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 15:30:44 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 15:30:44 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 15:30:44 DEBUG (MainThread) [xknx.log] Closing transport.

MGaeremynck · February 14, 2021, 2:49pm

strange, the clean system doesn’t detect the KNX IP interface if I don’t force the ip address in the config file.

One step forward, two backward

Logger: homeassistant.components.knx
Source: components/knx/__init__.py:180
Integration: KNX (documentation, issues)
First occurred: 15:44:48 (1 occurrences)
Last logged: 15:44:48

Could not connect to KNX interface: No Gateways found

Maybe because my test systems is not connected to the same access point.

farmio · February 14, 2021, 3:15pm

don’t try this over Wifi. Eliminate all factors that could cause troubles and put them back in place when it works… So get a cable and connect them. Also virtualisation can be a problem depending on the used network methods.
Both Wifi and virtualisation can cause problems with multicast packets.

It seems to try to connect to port 1735 again…

MGaeremynck:

<SearchResponse control_endpoint="<HPAI 10.0.0.17:3671 />" dibs="[
<DIBDeviceInformation
knx_medium="KNXMedium.TP1"
programming_mode="False"
individual_address="2.2.15"
installation_number="0"
project_number="0"
serial_number="ff:66:f9:05:00:00"
multicast_address="224.0.23.12"
mac_address="00:e0:4b:2d:08:6d"
name="eibPort eibPort Home MG" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />

Here you see your interface has

IP: 10.0.0.17
Port: 3671
does support Tunneling
does not support Routing

The timeout is part of the knx system specification - increasing it won’t be useful. In your log there is just no answer at all… the timeout is just the way this is measured. It has nothing to do with the [homeassistant.setup] Setup of <...> is taking over 10 seconds. messages.

MGaeremynck · February 14, 2021, 3:32pm

Thank you again for all your support in this!

Ok, let’s drop the virtual machine option. I propose to install a clean install on a new SD card and test on my RPI3. The RPI3 and the EibPort are both connected by cable to the same router.

Keep you posted of the results!

MGaeremynck · February 14, 2021, 4:15pm

Clean install with no other components or integrations activated, both RPI and EibPort connect with cable on the same router.

When turning on the lights:

Logger: xknx.log
Source: /usr/local/lib/python3.8/site-packages/xknx/core/telegram_queue.py:134
First occurred: 17:06:57 (10 occurrences)
Last logged: 17:07:02

Sending telegram failed. No active communication channel.

No other errors, EibPort is detected but doesn’t seem to respond as it should.

2021-02-14 17:03:28 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up recorder
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up http
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.1 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up system_log
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 0.2 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.bootstrap] Setting up stage 1: {'api', 'hassio', 'onboarding', 'auth', 'system_log', 'device_automation', 'http', 'alexa', 'frontend', 'person', 'config', 'webhook', 'lovelace', 'search', 'websocket_api', 'cloud', 'image'}
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up api
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.0 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up hassio
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up auth
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setting up device_automation
2021-02-14 17:03:29 INFO (MainThread) [homeassistant.setup] Setup of domain device_automation took 0.0 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up alexa
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain alexa took 0.0 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up config
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up webhook
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.2 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up image
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up search
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain search took 0.0 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.2 seconds
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setup of domain image took 0.0 seconds
2021-02-14 17:03:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded google_assistant from homeassistant.components.google_assistant
2021-02-14 17:03:30 INFO (SyncWorker_1) [homeassistant.loader] Loaded camera from homeassistant.components.camera
2021-02-14 17:03:30 INFO (MainThread) [homeassistant.setup] Setting up person
2021-02-14 17:03:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up cloud
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain person took 1.5 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 0.1 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.0 seconds
2021-02-14 17:03:32 INFO (SyncWorker_3) [homeassistant.loader] Loaded device_tracker from homeassistant.components.device_tracker
2021-02-14 17:03:32 INFO (SyncWorker_0) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up frontend
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.components.http] Now listening on port 8123
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 3.1 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.bootstrap] Setting up stage 2: {'history', 'scene', 'input_text', 'mobile_app', 'script', 'timer', 'rpi_power', 'automation', 'met', 'updater', 'zone', 'ssdp', 'blueprint', 'tts', 'default_config', 'input_number', 'logbook', 'dhcp', 'media_source', 'system_health', 'input_select', 'zeroconf', 'knx', 'counter', 'group', 'sun', 'input_boolean', 'input_datetime', 'map', 'tag'}
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up history
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up scene
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.components.scene] Setting up scene.homeassistant
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up input_text
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up script
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up timer
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up rpi_power
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain rpi_power took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up met
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain met took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up updater
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up zone
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up blueprint
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setup of domain blueprint took 0.0 seconds
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up input_number
2021-02-14 17:03:32 INFO (MainThread) [homeassistant.setup] Setting up logbook
2021-02-14 17:03:35 INFO (MainThread) [homeassistant.setup] Setting up dhcp
2021-02-14 17:03:35 INFO (MainThread) [homeassistant.setup] Setup of domain dhcp took 0.0 seconds
2021-02-14 17:03:35 INFO (MainThread) [homeassistant.setup] Setting up media_source
2021-02-14 17:03:35 INFO (MainThread) [homeassistant.setup] Setting up system_health
2021-02-14 17:03:35 INFO (MainThread) [homeassistant.setup] Setting up input_select
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up knx
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] XKNX v0.16.2 starting automatic connection to KNX bus.
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.log] Searching on lo / 127.0.0.1
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up counter
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up group
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up sun
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.1 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up input_boolean
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up input_datetime
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up map
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up tag
2021-02-14 17:03:38 INFO (SyncWorker_3) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on dummy0
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.log] Searching on eth0 / 10.0.0.16
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 5.7 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 5.6 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain media_source took 3.2 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 3.2 seconds
2021-02-14 17:03:38 INFO (SyncWorker_3) [homeassistant.loader] Loaded google_translate from homeassistant.components.google_translate
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up zeroconf
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.5 seconds
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.raw_socket] Received from ('127.0.0.1', 3671): 06100201000e0801e000170c0e57
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Unhandled KNXIPServiceType.SEARCH_REQUEST: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up automation
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_text took 5.9 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain timer took 5.9 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_number took 5.7 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 0.0 seconds
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on wlan0
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.log] Searching on docker0 / 172.17.0.1
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_select took 3.3 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain counter took 0.6 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_boolean took 0.4 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_datetime took 0.4 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain tag took 0.4 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setting up tts
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100202004c08010a0000110e5736010200220f0000ff66f9050000e000170c00e04b2d086d656962506f727420656962506f727420486f6d65204d47000000000000000802020103010401
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_RESPONSE" Reserve="0" TotalLength="76" />
body="<SearchResponse control_endpoint="<HPAI 10.0.0.17:3671 />" dibs="[
<DIBDeviceInformation
knx_medium="KNXMedium.TP1"
programming_mode="False"
individual_address="2.2.15"
installation_number="0"
project_number="0"
serial_number="ff:66:f9:05:00:00"
multicast_address="224.0.23.12"
mac_address="00:e0:4b:2d:08:6d"
name="eibPort eibPort Home MG" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.raw_socket] Received from ('10.0.0.17', 3671): 06100202004c08010a0000110e5736010200220f0000ff66f9050000e000170c00e04b2d086d656962506f727420656962506f727420486f6d65204d47000000000000000802020103010401
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_RESPONSE" Reserve="0" TotalLength="76" />
body="<SearchResponse control_endpoint="<HPAI 10.0.0.17:3671 />" dibs="[
<DIBDeviceInformation
knx_medium="KNXMedium.TP1"
programming_mode="False"
individual_address="2.2.15"
installation_number="0"
project_number="0"
serial_number="ff:66:f9:05:00:00"
multicast_address="224.0.23.12"
mac_address="00:e0:4b:2d:08:6d"
name="eibPort eibPort Home MG" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.3 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.rpi_power
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.log] Searching on hassio / 172.30.32.1
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain zeroconf took 0.3 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 6.1 seconds
2021-02-14 17:03:38 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.updater
2021-02-14 17:03:38 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="SEARCH_REQUEST" Reserve="0" TotalLength="14" />
body="<SearchRequest discovery_endpoint="<HPAI 224.0.23.12:3671 />" />" />
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethd3637d2
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethfb5828c
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth82f903a
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth43e8f21
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth5735198
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth758ef0f
2021-02-14 17:03:38 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethe365456
2021-02-14 17:03:39 INFO (SyncWorker_0) [homeassistant.loader] Loaded notify from homeassistant.components.notify
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Could not find IPv4 address on interface dummy0
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Using interface: eth0
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Starting tunnel from 10.0.0.16 to 10.0.0.17:3671
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.3 seconds
2021-02-14 17:03:39 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:52296 />" data_endpoint="<HPAI 10.0.0.16:52296 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setting up ssdp
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setup of domain ssdp took 0.0 seconds
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 0.0 seconds
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setting up notify
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.0 seconds
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.setup] Setting up default_config
2021-02-14 17:03:39 INFO (SyncWorker_1) [homeassistant.loader] Loaded stream from homeassistant.components.stream
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.components.notify] Setting up notify.mobile_app
2021-02-14 17:03:39 INFO (MainThread) [homeassistant.components.updater] Submitted analytics to Home Assistant servers. Information submitted includes {}
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up stream
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain stream took 0.0 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.5 seconds
2021-02-14 17:03:40 INFO (SyncWorker_0) [homeassistant.loader] Loaded weather from homeassistant.components.weather
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up weather
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 0.0 seconds
2021-02-14 17:03:40 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 17:03:40 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 17:03:40 DEBUG (MainThread) [xknx.state_updater] StateUpdater initializing values
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain knx took 2.3 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.weather] Setting up weather.met
2021-02-14 17:03:40 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:40 INFO (SyncWorker_4) [homeassistant.loader] Loaded light from homeassistant.components.light
2021-02-14 17:03:40 INFO (SyncWorker_1) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2021-02-14 17:03:40 INFO (SyncWorker_2) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2021-02-14 17:03:40 INFO (SyncWorker_3) [homeassistant.loader] Loaded switch from homeassistant.components.switch
2021-02-14 17:03:40 INFO (SyncWorker_0) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.notify] Setting up notify.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.scene] Setting up scene.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.weather] Setting up weather.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.weather] Not adding entity Home Hourly because it's disabled
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up light
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up climate
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up cover
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up switch
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 0.0 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setting up sensor
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 0.0 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.setup] Setup of domain light took 0.1 seconds
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.climate] Setting up climate.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.cover] Setting up cover.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 13.04s
2021-02-14 17:03:40 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2021-02-14 17:03:41 INFO (MainThread) [homeassistant.core] Timer:starting
2021-02-14 17:03:41 INFO (SyncWorker_3) [homeassistant.components.zeroconf] Starting Zeroconf broadcast
2021-02-14 17:03:41 INFO (SyncWorker_0) [homeassistant.loader] Loaded spotify from homeassistant.components.spotify
2021-02-14 17:03:42 INFO (SyncWorker_1) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2021-02-14 17:03:42 INFO (SyncWorker_3) [homeassistant.loader] Loaded plex from homeassistant.components.plex
2021-02-14 17:03:43 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:54051 />" data_endpoint="<HPAI 10.0.0.16:54051 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 17:03:44 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 17:03:44 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 17:03:44 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:47 INFO (SyncWorker_2) [homeassistant.loader] Loaded synology_dsm from homeassistant.components.synology_dsm
2021-02-14 17:03:47 INFO (SyncWorker_1) [homeassistant.loader] Loaded harmony from homeassistant.components.harmony
2021-02-14 17:03:47 INFO (SyncWorker_0) [homeassistant.loader] Loaded upnp from homeassistant.components.upnp
2021-02-14 17:03:47 INFO (SyncWorker_4) [homeassistant.loader] Loaded remote from homeassistant.components.remote
2021-02-14 17:03:47 INFO (MainThread) [homeassistant.setup] Setting up remote
2021-02-14 17:03:47 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds
2021-02-14 17:03:47 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:51536 />" data_endpoint="<HPAI 10.0.0.16:51536 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 17:03:48 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 17:03:48 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 17:03:48 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:51 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:40313 />" data_endpoint="<HPAI 10.0.0.16:40313 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 17:03:52 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 17:03:52 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 17:03:52 DEBUG (MainThread) [xknx.log] Closing transport.
2021-02-14 17:03:55 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="CONNECT_REQUEST" Reserve="0" TotalLength="26" />
body="<ConnectRequest control_endpoint="<HPAI 10.0.0.16:46330 />" data_endpoint="<HPAI 10.0.0.16:46330 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2021-02-14 17:03:56 DEBUG (MainThread) [xknx.log] Error: KNX bus did not respond in time (1.0 secs) to request of type 'Connect'
2021-02-14 17:03:56 DEBUG (MainThread) [xknx.log] Could not establish connection to KNX/IP interface. CommunicationError: ConnectRequest failed. Status code: None
2021-02-14 17:03:56 DEBUG (MainThread) [xknx.log] Closing transport.

For reference, my config file:

# Configure a default setup of Home Assistant (frontend, api, etc)
default_config:

# Text to speech
tts:
  - platform: google_translate

group: !include groups.yaml
automation: !include automations.yaml
script: !include scripts.yaml
scene: !include scenes.yaml

logger:
  default: info
  logs:
    knx: debug
    xknx: debug

knx:
  light:
    - name: "knx"
      address: "1/0/9"
    - name: "knx1"
      address: "1/0/1"
    - name: "knx2"
      address: "1/0/2"
    - name: "knx-lamp1"
      address: "1/1/0"
    - name: "knx-lamp2"
      address: "1/1/9"

Can we conclude with this test that the issue is the EibPort or is there still a possibility HA is not behaving as it should?

farmio · February 14, 2021, 6:54pm

Yes, looks like something is wrong with the EibPort.
A final test would be to listen to network traffic with wireshsark and filter knx. But I guess you’ll just see telegrams from the Pi and none from EibPort.
Strange thing is that you can use it with ETS…

Does the EibPort have any debug logs that can help you?

MGaeremynck · February 15, 2021, 8:43pm

Let me check how to access those debug logs on the EibPort.

Not sure if we can assume that if ETS works, tunneling also works at all moments.

I can connect to the EibPort by IP. Once connected, I can use ETS to configure my KNX components. This doesn’t mean by default that all packages are exchanged between IP/KNX bus or does it?

Playing in ETS I found following, this seems to work although autodiscovery didn’t work. I assume autodiscovery is based on multicast. I checked the router (ORBI50) but no settings related to multicast

More and more convinced that something is not working at the EibPort side. In ETS I have 2 connection methods; EIB (which I use to program) and KNX/IP. Testing KNX/IP fails as you can see below.

Seems [TinusK] is the only one that connected an EibPort with HomeAssistant. Would love to know how he made it work.

Tomorrow I will check for the EibPort Logs and see if this can help us further.

MGaeremynck · February 20, 2021, 9:47am

Although I still have a V2.1 I could use the video below to configure my EibPort (interface didn’t change that much):

This said, no luck neither. In the meantime I contacted Bab-tech support with the logs. Let’s see if we find a solution. If not, I may invest in a simple KNX/IP router, any suggestions?

I also have the cgi interface that I could use but I imagine this will not provide me a status of the light.

MGaeremynck · February 27, 2021, 9:02am

As BabTec doesn’t seem to find a solution why it doesn’t work (EibPort is configured correctly according to them). Any tips on a reliable KNX/IP Tunneling device that is hassle free and compatible with Home Assistant?

farmio · February 27, 2021, 9:49am

Well, until now every knx tunneling interface (without knx secure) was compatible.

kirimeister · March 1, 2021, 8:26pm

I’m using eibport as a gateway for tunelling and it works fine. Do not use it simultaeously with ETS, because then it might break. Try resetting the BCU in the eibport system configuration and then try restarting your HA immediately.

spacegaier · March 1, 2021, 9:03pm

I am also using an EibPort (Firmware Version 3.8.2) for the tunneling and so far had no issues.

MGaeremynck · March 3, 2021, 7:22am

@kirimeister @spacegaier @farmio

Thank you all for your support. I connected with BabTec support and it seems that the EibPort 2.1 is to old for this.

“CommunicationError: ConnectRequest failed”


As I now, the old KNX IP interface in EIBPORT is not answering to a request.

So you have to choose a new KNX IP interface like ABB IP/S  or so….

@kirimeister @spacegaier I assume you have an EibPort version 3?

kirimeister · March 3, 2021, 4:06pm

I’m also using the latest firmware version, so it’s really worth doing an update.

MGaeremynck · March 3, 2021, 8:54pm

I can’t update the 2.1 any further. The 3.0 is a major hardware/software
release. Sadly, BabTec has not further developed the 2.1 or proposed any exchange program.