search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
69.83k stars 28.95k forks source link

Knx crash issues of IP interface #24492

Closed laoweifeng closed 4 years ago

laoweifeng commented 5 years ago

Home Assistant release with the issue:

Last working Home Assistant release (if known): Hass.io V0.94.2

Operating environment (Hass.io/Docker/Windows/etc.):

Hass.io **Component/platform:**

https://www.home-assistant.io/components/knx/

Description of problem:

There are 3 issues about the KNX component:

1) The KNX heartbeat is lost and this error only display in the KNX.log and I prefer to have a Knx heartbeat sensor to monitor it. But 2) the Hass.io UI can continue to operate the KNX related devices such as the switches and lights. it shows the normal status for the operator but in fact, the KNX ip interface already lost and all the KNX devices cannot really operate. 3) The KNX Humidity sensor can normally display in Home assistant UI but cannot display in Homekit by the Homekit component (non-Homebirdge). It looked working well at 0.93.x version but after upgrade, they disappear. 4) after upgrade to 0.94.x, all the KNX devices which display in Homekit lost their original location, which is mean that Bedroom lights locate to Bedroom before, after upgrade to the latest version, this lights change to belong to the IT-Rack.

Problem-relevant configuration.yaml entries and (fill out even if it seems unimportant):

homekit:

# Discover some devices automatically
discovery:
  enable:
    - homekit

knx:
  tunneling:
    host: 192.168.1.234
    port: 3671
    local_ip: 192.168.1.238
    #rate_limit: 6

sensor:
  - platform: knx
    name: 1F_BR5 Hum
    address: '6/1/1'
    type: humidity

Traceback (if applicable):

019-06-12 16:08:29 WARNING (MainThread) [xknx.log] Heartbeat failed - reconnecting
2019-06-12 16:08:31 ERROR (MainThread) [homeassistant.core] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 228, in do_heartbeat
    await self.do_heartbeat_impl()
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 236, in do_heartbeat_impl
    await self.do_heartbeat_failed()
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 248, in do_heartbeat_failed
    await self.reconnect()
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 191, in reconnect
    await self.start()
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 83, in start
    await self.connect()
  File "/usr/local/lib/python3.7/site-packages/xknx/io/tunnel.py", line 104, in connect
    raise XKNXException("Could not establish connection")
xknx.exceptions.exception.XKNXException: Could not establish connection
2019-06-12 16:08:33 WARNING (MainThread) [xknx.log] Heartbeat failed - reconnecting
2019-06-12 16:19:18 WARNING (MainThread) [xknx.log] Could not read value of <Switch name="2F Bathroom FAN" switch="GroupAddress("2/4/2")/GroupAddress("2/0/2")/<DPTBinary value="0" />/Value.OFF" /> 2/0/2

Additional information:

ghost commented 5 years ago

Hey there @Julius2342, mind taking a look at this issue as its been labeled with a integration (knx) you are listed as a codeowner for? Thanks!

This is a automatic comment generated by codeowners-mention to help ensure issues and pull requests are seen by the right people.

farmio commented 5 years ago

Is your knx component working for a while and then loosing its connection or does this occur immediately at start of HA? Does this happen at fixed intervals?

How do you connect to your knx interface? What device are you using? Wlan or cable? Are the ip addresses fixed (local and interface)? I was loosing connection to my knx interface when I updated my networking devices (router and switch). After a restart of my HA server everything went fine again.

🤔 the last change to the knx component was in 0.90 so I don't know what causes the homekit sensors behaving diffrently on 0.94 and 0.93 . Maybe have a look at this issue: https://github.com/XKNX/xknx/issues/194

laoweifeng commented 5 years ago

@farmio thanks for your reply.

The KNX IP/interface working for about 8hours or 20 hours when I set the rate_limit to 6. and then I tried the 2 but it still crash within 24 hours.

But I found a phenomenon that when I restart the KNX ip/interface (WEINZIERL Interface 731), it showed me a lot of error (the flash and red indicator), about 1 minute later, it working normally. if I reboot the Hass.io, there is the same.

knx:
  tunneling:
    host: 192.168.1.234
    port: 3671
    local_ip: 192.168.1.238
  rate_limit: 6

So, I think the KNX/IP router will be working well with Hass.io and I bought a Gira IP router Gira 216700 and it is on its way. Before I replace the IP interface, I add the fire_event to the config file as bellowing

knx:
  tunneling:
    host: 192.168.1.234
    port: 3671
    local_ip: 192.168.1.238
  rate_limit: 5
  fire_event: true
  fire_event_filter: ["1/0/*","1/1/*", "2/0/*","2/1/*","10/1/*","1/3/1,11","1/4/*","2/4/*","1/5-7/*","2/5-7/*","6/0/*"]

The first time, the ip interface worked about 24 hours and then crash. I reboot the interface again and set the rate_limit from 2 to 5. and now, it works about 4 days without crash till now.

As you can seen, there is not the fixed interval whereas I always can see the error indicator when 731 power on.

I connected the devices all by "NETGEAR SOHO GS324 24-Port Gigabit Unmanaged Switch" with wired cables and fixed ip address by router (Ubiquiti EdgeRouter ER-X Advanced Gigabit Router ) or by ETS setting such as Knx/ip interface IP address setting. but all the Mac addresses are managing by router and I check them again without conflict. All the others networking devices are working well, I tried to plug-off the RJ45 from the KNX/IP interface and Plug-in again but the problem cannot be solved. the only way that I re-power on the KNX/IP interface. (at the same time, I do not need to touch Hass.io @ Raspberry 3, it can restore working well after the KNX/IP interface reboot)

about the humidity sensor, I will try it later with your advice because I want to have a look that how long the ip/interface can work for before next Monday(1-July)

farmio commented 5 years ago

I use Gira 216700 myself and it works flawlessly. I have not set a rate limit (default is 20) and I don't think values as low as 2 (2 telegrams per second) will work properly. (If you really have to lower it, I'd try something like 15 but I doubt it's necessary.)

Does the Weinzierl provide any logs? Maybe they run a webserver like the Gira devices. Try navigating to http://192.168.1.234 and see if you find something. Are HA and ETS the only applications connecting to the interface?

fire_event only affects how received telegrams are forwarded internally. It should not affect the connection.

The humidity sensor device_class will work with the next version of the knx component (it was released yesterday and will come to HA in a couple of weeks I think).

laoweifeng commented 5 years ago

Are you apply the filter when you use Gira 216700? I bought it just want to setup the filter for both sides.

the reason why I set the rate limit so law is that I once to control a Siri voice command to turn on and 5 seconds later turn off all the lights (about 40 lights) and the KNX/IP interface crashed immediately. I also test the normal KNX switches after that, they were working well. after I rebooted the KNX/IP interface, everything came back in HA and HomeKit.

Regarding to the Weinzierl 731, I only can test it on next Monday. So, I also will remove the fire_event configuration on that day.

And I can confirm there are only HA and ETS connect to the KNX/IP interface.

Thanks for your help!

farmio commented 5 years ago

Do you mean Group Address filter tables for different KNX lines/areas? Then yes I do use it but it works just as well without.

You could use a scene instead of triggering 40 lights successively - this would lower your bus load.

laoweifeng commented 5 years ago

Yes, I just mean Group Address filter. And I will continue to optimize the configuration next week.

BTW, could you add a KNX heartbeat function in the KNX component in the future, and then, create a heartbeat sensor in HA can easily to identify the communication situation between HA and KNX system.

farmio commented 5 years ago

You won't need this once you have a stable interface 😉 A lost connection should recover itself. I know this doesn't work in every case, and if I find time I will investigate why. To me your problem sounds like the Weinzierl is defective.

laoweifeng commented 4 years ago

Hi, today I replace the IP interface to Gira 216700 router. But it cannot run with routing or tunneling, can you do me a favours to give me your config? I already removed the secure setting for this router and the ETS function is working well. Thanks!

farmio commented 4 years ago

My config won't be of any help as my ip-addresses will be different. Normally you wouldn't need any configuration at all (only knx: without any subconfigs) to get it working. If this doesn't work, something in your LAN is blocking the (multicast)traffic.

Are there any errors in the home-assistant log?

laoweifeng commented 4 years ago

after setting only with "knx:", the log as following for your reference

2019-07-12 23:57:03 WARNING (MainThread) [homeassistant.components.http] Configuring api_password via the http component has been deprecated. Use the legacy api password auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#legacy-api-password
2019-07-12 23:57:03 WARNING (MainThread) [homeassistant.components.http] Configuring trusted_networks via the http component has been deprecated. Use the trusted networks auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#trusted-networks
2019-07-12 23:57:03 WARNING (MainThread) [homeassistant.components.http.auth] legacy_api_password support has been enabled.
2019-07-12 23:57:23 WARNING (MainThread) [homeassistant.setup] Setup of person is taking over 10 seconds.
2019-07-12 23:57:25 WARNING (MainThread) [homeassistant.setup] Setup of xiaomi_aqara is taking over 10 seconds.
2019-07-12 23:57:25 WARNING (MainThread) [homeassistant.setup] Setup of knx is taking over 10 seconds.
2019-07-12 23:57:26 ERROR (MainThread) [xknx.log] <CouldNotParseKNXIP description="wrong HPAI length" />
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/site-packages/xknx/io/udp_client.py", line 87, in data_received_callback
    knxipframe.from_knx(raw)
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/knxip.py", line 78, in from_knx
    pos += self.body.from_knx(data[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/connect_response.py", line 55, in from_knx
    pos += self.control_endpoint.from_knx(raw[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/hpai.py", line 23, in from_knx
    raise CouldNotParseKNXIP("wrong HPAI length")
xknx.exceptions.exception.CouldNotParseKNXIP: <CouldNotParseKNXIP description="wrong HPAI length" />
2019-07-12 23:57:26 WARNING (MainThread) [homeassistant.components.alarm_control_panel] Setup of platform manual is taking over 10 seconds.
2019-07-12 23:57:27 WARNING (MainThread) [homeassistant.components.weather] Setup of platform met is taking over 10 seconds.
2019-07-12 23:57:27 WARNING (MainThread) [homeassistant.components.sensor] Setup of platform yr is taking over 10 seconds.
2019-07-12 23:57:27 WARNING (MainThread) [homeassistant.components.switch] Setup of platform broadlink is taking over 10 seconds.
2019-07-12 23:57:27 WARNING (MainThread) [homeassistant.components.knx] Can't connect to KNX interface: Could not establish connection
2019-07-12 23:57:35 ERROR (MainThread) [homeassistant.components.yr.sensor] Retrying in 17 minutes: 
2019-07-12 23:57:36 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/1.9/ returned 
2019-07-12 23:57:36 ERROR (MainThread) [homeassistant.components.met.weather] Retrying in 18 minutes
farmio commented 4 years ago

Something seems very odd here. Are you able to capture the raw udp packets using wireshark or any equivalent and share the .pcap?

If this is no option you could try to add

logger:
  default: info
  logs:
    xknx: debug

to your configuration and post the logs again.

laoweifeng commented 4 years ago

I only can do the operation remotely at midnight.:-)

2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-07-13 00:48:28 WARNING (MainThread) [homeassistant.components.http] Configuring api_password via the http component has been deprecated. Use the legacy api password auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#legacy-api-password
2019-07-13 00:48:28 WARNING (MainThread) [homeassistant.components.http] Configuring trusted_networks via the http component has been deprecated. Use the trusted networks auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#trusted-networks
2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setting up http
2019-07-13 00:48:28 WARNING (MainThread) [homeassistant.components.http.auth] legacy_api_password support has been enabled.
2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.0 seconds.
2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-07-13 00:48:28 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 3.5 seconds.
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setting up group
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2019-07-13 00:48:32 INFO (SyncWorker_13) [homeassistant.loader] Loaded yr from homeassistant.components.yr
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setting up xiaomi_aqara
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2019-07-13 00:48:32 INFO (SyncWorker_12) [homeassistant.loader] Loaded manual from homeassistant.components.manual
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setting up updater
2019-07-13 00:48:32 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up ssdp
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setup of domain ssdp took 0.0 seconds.
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up person
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up configurator
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setup of domain configurator took 0.0 seconds.
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up homekit
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setting up met
2019-07-13 00:48:34 INFO (MainThread) [homeassistant.setup] Setup of domain met took 0.0 seconds.
2019-07-13 00:48:36 INFO (MainThread) [homeassistant.setup] Setting up esphome
2019-07-13 00:48:36 INFO (MainThread) [homeassistant.setup] Setup of domain esphome took 0.0 seconds.
2019-07-13 00:48:36 INFO (MainThread) [homeassistant.setup] Setting up knx
2019-07-13 00:48:37 DEBUG (MainThread) [xknx.log] Searching on lo / 127.0.0.1
2019-07-13 00:48:37 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-07-13 00:48:37 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.1 seconds.
2019-07-13 00:48:37 INFO (MainThread) [homeassistant.setup] Setting up sonos
2019-07-13 00:48:37 INFO (MainThread) [homeassistant.setup] Setup of domain sonos took 0.0 seconds.
2019-07-13 00:48:37 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.2 seconds.
2019-07-13 00:48:38 INFO (SyncWorker_9) [homeassistant.components.xiaomi_aqara] Discovering Xiaomi Gateways (Try 1)
2019-07-13 00:48:38 INFO (SyncWorker_9) [xiaomi_gateway] Xiaomi Gateway 34ce008ae784 found at IP 192.168.1.236
2019-07-13 00:48:38 INFO (SyncWorker_9) [xiaomi_gateway] Discovering Xiaomi Devices
2019-07-13 00:48:38 INFO (SyncWorker_9) [xiaomi_gateway] Found 13 devices
2019-07-13 00:48:38 INFO (SyncWorker_4) [homeassistant.loader] Loaded worldclock from homeassistant.components.worldclock
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setting up alarm_control_panel
2019-07-13 00:48:38 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_REQUEST" Reserve="0" TotalLength="14" />
 body="<SearchRequest discovery_endpoint="<HPAI 127.0.0.1:52877 />" />" />
2019-07-13 00:48:38 DEBUG (MainThread) [xknx.log] Searching on eth0 / 192.168.1.238
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setup of domain homekit took 3.9 seconds.
2019-07-13 00:48:38 INFO (SyncWorker_0) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-07-13 00:48:38 INFO (SyncWorker_10) [homeassistant.loader] Loaded weather from homeassistant.components.weather
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setting up history
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2019-07-13 00:48:38 INFO (SyncWorker_6) [homeassistant.loader] Loaded google_translate from homeassistant.components.google_translate
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setting up conversation
2019-07-13 00:48:38 INFO (MainThread) [homeassistant.setup] Setup of domain conversation took 0.0 seconds.
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setting up config
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setting up script
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.setup] Setting up light
2019-07-13 00:48:39 INFO (MainThread) [homeassistant.components.alarm_control_panel] Setting up alarm_control_panel.manual
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up api
2019-07-13 00:48:41 INFO (SyncWorker_18) [homeassistant.loader] Loaded broadlink from homeassistant.components.broadlink
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up apple_tv
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-07-13 00:48:41 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_REQUEST" Reserve="0" TotalLength="14" />
 body="<SearchRequest discovery_endpoint="<HPAI 192.168.1.238:55411 />" />" />
2019-07-13 00:48:41 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on wlan0
2019-07-13 00:48:41 DEBUG (MainThread) [xknx.log] Searching on hassio / 172.30.32.1
2019-07-13 00:48:41 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_RESPONSE" Reserve="0" TotalLength="80" />
 body="<SearchResponse control_endpoint="<HPAI 192.168.1.229:3671 />" dibs="[
<DIBDeviceInformation 
    knx_medium="KNXMedium.TP1" 
    programming_mode="False" 
    individual_address="PhysicalAddress("1.1.0")" 
    installation_number="0" 
    project_number="0" 
    serial_number="00:08:13:41:05:0a" 
    multicast_address="224.0.23.12" 
    mac_address="00:0a:b3:37:04:cd" 
    name="KNX IP-Router" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 2, DIBServiceFamily.DEVICE_MANAGEMENT version: 2, DIBServiceFamily.TUNNELING version: 2, DIBServiceFamily.ROUTING version: 2, DIBServiceFamily.REMOTE_CONFIGURATION_DIAGNOSIS version: 1]" />
]" />" />
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up weather
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 0.0 seconds.
2019-07-13 00:48:41 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.yr
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.worldclock
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.5 seconds.
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setting up switch
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.components.weather] Setting up weather.met
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setup of domain config took 3.1 seconds.
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setup of domain script took 2.9 seconds.
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.components.switch] Setting up switch.broadlink
2019-07-13 00:48:42 INFO (MainThread) [homeassistant.setup] Setup of domain apple_tv took 0.6 seconds.
2019-07-13 00:48:42 INFO (SyncWorker_5) [homeassistant.loader] Loaded remote from homeassistant.components.remote
2019-07-13 00:48:43 INFO (MainThread) [homeassistant.setup] Setting up cloud
2019-07-13 00:48:43 INFO (SyncWorker_9) [xiaomi_gateway] Gateway discovery finished in 5 seconds
2019-07-13 00:48:43 INFO (SyncWorker_9) [xiaomi_gateway] Creating Multicast Socket
2019-07-13 00:48:44 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-07-13 00:48:45 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2019-07-13 00:48:45 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_REQUEST" Reserve="0" TotalLength="14" />
 body="<SearchRequest discovery_endpoint="<HPAI 172.30.32.1:44819 />" />" />
2019-07-13 00:48:45 DEBUG (MainThread) [xknx.log] Searching on docker0 / 172.17.0.1
2019-07-13 00:48:45 DEBUG (MainThread) [xknx.knx] Received: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_RESPONSE" Reserve="0" TotalLength="76" />
 body="<SearchResponse control_endpoint="<HPAI 192.168.1.234:3671 />" dibs="[
<DIBDeviceInformation 
    knx_medium="KNXMedium.TP1" 
    programming_mode="False" 
    individual_address="PhysicalAddress("1.1.1")" 
    installation_number="0" 
    project_number="0" 
    serial_number="00:c5:01:01:ee:5a" 
    multicast_address="224.0.23.12" 
    mac_address="00:24:6d:01:70:9c" 
    name="KNX IP Interface 731" />,
<DIBSuppSVCFamilies families="[DIBServiceFamily.CORE version: 1, DIBServiceFamily.DEVICE_MANAGEMENT version: 1, DIBServiceFamily.TUNNELING version: 1]" />
]" />" />
2019-07-13 00:48:45 WARNING (MainThread) [homeassistant.setup] Setup of xiaomi_aqara is taking over 10 seconds.
2019-07-13 00:48:45 WARNING (MainThread) [homeassistant.setup] Setup of person is taking over 10 seconds.
2019-07-13 00:48:45 INFO (MainThread) [homeassistant.setup] Setting up media_player
2019-07-13 00:48:45 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.
2019-07-13 00:48:46 INFO (MainThread) [homeassistant.setup] Setting up zeroconf
2019-07-13 00:48:46 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.SEARCH_REQUEST" Reserve="0" TotalLength="14" />
 body="<SearchRequest discovery_endpoint="<HPAI 172.17.0.1:37277 />" />" />
2019-07-13 00:48:46 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth20e1cce
2019-07-13 00:48:46 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethc34575a
2019-07-13 00:48:46 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethff9d76b
2019-07-13 00:48:46 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on veth1222e9b
2019-07-13 00:48:46 INFO (MainThread) [xknx.log] Could not connect to an KNX/IP device on vethc2dad31
2019-07-13 00:48:46 DEBUG (MainThread) [xknx.log] Starting tunnel to 192.168.1.229:3671 from 192.168.1.238
2019-07-13 00:48:47 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.sonos
2019-07-13 00:48:47 INFO (MainThread) [xknx.log] closing transport None
2019-07-13 00:48:47 INFO (MainThread) [xknx.log] closing transport None
2019-07-13 00:48:47 INFO (MainThread) [xknx.log] closing transport None
2019-07-13 00:48:47 INFO (MainThread) [xknx.log] closing transport None
2019-07-13 00:48:47 WARNING (MainThread) [homeassistant.setup] Setup of knx is taking over 10 seconds.
2019-07-13 00:48:47 INFO (MainThread) [homeassistant.helpers.storage] Migrating onboarding storage from 2 to 3
2019-07-13 00:48:47 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 2.6 seconds.
2019-07-13 00:48:49 INFO (SyncWorker_3) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2019-07-13 00:48:49 INFO (SyncWorker_18) [homeassistant.loader] Loaded lock from homeassistant.components.lock
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.apple_tv
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 6.3 seconds.
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 7.2 seconds.
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setting up remote
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds.
2019-07-13 00:48:49 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.CONNECT_REQUEST" Reserve="0" TotalLength="26" />
 body="<ConnectRequest control_endpoint="<HPAI 192.168.1.238:41015 />" data_endpoint="<HPAI 192.168.1.238:41015 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain zeroconf took 3.1 seconds.
2019-07-13 00:48:49 INFO (SyncWorker_4) [homeassistant.loader] Loaded notify from homeassistant.components.notify
2019-07-13 00:48:49 ERROR (MainThread) [xknx.log] <CouldNotParseKNXIP description="wrong HPAI length" />
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/site-packages/xknx/io/udp_client.py", line 87, in data_received_callback
    knxipframe.from_knx(raw)
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/knxip.py", line 78, in from_knx
    pos += self.body.from_knx(data[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/connect_response.py", line 55, in from_knx
    pos += self.control_endpoint.from_knx(raw[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/hpai.py", line 23, in from_knx
    raise CouldNotParseKNXIP("wrong HPAI length")
xknx.exceptions.exception.CouldNotParseKNXIP: <CouldNotParseKNXIP description="wrong HPAI length" />
2019-07-13 00:48:49 WARNING (MainThread) [homeassistant.components.alarm_control_panel] Setup of platform manual is taking over 10 seconds.
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 7.9 seconds.
2019-07-13 00:48:49 INFO (MainThread) [homeassistant.setup] Setup of domain xiaomi_aqara took 17.4 seconds.
2019-07-13 00:48:50 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-07-13 00:48:50 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 0.5 seconds.
2019-07-13 00:48:50 INFO (MainThread) [homeassistant.components.remote] Setting up remote.apple_tv
2019-07-13 00:48:50 INFO (MainThread) [homeassistant.setup] Setting up notify
2019-07-13 00:48:50 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.0 seconds.
2019-07-13 00:48:50 WARNING (MainThread) [homeassistant.components.knx] Can't connect to KNX interface: Could not establish connection
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain knx took 14.5 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setting up cover
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setting up lock
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setting up map
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setting up panel_custom
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain panel_custom took 0.0 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.notify] Setting up notify.mobile_app
2019-07-13 00:48:51 INFO (SyncWorker_17) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2019-07-13 00:48:51 INFO (SyncWorker_16) [homeassistant.loader] Loaded scene from homeassistant.components.scene
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.cover] Setting up cover.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.components.notify] Setting up notify.knx
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain person took 17.7 seconds.
2019-07-13 00:48:51 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 7.8 seconds.
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.components.cover] Setting up cover.xiaomi_aqara
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.components.lock] Setting up lock.xiaomi_aqara
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.setup] Setting up climate
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.setup] Setting up scene
2019-07-13 00:48:52 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.0 seconds.
2019-07-13 00:48:52 WARNING (MainThread) [homeassistant.components.sensor] Setup of platform yr is taking over 10 seconds.
2019-07-13 00:48:52 WARNING (MainThread) [homeassistant.components.weather] Setup of platform met is taking over 10 seconds.
2019-07-13 00:48:52 WARNING (MainThread) [homeassistant.components.switch] Setup of platform broadlink is taking over 10 seconds.
2019-07-13 00:48:53 INFO (MainThread) [homeassistant.setup] Setting up hassio
2019-07-13 00:48:53 ERROR (MainThread) [homeassistant.components.yr.sensor] Retrying in 16 minutes: 
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.setup] Setting up default_config
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.components.climate] Setting up climate.knx
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.components.scene] Setting up scene.knx
2019-07-13 00:49:01 WARNING (MainThread) [homeassistant.components.binary_sensor] Setup of platform knx is taking over 10 seconds.
2019-07-13 00:49:01 WARNING (MainThread) [homeassistant.components.light] Setup of platform knx is taking over 10 seconds.
2019-07-13 00:49:01 WARNING (MainThread) [homeassistant.components.sensor] Setup of platform knx is taking over 10 seconds.
2019-07-13 00:49:01 WARNING (MainThread) [homeassistant.components.switch] Setup of platform knx is taking over 10 seconds.
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.setup] Setup of domain alarm_control_panel took 23.7 seconds.
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 29.5 seconds.
2019-07-13 00:49:01 INFO (SyncWorker_14) [homeassistant.loader] Loaded stream from homeassistant.components.stream
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 19.9 seconds.
2019-07-13 00:49:01 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 20.2 seconds.
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.esphome
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.esphome
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.setup] Setup of domain light took 22.3 seconds.
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.xiaomi_aqara
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.knx
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.switch] Setting up switch.xiaomi_aqara
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.xiaomi_aqara
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.setup] Setting up stream
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.setup] Setup of domain stream took 0.0 seconds.
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.light] Setting up light.xiaomi_aqara
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.9 seconds.
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 9.5 seconds.
2019-07-13 00:49:02 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to 192.168.1.231
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 37.00s
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm triggered
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.core] Timer:starting
2019-07-13 00:49:04 INFO (SyncWorker_14) [pyhap.accessory_driver] Loading Accessory state from `/config/.homekit.state`
2019-07-13 00:49:04 INFO (SyncWorker_1) [pyhap.accessory_driver] Starting the event loop
2019-07-13 00:49:04 INFO (SyncWorker_0) [pyhap.accessory_driver] Starting accessory Home Assistant Bridge on address 192.168.1.238, port 51827.
2019-07-13 00:49:04 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sensornode3.local
2019-07-13 00:49:04 INFO (SyncWorker_5) [homeassistant.loader] Loaded homekit_controller from homeassistant.components.homekit_controller
2019-07-13 00:49:05 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.235', 49178).
2019-07-13 00:49:05 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:05 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:05 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:10 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:10 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:10 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:10 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:11 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:14 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:14 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:15 WARNING (MainThread) [homeassistant.components.websocket_api.http.connection.1806696720] Disconnected: Did not receive auth message within 10 seconds
2019-07-13 00:49:15 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.94', 50405).
2019-07-13 00:49:17 INFO (Thread-24) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:18 INFO (Thread-24) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:18 INFO (Thread-24) [pyhap.hap_server] 192.168.1.94 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:19 INFO (SyncWorker_17) [homeassistant.loader] Loaded homematicip_cloud from homeassistant.components.homematicip_cloud
2019-07-13 00:49:22 INFO (SyncWorker_0) [homeassistant.loader] Loaded smhi from homeassistant.components.smhi
2019-07-13 00:49:23 INFO (SyncWorker_5) [homeassistant.loader] Loaded lifx from homeassistant.components.lifx
2019-07-13 00:49:26 INFO (SyncWorker_6) [homeassistant.loader] Loaded iqvia from homeassistant.components.iqvia
2019-07-13 00:49:27 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.72', 49547).
2019-07-13 00:49:27 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.162', 57393).
2019-07-13 00:49:27 INFO (Thread-25) [pyhap.hap_server] 192.168.1.72 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:27 INFO (Thread-26) [pyhap.hap_server] 192.168.1.162 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:27 INFO (Thread-25) [pyhap.hap_server] 192.168.1.72 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:27 INFO (Thread-26) [pyhap.hap_server] 192.168.1.162 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:28 INFO (Thread-25) [pyhap.hap_server] 192.168.1.72 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:28 INFO (Thread-26) [pyhap.hap_server] 192.168.1.162 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:28 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.94', 50406).
2019-07-13 00:49:29 INFO (Thread-27) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:29 INFO (Thread-27) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:29 INFO (Thread-27) [pyhap.hap_server] 192.168.1.94 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:31 INFO (SyncWorker_10) [homeassistant.loader] Loaded somfy from homeassistant.components.somfy
2019-07-13 00:49:31 INFO (SyncWorker_3) [homeassistant.loader] Loaded life360 from homeassistant.components.life360
2019-07-13 00:49:32 INFO (SyncWorker_4) [homeassistant.loader] Loaded ambient_station from homeassistant.components.ambient_station
2019-07-13 00:49:33 INFO (SyncWorker_11) [homeassistant.loader] Loaded unifi from homeassistant.components.unifi
2019-07-13 00:49:34 INFO (SyncWorker_13) [homeassistant.loader] Loaded geofency from homeassistant.components.geofency
2019-07-13 00:49:37 INFO (SyncWorker_17) [homeassistant.loader] Loaded wemo from homeassistant.components.wemo
2019-07-13 00:49:38 INFO (SyncWorker_12) [homeassistant.loader] Loaded tradfri from homeassistant.components.tradfri
2019-07-13 00:49:38 INFO (Thread-22) [pyhap.hap_server] Got connection with ('192.168.1.162', 57394).
2019-07-13 00:49:39 INFO (Thread-28) [pyhap.hap_server] 192.168.1.162 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:39 INFO (SyncWorker_10) [homeassistant.loader] Loaded hue from homeassistant.components.hue
2019-07-13 00:49:39 INFO (Thread-28) [pyhap.hap_server] 192.168.1.162 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 00:49:39 INFO (SyncWorker_3) [homeassistant.loader] Loaded upnp from homeassistant.components.upnp
2019-07-13 00:49:39 INFO (Thread-28) [pyhap.hap_server] 192.168.1.162 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 00:49:40 INFO (SyncWorker_4) [homeassistant.loader] Loaded mqtt from homeassistant.components.mqtt
2019-07-13 00:49:40 INFO (SyncWorker_14) [homeassistant.loader] Loaded deconz from homeassistant.components.deconz
2019-07-13 00:49:41 INFO (SyncWorker_18) [homeassistant.loader] Loaded dialogflow from homeassistant.components.dialogflow
2019-07-13 00:49:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded simplisafe from homeassistant.components.simplisafe
2019-07-13 00:49:43 INFO (SyncWorker_8) [homeassistant.loader] Loaded openuv from homeassistant.components.openuv
2019-07-13 00:49:44 INFO (SyncWorker_5) [homeassistant.loader] Loaded heos from homeassistant.components.heos
2019-07-13 00:49:46 INFO (SyncWorker_7) [homeassistant.loader] Loaded owntracks from homeassistant.components.owntracks
2019-07-13 00:49:46 INFO (SyncWorker_19) [homeassistant.loader] Loaded zha from homeassistant.components.zha
2019-07-13 00:49:48 INFO (SyncWorker_16) [homeassistant.loader] Loaded luftdaten from homeassistant.components.luftdaten
2019-07-13 00:49:49 INFO (SyncWorker_2) [homeassistant.loader] Loaded nest from homeassistant.components.nest
2019-07-13 00:49:50 INFO (SyncWorker_6) [homeassistant.loader] Loaded logi_circle from homeassistant.components.logi_circle
2019-07-13 00:49:51 INFO (SyncWorker_11) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2019-07-13 00:49:51 INFO (SyncWorker_13) [homeassistant.loader] Loaded ifttt from homeassistant.components.ifttt
2019-07-13 00:49:51 INFO (SyncWorker_17) [homeassistant.loader] Loaded locative from homeassistant.components.locative
2019-07-13 00:49:52 INFO (SyncWorker_12) [homeassistant.loader] Loaded ps4 from homeassistant.components.ps4
2019-07-13 00:49:52 INFO (SyncWorker_10) [homeassistant.loader] Loaded axis from homeassistant.components.axis
2019-07-13 00:49:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded daikin from homeassistant.components.daikin
2019-07-13 00:49:53 INFO (Thread-27) [pyhap.hap_server] 192.168.1.94 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:49:53 INFO (SyncWorker_4) [homeassistant.loader] Loaded ambiclimate from homeassistant.components.ambiclimate
2019-07-13 00:49:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded rainmachine from homeassistant.components.rainmachine
2019-07-13 00:49:53 INFO (SyncWorker_0) [homeassistant.loader] Loaded smartthings from homeassistant.components.smartthings
2019-07-13 00:49:54 INFO (SyncWorker_9) [homeassistant.loader] Loaded adguard from homeassistant.components.adguard
2019-07-13 00:49:54 INFO (SyncWorker_8) [homeassistant.loader] Loaded hangouts from homeassistant.components.hangouts
2019-07-13 00:49:54 INFO (SyncWorker_5) [homeassistant.loader] Loaded ios from homeassistant.components.ios
2019-07-13 00:49:54 INFO (SyncWorker_7) [homeassistant.loader] Loaded emulated_roku from homeassistant.components.emulated_roku
2019-07-13 00:49:54 INFO (SyncWorker_19) [homeassistant.loader] Loaded plaato from homeassistant.components.plaato
2019-07-13 00:49:54 INFO (SyncWorker_16) [homeassistant.loader] Loaded zone from homeassistant.components.zone
2019-07-13 00:49:54 INFO (SyncWorker_2) [homeassistant.loader] Loaded gpslogger from homeassistant.components.gpslogger
2019-07-13 00:49:54 INFO (SyncWorker_18) [homeassistant.loader] Loaded zwave from homeassistant.components.zwave
2019-07-13 00:49:54 INFO (SyncWorker_6) [homeassistant.loader] Loaded twilio from homeassistant.components.twilio
2019-07-13 00:49:54 INFO (SyncWorker_11) [homeassistant.loader] Loaded tellduslive from homeassistant.components.tellduslive
2019-07-13 00:49:54 INFO (SyncWorker_13) [homeassistant.loader] Loaded tplink from homeassistant.components.tplink
2019-07-13 00:49:54 INFO (SyncWorker_17) [homeassistant.loader] Loaded ipma from homeassistant.components.ipma
2019-07-13 00:49:54 INFO (SyncWorker_12) [homeassistant.loader] Loaded toon from homeassistant.components.toon
2019-07-13 00:49:54 INFO (SyncWorker_10) [homeassistant.loader] Loaded mailgun from homeassistant.components.mailgun
2019-07-13 00:49:54 INFO (SyncWorker_3) [homeassistant.loader] Loaded point from homeassistant.components.point
2019-07-13 00:49:55 INFO (Thread-28) [pyhap.hap_server] 192.168.1.162 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Found new service: apple_tv {'host': '192.168.1.235', 'port': 3689, 'hostname': 'kikyoshitsu.local.', 'properties': {'DFID': '4', 'EiTS': '1', 'mV': '131082', 'aV': '196620', 'hG': '00000000-51d3-4906-a588-f9eb24054eac', 'fs': '2', 'Name': '起居室', 'atSV': '65539', 'Ver': '131075'}, 'name': '起居室'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Found new service: xiaomi_gw {'host': '192.168.1.236', 'port': 54321, 'hostname': 'lumi-gateway-v3_miio57465655.local.', 'properties': {'epoch': '1', 'mac': '34ce008ae784'}, 'mac_address': '34ce008ae784'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'host': '192.168.1.98', 'port': 1400, 'hostname': 'sonos7828CAA239F8.local.', 'properties': {'VERSION': '1.0', 'CPath': '/spotifyzc'}}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'host': '192.168.1.99', 'port': 1400, 'hostname': 'sonos949F3EF7CC78.local.', 'properties': {'VERSION': '1.0', 'CPath': '/spotifyzc'}}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'host': '192.168.1.98', 'port': 1400, 'ssdp_description': 'http://192.168.1.98:1400/xml/device_description.xml', 'name': '192.168.1.98 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_7828CAA239F801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'host': '192.168.1.99', 'port': 1400, 'ssdp_description': 'http://192.168.1.99:1400/xml/device_description.xml', 'name': '192.168.1.99 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_949F3EF7CC7801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'host': '192.168.1.98', 'port': 1400, 'ssdp_description': 'http://192.168.1.98:1400/xml/device_description.xml', 'name': '192.168.1.98 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_7828CAA239F801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'host': '192.168.1.99', 'port': 1400, 'ssdp_description': 'http://192.168.1.99:1400/xml/device_description.xml', 'name': '192.168.1.99 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_949F3EF7CC7801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: home_assistant {'host': '192.168.1.238', 'port': 8123, 'hostname': '199 Home._home-assistant._tcp.local.', 'properties': {'version': '0.95.4', 'base_url': 'http://192.168.1.238:8123', 'requires_api_password': True}}
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.apple_tv
2019-07-13 00:50:03 INFO (MainThread) [homeassistant.components.remote] Setting up remote.apple_tv
2019-07-13 00:50:03 INFO (MainThread) [pyatv.daap] Logged in and got session id 21
2019-07-13 00:50:03 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "GET /characteristics?id=937494080.5 HTTP/1.1" 207 -
2019-07-13 00:50:03 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "GET /characteristics?id=937625154.5 HTTP/1.1" 207 -
2019-07-13 00:50:03 INFO (Thread-23) [pyhap.hap_server] 192.168.1.235 - "GET /characteristics?id=937428543.5 HTTP/1.1" 207 -
farmio commented 4 years ago

I only can do the operation remotely at midnight.:-)

Please do, the logs don't reveal any more information except that both interfaces are found. If you want to strictly connect to one of them you can add 

knx:
  tunneling:
    host: '192.168.1.229'
    port: 3671
    local_ip: '192.168.1.238'

(You could omit the port if you like and In the next release local_ip will be optional too.)

This could be related to https://github.com/XKNX/xknx/issues/76

laoweifeng commented 4 years ago

my original setting was above and it wasn't working with Gira216700 but 731 is working. The logs are following after I change the setting what you recommended.

019-07-13 10:40:46 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-07-13 10:40:46 WARNING (MainThread) [homeassistant.components.http] Configuring api_password via the http component has been deprecated. Use the legacy api password auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#legacy-api-password
2019-07-13 10:40:46 WARNING (MainThread) [homeassistant.components.http] Configuring trusted_networks via the http component has been deprecated. Use the trusted networks auth provider instead. For instructions, see https://www.home-assistant.io/docs/authentication/providers/#trusted-networks
2019-07-13 10:40:46 INFO (MainThread) [homeassistant.setup] Setting up http
2019-07-13 10:40:46 WARNING (MainThread) [homeassistant.components.http.auth] legacy_api_password support has been enabled.
2019-07-13 10:40:46 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.1 seconds.
2019-07-13 10:40:46 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-07-13 10:40:46 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-07-13 10:40:47 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-07-13 10:40:49 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 2.9 seconds.
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setting up sonos
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setup of domain sonos took 0.0 seconds.
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setting up updater
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setting up configurator
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setup of domain configurator took 0.0 seconds.
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setting up knx
2019-07-13 10:40:50 DEBUG (MainThread) [xknx.log] Starting tunnel to 192.168.1.229:3671 from 192.168.1.238
2019-07-13 10:40:50 INFO (MainThread) [homeassistant.setup] Setting up homekit
2019-07-13 10:40:51 INFO (MainThread) [homeassistant.setup] Setting up person
2019-07-13 10:40:51 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-07-13 10:40:51 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.3 seconds.
2019-07-13 10:40:51 INFO (MainThread) [homeassistant.setup] Setting up group
2019-07-13 10:40:51 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2019-07-13 10:40:51 INFO (SyncWorker_1) [homeassistant.loader] Loaded manual from homeassistant.components.manual
2019-07-13 10:40:51 INFO (SyncWorker_18) [homeassistant.loader] Loaded yr from homeassistant.components.yr
2019-07-13 10:40:53 INFO (MainThread) [homeassistant.setup] Setting up ssdp
2019-07-13 10:40:53 INFO (MainThread) [homeassistant.setup] Setup of domain ssdp took 0.0 seconds.
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.0 seconds.
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setting up met
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setup of domain met took 0.0 seconds.
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setting up esphome
2019-07-13 10:40:54 INFO (MainThread) [homeassistant.setup] Setup of domain esphome took 0.0 seconds.
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setting up xiaomi_aqara
2019-07-13 10:40:55 INFO (SyncWorker_3) [homeassistant.components.xiaomi_aqara] Discovering Xiaomi Gateways (Try 1)
2019-07-13 10:40:55 INFO (SyncWorker_7) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-07-13 10:40:55 INFO (SyncWorker_3) [xiaomi_gateway] Xiaomi Gateway 34ce008ae784 found at IP 192.168.1.236
2019-07-13 10:40:55 INFO (SyncWorker_3) [xiaomi_gateway] Discovering Xiaomi Devices
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setting up alarm_control_panel
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setup of domain homekit took 4.3 seconds.
2019-07-13 10:40:55 INFO (SyncWorker_13) [homeassistant.loader] Loaded worldclock from homeassistant.components.worldclock
2019-07-13 10:40:55 INFO (SyncWorker_12) [homeassistant.loader] Loaded weather from homeassistant.components.weather
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setting up conversation
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setup of domain conversation took 0.0 seconds.
2019-07-13 10:40:55 INFO (SyncWorker_17) [homeassistant.loader] Loaded google_translate from homeassistant.components.google_translate
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-07-13 10:40:55 INFO (SyncWorker_3) [xiaomi_gateway] Found 13 devices
2019-07-13 10:40:55 INFO (MainThread) [homeassistant.setup] Setting up config
2019-07-13 10:40:55 INFO (SyncWorker_10) [homeassistant.loader] Loaded broadlink from homeassistant.components.broadlink
2019-07-13 10:40:56 INFO (MainThread) [homeassistant.setup] Setting up apple_tv
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up api
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up script
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up light
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.components.alarm_control_panel] Setting up alarm_control_panel.manual
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up history
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-07-13 10:40:58 DEBUG (MainThread) [xknx.knx] Sending: <KNXIPFrame <KNXIPHeader HeaderLength="6" ProtocolVersion="16" KNXIPServiceType="KNXIPServiceType.CONNECT_REQUEST" Reserve="0" TotalLength="26" />
 body="<ConnectRequest control_endpoint="<HPAI 192.168.1.238:39571 />" data_endpoint="<HPAI 192.168.1.238:39571 />" request_type="ConnectRequestType.TUNNEL_CONNECTION" />" />
2019-07-13 10:40:58 ERROR (MainThread) [xknx.log] <CouldNotParseKNXIP description="wrong HPAI length" />
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/site-packages/xknx/io/udp_client.py", line 87, in data_received_callback
    knxipframe.from_knx(raw)
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/knxip.py", line 78, in from_knx
    pos += self.body.from_knx(data[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/connect_response.py", line 55, in from_knx
    pos += self.control_endpoint.from_knx(raw[pos:])
  File "/usr/local/lib/python3.7/site-packages/xknx/knxip/hpai.py", line 23, in from_knx
    raise CouldNotParseKNXIP("wrong HPAI length")
xknx.exceptions.exception.CouldNotParseKNXIP: <CouldNotParseKNXIP description="wrong HPAI length" />
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up weather
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 0.0 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up switch
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.4 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.yr
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.worldclock
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.components.weather] Setting up weather.met
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain config took 2.9 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.components.switch] Setting up switch.broadlink
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain apple_tv took 2.7 seconds.
2019-07-13 10:40:58 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.7 seconds.
2019-07-13 10:40:58 INFO (SyncWorker_4) [homeassistant.loader] Loaded remote from homeassistant.components.remote
2019-07-13 10:40:59 INFO (MainThread) [homeassistant.setup] Setting up cloud
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2019-07-13 10:41:00 WARNING (MainThread) [homeassistant.setup] Setup of knx is taking over 10 seconds.
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setting up media_player
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds.
2019-07-13 10:41:00 WARNING (MainThread) [homeassistant.components.knx] Can't connect to KNX interface: Could not establish connection
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setup of domain knx took 10.2 seconds.
2019-07-13 10:41:00 INFO (SyncWorker_3) [xiaomi_gateway] Gateway discovery finished in 5 seconds
2019-07-13 10:41:00 INFO (SyncWorker_3) [xiaomi_gateway] Creating Multicast Socket
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.setup] Setting up zeroconf
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.sonos
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:00 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:01 WARNING (MainThread) [homeassistant.setup] Setup of person is taking over 10 seconds.
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.helpers.storage] Migrating onboarding storage from 2 to 3
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 2.6 seconds.
2019-07-13 10:41:01 INFO (SyncWorker_4) [homeassistant.loader] Loaded notify from homeassistant.components.notify
2019-07-13 10:41:01 INFO (SyncWorker_7) [homeassistant.loader] Loaded scene from homeassistant.components.scene
2019-07-13 10:41:01 INFO (SyncWorker_15) [homeassistant.loader] Loaded climate from homeassistant.components.climate
2019-07-13 10:41:01 INFO (SyncWorker_8) [homeassistant.loader] Loaded cover from homeassistant.components.cover
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.apple_tv
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 1.1 seconds.
2019-07-13 10:41:01 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 2.2 seconds.
2019-07-13 10:41:01 INFO (SyncWorker_13) [homeassistant.loader] Loaded lock from homeassistant.components.lock
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setting up remote
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds.
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setting up notify
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 0.0 seconds.
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setting up scene
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.0 seconds.
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setting up climate
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds.
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 4.0 seconds.
2019-07-13 10:41:02 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-07-13 10:41:03 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.0 seconds.
2019-07-13 10:41:03 INFO (MainThread) [homeassistant.setup] Setup of domain zeroconf took 3.1 seconds.
2019-07-13 10:41:04 INFO (MainThread) [homeassistant.components.remote] Setting up remote.apple_tv
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.notify] Setting up notify.knx
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.notify] Setting up notify.mobile_app
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.scene] Setting up scene.knx
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.climate] Setting up climate.knx
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setting up cover
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain cover took 0.0 seconds.
2019-07-13 10:41:05 WARNING (MainThread) [homeassistant.setup] Setup of xiaomi_aqara is taking over 10 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setting up panel_custom
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain panel_custom took 0.0 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setting up map
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setting up lock
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain xiaomi_aqara took 10.4 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 11.4 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain person took 14.6 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 5.3 seconds.
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.cover] Setting up cover.knx
2019-07-13 10:41:05 INFO (MainThread) [homeassistant.components.cover] Setting up cover.xiaomi_aqara
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.esphome
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.setup] Setting up hassio
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.components.lock] Setting up lock.xiaomi_aqara
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.setup] Setup of domain light took 8.8 seconds.
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.knx
2019-07-13 10:41:06 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.xiaomi_aqara
2019-07-13 10:41:08 INFO (MainThread) [homeassistant.setup] Setting up default_config
2019-07-13 10:41:08 WARNING (MainThread) [homeassistant.components.sensor] Setup of platform yr is taking over 10 seconds.
2019-07-13 10:41:08 WARNING (MainThread) [homeassistant.components.weather] Setup of platform met is taking over 10 seconds.
2019-07-13 10:41:08 INFO (MainThread) [homeassistant.setup] Setup of domain alarm_control_panel took 13.5 seconds.
2019-07-13 10:41:08 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 10.3 seconds.
2019-07-13 10:41:08 INFO (SyncWorker_4) [homeassistant.loader] Loaded stream from homeassistant.components.stream
2019-07-13 10:41:10 INFO (MainThread) [homeassistant.components.light] Setting up light.knx
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.light] Setting up light.xiaomi_aqara
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.switch] Setting up switch.knx
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.switch] Setting up switch.xiaomi_aqara
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.setup] Setting up stream
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.setup] Setup of domain stream took 0.0 seconds.
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 2.8 seconds.
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 13.1 seconds.
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.esphome
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.knx
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.xiaomi_aqara
2019-07-13 10:41:11 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to 192.168.1.231
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 9.8 seconds.
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 31.39s
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm triggered
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.components.automation] Initialized trigger Alarm trigger while armed away！
2019-07-13 10:41:16 INFO (MainThread) [homeassistant.core] Timer:starting
2019-07-13 10:41:17 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sensornode3.local
2019-07-13 10:41:17 INFO (SyncWorker_6) [pyhap.accessory_driver] Loading Accessory state from `/config/.homekit.state`
2019-07-13 10:41:17 INFO (SyncWorker_13) [pyhap.accessory_driver] Starting the event loop
2019-07-13 10:41:17 INFO (SyncWorker_0) [pyhap.accessory_driver] Starting accessory Home Assistant Bridge on address 192.168.1.238, port 51827.
2019-07-13 10:41:18 INFO (SyncWorker_3) [homeassistant.loader] Loaded homekit_controller from homeassistant.components.homekit_controller
2019-07-13 10:41:18 INFO (Thread-14) [pyhap.hap_server] Got connection with ('192.168.1.235', 49179).
2019-07-13 10:41:18 INFO (Thread-14) [pyhap.hap_server] Got connection with ('192.168.1.160', 55431).
2019-07-13 10:41:18 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:18 INFO (Thread-16) [pyhap.hap_server] 192.168.1.160 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:19 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:19 INFO (Thread-16) [pyhap.hap_server] 192.168.1.160 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:19 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 10:41:19 INFO (Thread-16) [pyhap.hap_server] 192.168.1.160 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 10:41:30 INFO (Thread-16) [pyhap.hap_server] 192.168.1.160 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:30 INFO (Thread-16) [pyhap.hap_server] 192.168.1.160 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:30 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:30 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:30 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:31 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:31 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:31 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:31 INFO (Thread-15) [pyhap.hap_server] 192.168.1.235 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Found new service: apple_tv {'host': '192.168.1.235', 'port': 3689, 'hostname': 'kikyoshitsu.local.', 'properties': {'DFID': '4', 'EiTS': '1', 'mV': '131082', 'aV': '196620', 'hG': '00000000-51d3-4906-a588-f9eb24054eac', 'fs': '2', 'Name': '起居室', 'atSV': '65539', 'Ver': '131075'}, 'name': '起居室'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Found new service: xiaomi_gw {'host': '192.168.1.236', 'port': 54321, 'hostname': 'lumi-gateway-v3_miio57465655.local.', 'properties': {'epoch': '1', 'mac': '34ce008ae784'}, 'mac_address': '34ce008ae784'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'host': '192.168.1.99', 'port': 1400, 'hostname': 'sonos949F3EF7CC78.local.', 'properties': {'VERSION': '1.0', 'CPath': '/spotifyzc'}}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'host': '192.168.1.98', 'port': 1400, 'ssdp_description': 'http://192.168.1.98:1400/xml/device_description.xml', 'name': '192.168.1.98 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_7828CAA239F801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'host': '192.168.1.99', 'port': 1400, 'ssdp_description': 'http://192.168.1.99:1400/xml/device_description.xml', 'name': '192.168.1.99 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_949F3EF7CC7801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'host': '192.168.1.98', 'port': 1400, 'ssdp_description': 'http://192.168.1.98:1400/xml/device_description.xml', 'name': '192.168.1.98 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_7828CAA239F801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'host': '192.168.1.99', 'port': 1400, 'ssdp_description': 'http://192.168.1.99:1400/xml/device_description.xml', 'name': '192.168.1.99 - Sonos Play:1', 'model_name': 'Sonos Play:1', 'model_number': 'S12', 'serial': None, 'manufacturer': 'Sonos, Inc.', 'udn': 'uuid:RINCON_949F3EF7CC7801400', 'upnp_device_type': 'urn:schemas-upnp-org:device:ZonePlayer:1'}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: home_assistant {'host': '192.168.1.238', 'port': 8123, 'hostname': '199 Home._home-assistant._tcp.local.', 'properties': {'version': '0.95.4', 'base_url': 'http://192.168.1.238:8123', 'requires_api_password': True}}
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.apple_tv
2019-07-13 10:41:33 INFO (MainThread) [homeassistant.components.remote] Setting up remote.apple_tv
2019-07-13 10:41:33 INFO (MainThread) [pyatv.daap] Logged in and got session id 22
2019-07-13 10:41:34 INFO (Thread-14) [pyhap.hap_server] Got connection with ('192.168.1.94', 50412).
2019-07-13 10:41:34 INFO (Thread-17) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:34 INFO (Thread-17) [pyhap.hap_server] 192.168.1.94 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:34 INFO (Thread-17) [pyhap.hap_server] 192.168.1.94 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 10:41:36 INFO (SyncWorker_16) [homeassistant.loader] Loaded somfy from homeassistant.components.somfy
2019-07-13 10:41:40 INFO (SyncWorker_11) [homeassistant.loader] Loaded logi_circle from homeassistant.components.logi_circle
2019-07-13 10:41:41 INFO (SyncWorker_0) [homeassistant.loader] Loaded hue from homeassistant.components.hue
2019-07-13 10:41:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded simplisafe from homeassistant.components.simplisafe
2019-07-13 10:41:42 INFO (SyncWorker_12) [homeassistant.loader] Loaded point from homeassistant.components.point
2019-07-13 10:41:43 INFO (SyncWorker_3) [homeassistant.loader] Loaded zwave from homeassistant.components.zwave
2019-07-13 10:41:44 INFO (SyncWorker_17) [homeassistant.loader] Loaded tradfri from homeassistant.components.tradfri
2019-07-13 10:41:45 INFO (SyncWorker_0) [homeassistant.loader] Loaded luftdaten from homeassistant.components.luftdaten
2019-07-13 10:41:45 INFO (SyncWorker_5) [homeassistant.loader] Loaded openuv from homeassistant.components.openuv
2019-07-13 10:41:46 INFO (SyncWorker_10) [homeassistant.loader] Loaded tplink from homeassistant.components.tplink
2019-07-13 10:41:46 INFO (SyncWorker_6) [homeassistant.loader] Loaded unifi from homeassistant.components.unifi
2019-07-13 10:41:46 INFO (SyncWorker_8) [homeassistant.loader] Loaded ambiclimate from homeassistant.components.ambiclimate
2019-07-13 10:41:46 INFO (SyncWorker_15) [homeassistant.loader] Loaded upnp from homeassistant.components.upnp
2019-07-13 10:41:46 INFO (Thread-14) [pyhap.hap_server] Got connection with ('192.168.1.72', 49560).
2019-07-13 10:41:47 INFO (Thread-18) [pyhap.hap_server] 192.168.1.72 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:47 INFO (Thread-18) [pyhap.hap_server] 192.168.1.72 - "POST /pair-verify HTTP/1.1" 200 -
2019-07-13 10:41:47 INFO (SyncWorker_16) [homeassistant.loader] Loaded ios from homeassistant.components.ios
2019-07-13 10:41:47 INFO (Thread-18) [pyhap.hap_server] 192.168.1.72 - "GET /accessories HTTP/1.1" 200 -
2019-07-13 10:41:47 INFO (SyncWorker_14) [homeassistant.loader] Loaded gpslogger from homeassistant.components.gpslogger
2019-07-13 10:41:48 INFO (Thread-17) [pyhap.hap_server] 192.168.1.94 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:48 INFO (SyncWorker_3) [homeassistant.loader] Loaded mailgun from homeassistant.components.mailgun
2019-07-13 10:41:48 INFO (SyncWorker_17) [homeassistant.loader] Loaded heos from homeassistant.components.heos
2019-07-13 10:41:48 INFO (SyncWorker_0) [homeassistant.loader] Loaded owntracks from homeassistant.components.owntracks
2019-07-13 10:41:49 INFO (SyncWorker_5) [homeassistant.loader] Loaded daikin from homeassistant.components.daikin
2019-07-13 10:41:49 INFO (SyncWorker_10) [homeassistant.loader] Loaded toon from homeassistant.components.toon
2019-07-13 10:41:50 INFO (SyncWorker_6) [homeassistant.loader] Loaded deconz from homeassistant.components.deconz
2019-07-13 10:41:50 INFO (SyncWorker_8) [homeassistant.loader] Loaded smartthings from homeassistant.components.smartthings
2019-07-13 10:41:50 INFO (SyncWorker_15) [homeassistant.loader] Loaded geofency from homeassistant.components.geofency
2019-07-13 10:41:51 INFO (SyncWorker_16) [homeassistant.loader] Loaded tellduslive from homeassistant.components.tellduslive
2019-07-13 10:41:51 INFO (SyncWorker_14) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2019-07-13 10:41:51 INFO (SyncWorker_3) [homeassistant.loader] Loaded adguard from homeassistant.components.adguard
2019-07-13 10:41:51 INFO (SyncWorker_17) [homeassistant.loader] Loaded rainmachine from homeassistant.components.rainmachine
2019-07-13 10:41:52 INFO (SyncWorker_0) [homeassistant.loader] Loaded hangouts from homeassistant.components.hangouts
2019-07-13 10:41:52 INFO (Thread-18) [pyhap.hap_server] 192.168.1.72 - "PUT /characteristics HTTP/1.1" 204 -
2019-07-13 10:41:52 INFO (SyncWorker_5) [homeassistant.loader] Loaded wemo from homeassistant.components.wemo
2019-07-13 10:41:52 INFO (SyncWorker_10) [homeassistant.loader] Loaded smhi from homeassistant.components.smhi
2019-07-13 10:41:53 INFO (SyncWorker_6) [homeassistant.loader] Loaded ambient_station from homeassistant.components.ambient_station
2019-07-13 10:41:53 INFO (SyncWorker_8) [homeassistant.loader] Loaded twilio from homeassistant.components.twilio
2019-07-13 10:41:53 INFO (SyncWorker_15) [homeassistant.loader] Loaded lifx from homeassistant.components.lifx
2019-07-13 10:41:53 INFO (SyncWorker_16) [homeassistant.loader] Loaded dialogflow from homeassistant.components.dialogflow
2019-07-13 10:41:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded mqtt from homeassistant.components.mqtt
2019-07-13 10:41:54 INFO (SyncWorker_3) [homeassistant.loader] Loaded zha from homeassistant.components.zha
2019-07-13 10:41:54 INFO (SyncWorker_17) [homeassistant.loader] Loaded axis from homeassistant.components.axis
2019-07-13 10:41:54 INFO (SyncWorker_0) [homeassistant.loader] Loaded iqvia from homeassistant.components.iqvia
2019-07-13 10:41:55 INFO (SyncWorker_5) [homeassistant.loader] Loaded homematicip_cloud from homeassistant.components.homematicip_cloud
2019-07-13 10:41:55 INFO (SyncWorker_10) [homeassistant.loader] Loaded ipma from homeassistant.components.ipma
2019-07-13 10:41:55 INFO (SyncWorker_6) [homeassistant.loader] Loaded locative from homeassistant.components.locative
2019-07-13 10:41:55 INFO (SyncWorker_8) [homeassistant.loader] Loaded plaato from homeassistant.components.plaato
2019-07-13 10:41:55 INFO (SyncWorker_15) [homeassistant.loader] Loaded life360 from homeassistant.components.life360
2019-07-13 10:41:55 INFO (SyncWorker_16) [homeassistant.loader] Loaded zone from homeassistant.components.zone
2019-07-13 10:41:55 INFO (SyncWorker_14) [homeassistant.loader] Loaded ps4 from homeassistant.components.ps4
2019-07-13 10:41:55 INFO (SyncWorker_3) [homeassistant.loader] Loaded emulated_roku from homeassistant.components.emulated_roku
2019-07-13 10:41:55 INFO (SyncWorker_17) [homeassistant.loader] Loaded ifttt from homeassistant.components.ifttt
2019-07-13 10:41:55 INFO (SyncWorker_0) [homeassistant.loader] Loaded nest from homeassistant.components.nest
farmio commented 4 years ago

Well, that's essentially the same as above. Without a package capture I can only guess: the one thing coming to my mind is a conflict of physical addresses. The Gira has 2 applications that need to get loaded. See the manual.

laoweifeng commented 4 years ago

Yes. and I will try to capture the network package next Monday. Thanks!

laoweifeng commented 4 years ago

Hi, I got the packet capture file and the log files of Gira 216700 for your reference, thanks for your help. 15072019.pcapng.zip gdslogfiles.zip

farmio commented 4 years ago

I couldn't find any packets from '192.168.1.229' to '192.168.1.238' or vice versa in your capture.

laoweifeng commented 4 years ago

I retry it twice in this morning and capture the packets during the Hass reboot. I hope it can be help. thanks again! Archive.zip

laoweifeng commented 4 years ago

Oh! I looked through the packets, I only found out the KNX IP router to 224.0.23.12 but without Hass to KNX IP router. Why?

And I also concern the setting of the KNX IP router in ETS, does the following setting right?

disable the secure tunnel

image

and keep the password as empty for each tunnel

image

The structure is below and I removed the IP interface 731

image
farmio commented 4 years ago

I never used a device with knx-secure so I can't say anything about it. It looks like you only captured multicast packets, but tunnels are unicast. Are you capturing from the device running Hass? Are there multiple interfaces and you chose the wrong?

laoweifeng commented 4 years ago

so, I need to disable all the secure setting in this case.

And I just run the wireshark in a 3rd laptop. do you mean that I need to run it in Raspberry which running Hassio?

farmio commented 4 years ago

Yes this would be one option. You could also mirror the port the Raspberry is plugged in. Even easier would be to run a testing instance of xknx on your laptop. You can clone current master from github which has https://github.com/XKNX/xknx/pull/217 already merged and should tell you the error code right away (the version on pip - currently 0.11.1 - doesn't include this).

You can use this to run a simple test. Just adapt the ga_switch variable: https://gist.github.com/farmio/ba5220f60ff9ba4c59420d7edbcf1716

laoweifeng commented 4 years ago

Hi Farmio, I try the py but it shows me the error File "/config/xknx_blink.py", line 4, in <module> from xknx import XKNX ModuleNotFoundError: No module named 'xknx' so, I try to install the tcpdump in Hassio and restart the HomeAssistant service at Configuration to capture the packets.

And I try to install webSSH in HassIO first and run the code reference from "https://community.home-assistant.io/t/is-there-a-tcpdump-addon-for-hassio/44847" as following:

Step1: install the tcpdump at webssh apk add --no-cache tcpdump Step 2: use the command line to start: tcpdump -s 0 -i eth0 -w /config/knxissues.pcap

Step 3: Restart the Home assistant services

image

Step 4: after the service restarted, use "Ctrl+C" to stop capture

Step 5: copy/download the file from Hassio

If my above steps were wrong, I only can do it tomorrow again, and I hope this time can provide the useful information, thanks. knxissues3.pcap.zip

Julius2342 commented 4 years ago

For solving the ModuleNotFoundError : Set the environment variable PYTHONPATH to the path of the xknx checkout.

laoweifeng commented 4 years ago

does the capture file helpful?

farmio commented 4 years ago

So your status code is: E_CONNECTION_TYPE - The KNXnet/IP server device does not support the requested connection type (0x22)

Request:

Frame 1530: 68 bytes on wire (544 bits), 68 bytes captured (544 bits)
Ethernet II, Src: Raspberr_17:40:5f (b8:27:eb:17:40:5f), Dst: FaGira_37:04:cd (00:0a:b3:37:04:cd)
Internet Protocol Version 4, Src: 192.168.1.238, Dst: 192.168.1.229
User Datagram Protocol, Src Port: 55427, Dst Port: 3671
KNXnet/IP
    Header
        Header Length: 0x06
        Protocol Version: 0x10
        Service Type Identifier: CONNECT_REQUEST (0x0205)
        Total Length: 26 octets
    Body
        HPAI: Discovery endpoint
            Structure Length: 8 octets
            Host Protocol Code: IPV4_UDP (0x01)
            IP Address: 192.168.1.238
            IP Port: 55427
        HPAI: Data endpoint
            Structure Length: 8 octets
            Host Protocol Code: IPV4_UDP (0x01)
            IP Address: 192.168.1.238
            IP Port: 55427
        Connection Request Information
            Structure Length: 4 octets
            Connection Type: TUNNEL_CONNECTION (0x04)
            KNX Layer: TUNNEL_LINKLAYER (0x02)
            reserved: 00

Response:

Frame 1531: 60 bytes on wire (480 bits), 60 bytes captured (480 bits)
Ethernet II, Src: FaGira_37:04:cd (00:0a:b3:37:04:cd), Dst: Raspberr_17:40:5f (b8:27:eb:17:40:5f)
Internet Protocol Version 4, Src: 192.168.1.229, Dst: 192.168.1.238
User Datagram Protocol, Src Port: 3671, Dst Port: 55427
KNXnet/IP
    Header
        Header Length: 0x06
        Protocol Version: 0x10
        Service Type Identifier: CONNECT_RESPONSE (0x0206)
        Total Length: 8 octets
    Body
        Communication Channel ID: 0
        Status: E_CONNECTION_TYPE - The KNXnet/IP server device does not support the requested connection type (0x22)
        HPAI: Data endpoint
[Malformed Packet: knxnetip]
    [Expert Info (Error/Malformed): Malformed Packet (Exception occurred)]

I would never have guessed that. It should provide 4 concurrent tunneling connections. Mine does, but I have an earlier revision (without knx secure support). I don't really know what to do from here except see if there is a firmware update or contact Gira for support.

laoweifeng commented 4 years ago

I updated the firmware by GPA software from Gira and I can downgrade the firmware tomorrow to 3.x version.(Now is 4.0.x version).

So, I will like to have your Gira IP router firmware version and I try to set to same for testing; and second, I will recommend you can add a password function in KNX component for the secure connection. nowadays, ABB Knx IP router also need to set a secure connection.

farmio commented 4 years ago

Mine has 4.0.435 Knx Data Secure requires more than just transmission of a password. As far as I'm concerned every device must be registered in ETS to be able to communicate. I doubt we can get xknx certified for this, not to mention the implementation work for this probably exceeds our available free time and we have no test devices for it.

laoweifeng commented 4 years ago

After you helped me to analysed the packets, I guess the problem is on Gira 216700 itself. So I took a lot testing which focus on it.

I used the same version with ETS 5.7 and it can be working well after I reset the Gira 216700. the detail operating records as following.

And I found out that I must dis-activated all the secure setting of Gira 216700 in ETS first and reset the Gira 216700 by GPA SW, and then, I download the data via USB-Knx dongel.

secondly, only set the knx: in the Hassio config file: /config/configuration.yaml

after reboot the Hassio, it is working.

At this moment, I did not use the filter table in the Knx ip router and it looks working well with huge control command such as all lights turn on or turn off by Hassio.group.

stale[bot] commented 4 years ago

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.