Philips HUE bulb not paired

Hi, I have conbee2 with Hassio + ZHA. I have tried several times to reset HUE bulb and pair it, but it is impossible. My bulb is this one, with packed dimmer: https://www.amazon.com/Philips-Hue-Dimmable-Installation-Assistant/dp/B01N4OMRJ9/ref=sr_1_28?keywords=philips+hue+white+bulbs&qid=1573159244&sr=8-28
I was able to pair aqara sensor, hue dimmer, but not the hue bulb
this are steps which I do:
call service zha.permit with duration 60
from configuration > zha > add device
turn off the bulb
resetting HUE bulb with dimmer (I+O until it blinks 3 times , and then stay on)
Here are debug logs:
2019-11-07 21:08:41 INFO (SyncWorker_0) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor,
2019-11-07 21:08:41 INFO (SyncWorker_4) [homeassistant.loader] Loaded mobile_app from homeassistant.components.mobile_app,
2019-11-07 21:08:41 INFO (SyncWorker_1) [homeassistant.loader] Loaded input_boolean from homeassistant.components.input_boolean,
2019-11-07 21:08:41 INFO (SyncWorker_1) [homeassistant.loader] Loaded input_number from homeassistant.components.input_number,
2019-11-07 21:08:41 INFO (SyncWorker_9) [homeassistant.loader] Loaded light from homeassistant.components.light,
2019-11-07 21:08:41 INFO (SyncWorker_5) [homeassistant.loader] Loaded cloud from homeassistant.components.cloud,
2019-11-07 21:08:41 INFO (SyncWorker_6) [homeassistant.loader] Loaded logger from homeassistant.components.logger,
2019-11-07 21:08:41 INFO (SyncWorker_0) [homeassistant.loader] Loaded discovery from homeassistant.components.discovery,
2019-11-07 21:08:41 INFO (SyncWorker_9) [homeassistant.loader] Loaded input_datetime from homeassistant.components.input_datetime,
2019-11-07 21:08:41 INFO (SyncWorker_8) [homeassistant.loader] Loaded updater from homeassistant.components.updater,
2019-11-07 21:08:41 INFO (SyncWorker_1) [homeassistant.loader] Loaded history from homeassistant.components.history,
2019-11-07 21:08:41 INFO (SyncWorker_5) [homeassistant.loader] Loaded conversation from homeassistant.components.conversation,
2019-11-07 21:08:41 INFO (SyncWorker_4) [homeassistant.loader] Loaded xiaomi_aqara from homeassistant.components.xiaomi_aqara,
2019-11-07 21:08:41 INFO (SyncWorker_3) [homeassistant.loader] Loaded group from homeassistant.components.group,
2019-11-07 21:08:41 INFO (SyncWorker_2) [homeassistant.loader] Loaded tts from homeassistant.components.tts,
2019-11-07 21:08:41 INFO (SyncWorker_8) [homeassistant.loader] Loaded automation from homeassistant.components.automation,
2019-11-07 21:08:41 INFO (SyncWorker_8) [homeassistant.loader] Loaded input_text from homeassistant.components.input_text,
2019-11-07 21:08:41 INFO (SyncWorker_1) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend,
2019-11-07 21:08:41 INFO (SyncWorker_3) [homeassistant.loader] Loaded map from homeassistant.components.map,
2019-11-07 21:08:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded config from homeassistant.components.config,
2019-11-07 21:08:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded logbook from homeassistant.components.logbook,
2019-11-07 21:08:42 INFO (SyncWorker_1) [homeassistant.loader] Loaded hassio from homeassistant.components.hassio,
2019-11-07 21:08:42 INFO (SyncWorker_4) [homeassistant.loader] Loaded weather from homeassistant.components.weather,
2019-11-07 21:08:42 INFO (SyncWorker_1) [homeassistant.loader] Loaded sun from homeassistant.components.sun,
2019-11-07 21:08:42 INFO (SyncWorker_5) [homeassistant.loader] Loaded http from homeassistant.components.http,
2019-11-07 21:08:42 INFO (SyncWorker_1) [homeassistant.loader] Loaded input_select from homeassistant.components.input_select,
2019-11-07 21:08:42 INFO (SyncWorker_6) [homeassistant.loader] Loaded script from homeassistant.components.script,
2019-11-07 21:08:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded system_health from homeassistant.components.system_health,
2019-11-07 21:08:42 INFO (SyncWorker_4) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player,
2019-11-07 21:08:42 INFO (SyncWorker_4) [homeassistant.loader] Loaded webhook from homeassistant.components.webhook,
2019-11-07 21:08:42 INFO (SyncWorker_8) [homeassistant.loader] Loaded heos from homeassistant.components.heos,
2019-11-07 21:08:42 INFO (SyncWorker_0) [homeassistant.loader] Loaded dialogflow from homeassistant.components.dialogflow,
2019-11-07 21:08:42 INFO (SyncWorker_2) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor,
2019-11-07 21:08:42 INFO (SyncWorker_4) [homeassistant.loader] Loaded recorder from homeassistant.components.recorder,
2019-11-07 21:08:42 INFO (SyncWorker_8) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom,
2019-11-07 21:08:42 INFO (SyncWorker_1) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification,
2019-11-07 21:08:42 INFO (SyncWorker_6) [homeassistant.loader] Loaded device_automation from homeassistant.components.device_automation,
2019-11-07 21:08:42 INFO (SyncWorker_3) [homeassistant.loader] Loaded ios from homeassistant.components.ios,
2019-11-07 21:08:42 INFO (SyncWorker_7) [homeassistant.loader] Loaded met from homeassistant.components.met,
2019-11-07 21:08:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded api from homeassistant.components.api,
2019-11-07 21:08:42 INFO (SyncWorker_5) [homeassistant.loader] Loaded homeassistant from homeassistant.components.homeassistant,
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.setup] Setting up persistent_notification,
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.setup] Setup of domain persistent_notification took 0.0 seconds.,
2019-11-07 21:08:42 INFO (SyncWorker_2) [homeassistant.loader] Loaded auth from homeassistant.components.auth,
2019-11-07 21:08:42 INFO (SyncWorker_0) [homeassistant.loader] Loaded device_tracker from homeassistant.components.device_tracker,
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.setup] Setting up homeassistant,
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.setup] Setup of domain homeassistant took 0.0 seconds.,
2019-11-07 21:08:42 INFO (SyncWorker_8) [homeassistant.loader] Loaded lovelace from homeassistant.components.lovelace,
2019-11-07 21:08:42 INFO (SyncWorker_3) [homeassistant.loader] Loaded onboarding from homeassistant.components.onboarding,
2019-11-07 21:08:42 INFO (SyncWorker_9) [homeassistant.loader] Loaded system_log from homeassistant.components.system_log,
2019-11-07 21:08:42 INFO (SyncWorker_6) [homeassistant.loader] Loaded zone from homeassistant.components.zone,
2019-11-07 21:08:42 INFO (SyncWorker_4) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api,
2019-11-07 21:08:42 INFO (SyncWorker_0) [homeassistant.loader] Loaded zeroconf from homeassistant.components.zeroconf,
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.bootstrap] Setting up {'logger', 'system_log'},
2019-11-07 21:08:42 INFO (MainThread) [homeassistant.setup] Setting up logger,
2019-11-07 21:08:43 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=7 from 2019-11-04 22:20:17.680801),
2019-11-07 21:08:44 ERROR (MainThread) [homeassistant.setup] Setup failed for input_text: Integration failed to initialize.,
2019-11-07 21:08:45 ERROR (MainThread) [homeassistant.setup] Setup failed for input_select: Integration failed to initialize.,
2019-11-07 21:08:48 ERROR (MainThread) [homeassistant.components.google_cloud.tts] File /config/secrets/google_cloud.json doesn't exist,
2019-11-07 21:08:48 ERROR (MainThread) [homeassistant.components.tts] Error setting up platform google_cloud,
2019-11-07 21:08:49 ERROR (SyncWorker_6) [homeassistant.components.ios.notify] The notify.ios platform was loaded but no devices exist! Please check the documentation at https://home-assistant.io/ecosystem/ios/notifications/ for more information,
2019-11-07 21:08:57 WARNING (MainThread) [homeassistant.components.binary_sensor] Setup of platform ping is taking over 10 seconds.,
Config directory: /config,
Tries remaining: 2,
Tries remaining: 3,
Tries remaining: 3,
Tries remaining: 3,
2019-11-07 21:11:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection.139706634976592] Error handling message: expected dict for dictionary value @ data['service_data']. Got 'duration:250',
Traceback (most recent call last):,
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/connection.py", line 96, in async_handle,
    handler(self.hass, self, schema(msg)),
  File "/usr/local/lib/python3.7/site-packages/voluptuous/schema_builder.py", line 272, in __call__,
    return self._compiled([], data),
  File "/usr/local/lib/python3.7/site-packages/voluptuous/schema_builder.py", line 594, in validate_dict,
    return base_validate(path, iteritems(data), out),
  File "/usr/local/lib/python3.7/site-packages/voluptuous/schema_builder.py", line 432, in validate_mapping,
    raise er.MultipleInvalid(errors),
voluptuous.error.MultipleInvalid: expected dict for dictionary value @ data['service_data'],
Tries remaining: 2,
2019-11-07 21:11:49 WARNING (MainThread) [zigpy.util] Error calling listener.node_descriptor_updated: FOREIGN KEY constraint failed,
Tries remaining: 3,
Tries remaining: 3,
Tries remaining: 2,
2019-11-07 21:14:14 WARNING (MainThread) [zigpy.util] Error calling listener.node_descriptor_updated: FOREIGN KEY constraint failed,
Tries remaining: 3,
Tries remaining: 3,
Tries remaining: 3,
2019-11-07 21:15:46 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:15:46 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:15:57 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:15:57 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:18 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:33 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:40 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:40 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:41 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:17:42 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:18:23 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:21:33 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:30:33 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.write_parameter' command,
2019-11-07 21:30:33 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/zigpy_deconz/zigbee/application.py", line 36, in _reset_watchdog,
    await self._api.write_parameter(NetworkParameter.watchdog_ttl, 3600),
  File "/usr/local/lib/python3.7/site-packages/zigpy_deconz/api.py", line 174, in _command,
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT),
  File "/usr/local/lib/python3.7/asyncio/tasks.py", line 449, in wait_for,
    raise futures.TimeoutError(),
concurrent.futures._base.TimeoutError,
2019-11-07 21:37:02 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:37:39 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:37:39 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:37:40 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:37:41 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 21:45:17 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:00:00 INFO (Thread-5) [limitlessled.pipeline] Starting a new pipeline on group 1 (Obývačka stena) @ 192.168.2.13,
2019-11-07 22:00:00 INFO (Thread-5) [limitlessled.pipeline]  -> Running stage 'off()' on group 1 (Obývačka stena) @ 192.168.2.13,
2019-11-07 22:00:00 INFO (Thread-6) [limitlessled.pipeline] Starting a new pipeline on group 2 (Obývačka okno) @ 192.168.2.13,
2019-11-07 22:00:00 INFO (Thread-5) [limitlessled.pipeline] Finished pipeline on group 1 (Obývačka stena) @ 192.168.2.13,
2019-11-07 22:00:00 INFO (Thread-6) [limitlessled.pipeline]  -> Running stage 'off()' on group 2 (Obývačka okno) @ 192.168.2.13,
2019-11-07 22:00:00 INFO (Thread-6) [limitlessled.pipeline] Finished pipeline on group 2 (Obývačka okno) @ 192.168.2.13,
2019-11-07 22:03:02 ERROR (MainThread) [frontend.js.latest.201910251] https://lan.chovan.net:8123/frontend_latest/chunk.cca25d3cf542a8e8fae0.js:632:681 TypeError: this.hass is undefined,
2019-11-07 22:03:49 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:04:11 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:04:33 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:04:55 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:05:17 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:05:39 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:06:01 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:06:23 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:06:45 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:07:01 INFO (MainThread) [homeassistant.components.zha.api] Permitting joins for 250s,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 113 under 114 request id, data: b'71fa00',
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 114, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'q\xfa\x00', 2, 0),
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x122b0018001100720001fcff0000360000030071fa000200,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x122b00090002002272,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 114],
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2c000700aa00,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172c000800010001,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172c0021001a002201fcff010200000000003600030071fa0000aff1ba5b000013,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'q\xfa\x00', 0, 175, 241, 186, 91, 0, 0, 19],
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [250, <Bool.false: 0>],
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'71fa00',
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2d000700a600,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x042d0007000000,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x042d0012000b00227201fcff000000000000,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 114: 00,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x72 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xfa'),
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b2e000900020021fa,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b2e000800010021,
2019-11-07 22:07:01 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:07:07 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 115 under 116 request id, data: b'733c00',
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 116, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b's<\x00', 2, 0),
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x122f0018001100740001fcff00003600000300733c000200,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x122f00090002002274,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 116],
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e30000700aa00,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1730000800010001,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17300021001a002201fcff0102000000000036000300733c0000aff1bd5b000013,
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b's<\x00', 0, 175, 241, 189, 91, 0, 0, 19],
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:07:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'733c00',
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e31000700a600,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04310007000000,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04310012000b00227401fcff000000000000,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 116: 00,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x74 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b320009000200213c,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b32000800010021,
2019-11-07 22:07:18 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:07:28 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:07:50 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:08:12 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:08:34 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:08:56 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:09:18 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:09:40 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:10:02 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:10:24 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:10:31 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'\x10\x0e\x00\x00'),
2019-11-07 22:10:31 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b33000c00050026100e0000,
2019-11-07 22:10:31 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b33000800010026,
2019-11-07 22:10:31 DEBUG (MainThread) [zigpy_deconz.api] Write parameter watchdog_ttl: SUCCESS,
2019-11-07 22:10:46 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:11:08 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:11:30 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:11:51 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:12:13 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:12:35 DEBUG (SyncWorker_5) [asyncio] Using selector: EpollSelector,
2019-11-07 22:12:57 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 117 under 118 request id, data: b'753c00',
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 118, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'u<\x00', 2, 0),
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12340018001100760001fcff00003600000300753c000200,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x123400090002002276,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 118],
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e35000700aa00,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1735000800010001,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17350021001a002201fcff0102000000000036000300753c0000aff1d65b000013,
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'u<\x00', 0, 175, 241, 214, 91, 0, 0, 19],
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:13:02 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'753c00',
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e36000700a600,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04360007000000,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04360012000b00227601fcff000000000000,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 118: 00,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x76 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b370009000200213c,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b37000800010021,
2019-11-07 22:13:03 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:13:19 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:13:40 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:14:02 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:14:24 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:14:46 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:15:08 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:15:13 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] Shutting down ZHA ControllerApplication,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded updater from homeassistant.components.updater,
2019-11-07 22:15:30 INFO (SyncWorker_1) [homeassistant.loader] Loaded ios from homeassistant.components.ios,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player,
2019-11-07 22:15:30 INFO (SyncWorker_6) [homeassistant.loader] Loaded input_datetime from homeassistant.components.input_datetime,
2019-11-07 22:15:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded input_select from homeassistant.components.input_select,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded xiaomi_aqara from homeassistant.components.xiaomi_aqara,
2019-11-07 22:15:30 INFO (SyncWorker_1) [homeassistant.loader] Loaded config from homeassistant.components.config,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded weather from homeassistant.components.weather,
2019-11-07 22:15:30 INFO (SyncWorker_7) [homeassistant.loader] Loaded met from homeassistant.components.met,
2019-11-07 22:15:30 INFO (SyncWorker_8) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded system_health from homeassistant.components.system_health,
2019-11-07 22:15:30 INFO (SyncWorker_5) [homeassistant.loader] Loaded map from homeassistant.components.map,
2019-11-07 22:15:30 INFO (SyncWorker_5) [homeassistant.loader] Loaded input_number from homeassistant.components.input_number,
2019-11-07 22:15:30 INFO (SyncWorker_3) [homeassistant.loader] Loaded group from homeassistant.components.group,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded conversation from homeassistant.components.conversation,
2019-11-07 22:15:30 INFO (SyncWorker_8) [homeassistant.loader] Loaded hassio from homeassistant.components.hassio,
2019-11-07 22:15:30 INFO (SyncWorker_8) [homeassistant.loader] Loaded heos from homeassistant.components.heos,
2019-11-07 22:15:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded cloud from homeassistant.components.cloud,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded discovery from homeassistant.components.discovery,
2019-11-07 22:15:30 INFO (SyncWorker_3) [homeassistant.loader] Loaded input_boolean from homeassistant.components.input_boolean,
2019-11-07 22:15:30 INFO (SyncWorker_8) [homeassistant.loader] Loaded input_text from homeassistant.components.input_text,
2019-11-07 22:15:30 INFO (SyncWorker_6) [homeassistant.loader] Loaded script from homeassistant.components.script,
2019-11-07 22:15:30 INFO (SyncWorker_5) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor,
2019-11-07 22:15:30 INFO (SyncWorker_9) [homeassistant.loader] Loaded logbook from homeassistant.components.logbook,
2019-11-07 22:15:30 INFO (SyncWorker_3) [homeassistant.loader] Loaded logger from homeassistant.components.logger,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded http from homeassistant.components.http,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded mobile_app from homeassistant.components.mobile_app,
2019-11-07 22:15:30 INFO (SyncWorker_7) [homeassistant.loader] Loaded history from homeassistant.components.history,
2019-11-07 22:15:30 INFO (SyncWorker_1) [homeassistant.loader] Loaded tts from homeassistant.components.tts,
2019-11-07 22:15:30 INFO (SyncWorker_5) [homeassistant.loader] Loaded zha from homeassistant.components.zha,
2019-11-07 22:15:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded dialogflow from homeassistant.components.dialogflow,
2019-11-07 22:15:30 INFO (SyncWorker_7) [homeassistant.loader] Loaded device_tracker from homeassistant.components.device_tracker,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded homeassistant from homeassistant.components.homeassistant,
2019-11-07 22:15:30 INFO (SyncWorker_9) [homeassistant.loader] Loaded automation from homeassistant.components.automation,
2019-11-07 22:15:30 INFO (SyncWorker_2) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification,
2019-11-07 22:15:30 INFO (SyncWorker_3) [homeassistant.loader] Loaded light from homeassistant.components.light,
2019-11-07 22:15:30 INFO (SyncWorker_6) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor,
2019-11-07 22:15:30 INFO (SyncWorker_8) [homeassistant.loader] Loaded sun from homeassistant.components.sun,
2019-11-07 22:15:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded webhook from homeassistant.components.webhook,
2019-11-07 22:15:30 INFO (SyncWorker_5) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom,
2019-11-07 22:15:30 INFO (SyncWorker_7) [homeassistant.loader] Loaded recorder from homeassistant.components.recorder,
2019-11-07 22:15:30 INFO (SyncWorker_1) [homeassistant.loader] Loaded api from homeassistant.components.api,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded zone from homeassistant.components.zone,
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.setup] Setting up homeassistant,
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.setup] Setup of domain homeassistant took 0.0 seconds.,
2019-11-07 22:15:30 INFO (SyncWorker_9) [homeassistant.loader] Loaded device_automation from homeassistant.components.device_automation,
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.setup] Setting up persistent_notification,
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.setup] Setup of domain persistent_notification took 0.0 seconds.,
2019-11-07 22:15:30 INFO (SyncWorker_3) [homeassistant.loader] Loaded auth from homeassistant.components.auth,
2019-11-07 22:15:30 INFO (SyncWorker_6) [homeassistant.loader] Loaded zeroconf from homeassistant.components.zeroconf,
2019-11-07 22:15:30 INFO (SyncWorker_0) [homeassistant.loader] Loaded lovelace from homeassistant.components.lovelace,
2019-11-07 22:15:30 INFO (SyncWorker_7) [homeassistant.loader] Loaded onboarding from homeassistant.components.onboarding,
2019-11-07 22:15:30 INFO (SyncWorker_4) [homeassistant.loader] Loaded system_log from homeassistant.components.system_log,
2019-11-07 22:15:30 INFO (SyncWorker_9) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api,
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.bootstrap] Setting up {'system_log', 'logger'},
2019-11-07 22:15:30 INFO (MainThread) [homeassistant.setup] Setting up logger,
2019-11-07 22:15:31 ERROR (MainThread) [homeassistant.setup] Setup failed for input_select: Integration failed to initialize.,
2019-11-07 22:15:32 ERROR (MainThread) [homeassistant.setup] Setup failed for input_text: Integration failed to initialize.,
2019-11-07 22:15:36 ERROR (MainThread) [homeassistant.components.google_cloud.tts] File /config/secrets/google_cloud.json doesn't exist,
2019-11-07 22:15:36 ERROR (MainThread) [homeassistant.components.tts] Error setting up platform google_cloud,
2019-11-07 22:15:36 ERROR (SyncWorker_7) [homeassistant.components.ios.notify] The notify.ios platform was loaded but no devices exist! Please check the documentation at https://home-assistant.io/ecosystem/ios/notifications/ for more information,
2019-11-07 22:15:44 WARNING (MainThread) [homeassistant.components.binary_sensor] Setup of platform ping is taking over 10 seconds.,
2019-11-07 22:16:56 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1a000c0005000267d325b0,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1b000c0005000267d34ab0,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e1c000700aa00,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x171a000800010001,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x171a002a0023002202fdff000267d300000013000c002567d379217306018817008000af4aed5b0000b0,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b'%g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 74, 237, 91, 0, 0, -80],
2019-11-07 22:17:11 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:17:11 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1b000c0005000267d325b0,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'2567d3792173060188170080',
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e1c000700aa00,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x171b000800010001,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x171b00290022002202fcff000267d300000000000b0026bbd404ffff2e2100000000af4fee5b0000b1,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [34, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffc>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 0, b'&\xbb\xd4\x04\xff\xff.!\x00\x00\x00', 0, 175, 79, 238, 91, 0, 0, -79],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0000: [00:21:2e:ff:ff:04:d4:bb, 0, 0],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 38 under 5 request id, data: b'2600bbd404ffff2e210000000000',
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (29, 5, 0, <DeconzAddressEndpoint address_mode=2 address=0xd367 endpoint=0>, 0, 0x8000, 0, b'&\x00\xbb\xd4\x04\xff\xff.!\x00\x00\x00\x00\x00', 2, 0),
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x121c0024001d0005000267d30000000080000e002600bbd404ffff2e2100000000000200,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0000, data: b'26bbd404ffff2e21000000',
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x121c00090002002205,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 5],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1d000c0005000267d34fb1,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e1e000700a600,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1f000c0005000267d34fb1,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x041d0007000000,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x041d0013000c0022050267d300000000000000,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 5: 00,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x05 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd367 endpoint=0>, status: 0x00,
2019-11-07 22:17:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1e000c0005000267d34ab0,
2019-11-07 22:17:12 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c1f000c0005000267d34ab0,
2019-11-07 22:17:12 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c20000c0005000267d34ab0,
2019-11-07 22:17:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c21000c0005000267d34fb1,
2019-11-07 22:17:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c22000c0005000267d34ab0,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c23000c0005000267d34ab0,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 6 under 7 request id, data: b'063c00',
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 7, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x06<\x00', 2, 0),
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x121e0018001100070001fcff00003600000300063c000200,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x121e00090002002207,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 7],
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e1f000700aa00,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x171f000800010001,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x171f0021001a002201fcff0102000000000036000300063c0000aff1f35b000013,
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x06<\x00', 0, 175, 241, 243, 91, 0, 0, 19],
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'063c00',
2019-11-07 22:17:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c20000c0005000267d34ab0,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e21000700a600,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04200007000000,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04200012000b00220701fcff000000000000,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 7: 00,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x07 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b210009000200213c,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b21000800010021,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c22000c0005000267d34fb1,
2019-11-07 22:17:15 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c23000c0005000267d377b1,
2019-11-07 22:17:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c24000c0005000267d34ab0,
2019-11-07 22:17:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c25000c0005000267d37fb2,
2019-11-07 22:17:18 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:17:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c26000c0005000267d325b0,
2019-11-07 22:17:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c27000c0005000267d34ab0,
2019-11-07 22:17:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c28000c0005000267d387b3,
2019-11-07 22:17:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c29000c0005000267d37fb2,
2019-11-07 22:17:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2a000c0005000267d34fb1,
2019-11-07 22:17:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2b000c0005000267d377b1,
2019-11-07 22:17:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2c000c0005000267d377b1,
2019-11-07 22:17:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2d000c0005000267d37fb2,
2019-11-07 22:17:40 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2e000700aa00,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1722000800010001,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2f000c0005000267d345af,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1722002b00240022020000010267d302040100fc0d001d0b106900040000300021000000af25f95b0000b0,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10i\x00\x04\x00\x000\x00!\x00\x00', 0, 175, 37, 249, 91, 0, 0, -80],
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=105 command_id=0>,
2019-11-07 22:17:55 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x00!\x00\x00',
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1069000400003000210000',
2019-11-07 22:17:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c23000c0005000267d322af,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c24000c0005000267d38fb4,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c25000c0005000267d38fb4,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e26000700aa00,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1723000800010001,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1723002a0023002202fdff000267d300000013000c002a67d379217306018817008000af8ff95b0000b4,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b'*g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 143, 249, 91, 0, 0, -76],
2019-11-07 22:17:56 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:17:56 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'2a67d3792173060188170080',
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c24000c0005000267d387b3,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e25000700aa00,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1724000800010001,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1724002b00240022020000010267d302040100fc0d001d0b106b00040000300221020000af87fb5b0000b3,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10k\x00\x04\x00\x000\x02!\x02\x00', 0, 175, 135, 251, 91, 0, 0, -77],
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=107 command_id=0>,
2019-11-07 22:17:56 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x02\x00',
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:17:56 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b106b000400003002210200',
2019-11-07 22:17:57 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c25000c0005000267d387b3,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c26000c0005000267d355b2,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e27000700aa00,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1725000800010001,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e28000700aa00,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1725002b0024002a020000010267d302040100fc0d001d0b106c00040000300021000000af7ffd5b0000b2,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 42, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10l\x00\x04\x00\x000\x00!\x00\x00', 0, 175, 127, 253, 91, 0, 0, -78],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=108 command_id=0>,
2019-11-07 22:17:58 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x00!\x00\x00',
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1726000800010001,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b106c000400003000210000',
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17260023001c002201fe64000267d301040106000500016d40000000af7ffd5b0000b2,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x64fe>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 1, 260, 6, b'\x01m@\x00\x00', 0, 175, 127, 253, 91, 0, 0, -78],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=109 command_id=64>,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL request 0x0040: [0, 0],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] No handler for cluster command 64,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'016d400000',
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e27000700aa00,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1727000800010001,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1727002b00240022020000010267d302040100fc0d001d0b106e00040000300221020000af87fb5b0000b3,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10n\x00\x04\x00\x000\x02!\x02\x00', 0, 175, 135, 251, 91, 0, 0, -77],
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=110 command_id=0>,
2019-11-07 22:17:58 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x02\x00',
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b106e000400003002210200',
2019-11-07 22:17:58 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c28000c0005000267d387b3,
2019-11-07 22:17:59 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c29000c0005000267d38fb4,
2019-11-07 22:17:59 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2a000c0005000267d39fb6,
2019-11-07 22:18:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2b000c0005000267d39fb6,
2019-11-07 22:18:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2c000c0005000267d377b1,
2019-11-07 22:18:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2d000c0005000267d325b0,
2019-11-07 22:18:02 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:18:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2e000c0005000267d322af,
2019-11-07 22:18:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2f000c0005000267d34ab0,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c30000c0005000267d34fb1,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c31000c0005000267d355b2,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e32000700aa00,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1728000800010001,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1728002a0023002202fdff000267d300000013000c002c67d379217306018817008000af7fff5b0000b2,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b',g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 127, 255, 91, 0, 0, -78],
2019-11-07 22:18:07 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:18:07 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'2c67d3792173060188170080',
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c29000c0005000267d37fb2,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2a000700aa00,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1729000800010001,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1729002b00240022020000010267d302040100fc0d001d0b107100010000300221020000af4f005c0000b1,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10q\x00\x01\x00\x000\x02!\x02\x00', 0, 175, 79, 0, 92, 0, 0, -79],
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=113 command_id=0>,
2019-11-07 22:18:07 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x02!\x02\x00',
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1071000100003002210200',
2019-11-07 22:18:08 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2a000c0005000267d34fb1,
2019-11-07 22:18:09 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2b000c0005000267d34fb1,
2019-11-07 22:18:10 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2c000c0005000267d38fb4,
2019-11-07 22:18:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2d000c0005000267d34fb1,
2019-11-07 22:18:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2e000c0005000267d34fb1,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2f000c0005000267d37fb2,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e30000700aa00,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172a000800010001,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172a002a0023002202fdff000267d300000013000c002e67d379217306018817008000af87035c0000b3,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b'.g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 135, 3, 92, 0, 0, -77],
2019-11-07 22:18:22 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:18:22 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'2e67d3792173060188170080',
2019-11-07 22:18:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2b000c0005000267d34fb1,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2c000700aa00,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172b000800010001,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172b002b00240022020000010267d302040100fc0d001d0b107a00010000300121380000af77055c0000b1,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10z\x00\x01\x00\x000\x01!8\x00', 0, 175, 119, 5, 92, 0, 0, -79],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=122 command_id=0>,
2019-11-07 22:18:23 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x01!8\x00',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b107a000100003001213800',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2c000c0005000267d325b0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2d000c0005000267d34ab0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2e000700aa00,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172c000800010001,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172c002b00240022020000010267d302040100fc0d001d0b107b00010000300121400000af25065c0000b0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10{\x00\x01\x00\x000\x01!@\x00', 0, 175, 37, 6, 92, 0, 0, -80],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=123 command_id=0>,
2019-11-07 22:18:23 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x01!@\x00',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b107b000100003001214000',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2d000700aa00,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172d000800010001,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172d002b00240022020000010267d302040100fc0d001d0b107c00010000300321410000af22065c0000af,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10|\x00\x01\x00\x000\x03!A\x00', 0, 175, 34, 6, 92, 0, 0, -81],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=124 command_id=0>,
2019-11-07 22:18:23 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x03!A\x00',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b107c000100003003214100',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2e000700aa00,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172e000800010001,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e2f000700aa00,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172e002b0024002a020000010267d302040100fc0d001d0b107d00010000300021000000af4f065c0000b1,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 42, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10}\x00\x01\x00\x000\x00!\x00\x00', 0, 175, 79, 6, 92, 0, 0, -79],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=125 command_id=0>,
2019-11-07 22:18:23 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x00!\x00\x00',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x172f000800010001,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c2f000c0005000267d34ab0,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b107d000100003000210000',
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x172f0021001a002201fe64000267d301040106000300017e0100af4f065c0000b1,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x64fe>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 1, 260, 6, b'\x01~\x01', 0, 175, 79, 6, 92, 0, 0, -79],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=126 command_id=1>,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL request 0x0001: [],
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] No handler for cluster command 1,
2019-11-07 22:18:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'017e01',
2019-11-07 22:18:24 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c30000c0005000267d37fb2,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c31000c0005000267d37fb2,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e32000700aa00,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1730000800010001,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1730002b00240022020000010267d302040100fc0d001d0b107f00010000300121080000af77075c0000b1,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\x7f\x00\x01\x00\x000\x01!\x08\x00', 0, 175, 119, 7, 92, 0, 0, -79],
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=127 command_id=0>,
2019-11-07 22:18:24 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x01!\x08\x00',
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b107f000100003001210800',
2019-11-07 22:18:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c31000c0005000267d355b2,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c32000c0005000267d37fb2,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c33000c0005000267d37fb2,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e34000700aa00,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1731000800010001,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1731002b00240022020000010267d302040100fc0d001d0b108000010000300121100000af4f085c0000b1,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\x80\x00\x01\x00\x000\x01!\x10\x00', 0, 175, 79, 8, 92, 0, 0, -79],
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=128 command_id=0>,
2019-11-07 22:18:25 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x01!\x10\x00',
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1080000100003001211000',
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c32000c0005000267d37fb2,
2019-11-07 22:18:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c33000c0005000267d34fb1,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c34000c0005000267d34fb1,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e35000700aa00,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1732000800010001,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1732002b00240022020000010267d302040100fc0d001d0b108100010000300121180000af4f085c0000b1,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\x81\x00\x01\x00\x000\x01!\x18\x00', 0, 175, 79, 8, 92, 0, 0, -79],
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=129 command_id=0>,
2019-11-07 22:18:26 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x01\x00\x000\x01!\x18\x00',
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:18:26 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1081000100003001211800',
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 8 under 9 request id, data: b'083c00',
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 9, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x08<\x00', 2, 0),
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12330018001100090001fcff00003600000300083c000200,
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x123300090002002209,
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 9],
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e34000700aa00,
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1734000800010001,
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17340021001a002201fcff0102000000000036000300083c0000af00085c000007,
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x08<\x00', 0, 175, 0, 8, 92, 0, 0, 7],
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:18:37 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'083c00',
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e35000700a600,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04350007000000,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04350012000b00220901fcff000000000000,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 9: 00,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x09 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b360009000200213c,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b36000800010021,
2019-11-07 22:18:38 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:18:46 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:19:08 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:19:11 INFO (MainThread) [homeassistant.components.zha.api] Permitting joins for 250s,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 10 under 11 request id, data: b'0afa00',
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 11, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\n\xfa\x00', 2, 0),
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x123700180011000b0001fcff000036000003000afa000200,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12370009000200220b,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 11],
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e38000700aa00,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1738000800010001,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17380021001a002201fcff01020000000000360003000afa0000aff1105c000013,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\n\xfa\x00', 0, 175, 241, 16, 92, 0, 0, 19],
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [250, <Bool.false: 0>],
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'0afa00',
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e39000700a600,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04390007000000,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04390012000b00220b01fcff000000000000,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 11: 00,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xfa'),
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b3a000900020021fa,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b3a000800010021,
2019-11-07 22:19:11 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:19:30 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:19:36 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3b000c0005000267d325b0,
2019-11-07 22:19:39 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3c000c0005000267d34fb1,
2019-11-07 22:19:39 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3d000c0005000267d34fb1,
2019-11-07 22:19:40 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3e000c0005000267d322af,
2019-11-07 22:19:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3f000c0005000267d322af,
2019-11-07 22:19:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c40000c0005000267d37fb2,
2019-11-07 22:19:42 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c41000c0005000267d38fb4,
2019-11-07 22:19:42 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c42000c0005000267d387b3,
2019-11-07 22:19:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c43000c0005000267d387b3,
2019-11-07 22:19:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c44000c0005000267d387b3,
2019-11-07 22:19:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c45000c0005000267d387b3,
2019-11-07 22:19:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c46000c0005000267d387b3,
2019-11-07 22:19:52 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:20:13 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:20:35 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:20:57 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:21:19 DEBUG (SyncWorker_8) [asyncio] Using selector: EpollSelector,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c47000c0005000267d39fb6,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e48000700aa00,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x173b000800010001,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x173b002a0023002202fdff000267d300000013000c003a67d379217306018817008000af971c5c0000b5,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b':g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 151, 28, 92, 0, 0, -75],
2019-11-07 22:21:32 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:21:32 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'3a67d3792173060188170080',
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3c000c0005000267d39fb6,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e3d000700aa00,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x173c000800010001,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x173c002b00240022020000010267d302040100fc0d001d0b10a900040000300221010000af9f1d5c0000b6,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\xa9\x00\x04\x00\x000\x02!\x01\x00', 0, 175, 159, 29, 92, 0, 0, -74],
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=169 command_id=0>,
2019-11-07 22:21:32 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x01\x00',
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b10a9000400003002210100',
2019-11-07 22:21:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3d000c0005000267d39fb6,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e3e000700aa00,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x173d000800010001,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x173d002b00240022020000010267d302040100fc0d001d0b10aa00040000300021000000af971f5c0000b5,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\xaa\x00\x04\x00\x000\x00!\x00\x00', 0, 175, 151, 31, 92, 0, 0, -75],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=170 command_id=0>,
2019-11-07 22:21:33 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x00!\x00\x00',
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b10aa000400003000210000',
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e3e000700aa00,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c3f000c0005000267d397b5,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x173e000800010001,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x173e0023001c002201fe64000267d30104010600050001ab40000000af971f5c0000b5,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x64fe>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 1, 260, 6, b'\x01\xab@\x00\x00', 0, 175, 151, 31, 92, 0, 0, -75],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=171 command_id=64>,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] ZCL request 0x0040: [0, 0],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:1:0x0006] No handler for cluster command 64,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'01ab400000',
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e3f000700aa00,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x173f000800010001,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x173f002b00240022020000010267d302040100fc0d001d0b10ac00040000300221020000af8f1d5c0000b4,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\xac\x00\x04\x00\x000\x02!\x02\x00', 0, 175, 143, 29, 92, 0, 0, -76],
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=172 command_id=0>,
2019-11-07 22:21:33 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x02\x00',
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b10ac000400003002210200',
2019-11-07 22:21:33 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c40000c0005000267d397b5,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c41000c0005000267d37fb2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c42000c0005000267d37fb2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e43000700aa00,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1740000800010001,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1740002a0023002202fdff000267d300000013000c003c67d379217306018817008000af7f215c0000b2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 0, 0, 19, b'<g\xd3y!s\x06\x01\x88\x17\x00\x80', 0, 175, 127, 33, 92, 0, 0, -78],
2019-11-07 22:21:37 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0xd367, 00:17:88:01:06:73:21:79,
2019-11-07 22:21:37 INFO (MainThread) [zigpy.application] Device 0xd367 (00:17:88:01:06:73:21:79) joined the network,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:17:88:01:06:73:21:79,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy.zdo] [0xd367:zdo] ZDO request 0x0013: [0xd367, 00:17:88:01:06:73:21:79, 128],
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'3c67d3792173060188170080',
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c41000c0005000267d37fb2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c42000c0005000267d37fb2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e43000700aa00,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1741000800010001,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1741002b00240022020000010267d302040100fc0d001d0b10b800040000300221020000af7f225c0000b2,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\xb8\x00\x04\x00\x000\x02!\x02\x00', 0, 175, 127, 34, 92, 0, 0, -78],
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=184 command_id=0>,
2019-11-07 22:21:37 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x02\x00',
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:21:37 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b10b8000400003002210200',
2019-11-07 22:21:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c42000c0005000267d377b1,
2019-11-07 22:21:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c43000c0005000267d37fb2,
2019-11-07 22:21:39 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c44000c0005000267d322af,
2019-11-07 22:21:40 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c45000c0005000267d322af,
2019-11-07 22:21:41 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 12 under 13 request id, data: b'0c3c00',
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 13, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x0c<\x00', 2, 0),
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x124200180011000d0001fcff000036000003000c3c000200,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12420009000200220d,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 13],
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e43000700aa00,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1743000800010001,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17430021001a002201fcff01020000000000360003000c3c0000aff1255c000013,
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x0c<\x00', 0, 175, 241, 37, 92, 0, 0, 19],
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:21:44 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'0c3c00',
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e44000700a600,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04440007000000,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04440012000b00220d01fcff000000000000,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 13: 00,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b450009000200213c,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b45000800010021,
2019-11-07 22:21:45 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c46000c0005000267d300af,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e47000700aa00,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1746000800010001,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1746002b00240022020000010267d302040100fc0d001d0b10be00040000300221030000af25255c0000b0,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, 34, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, 2, 260, 64512, b'\x1d\x0b\x10\xbe\x00\x04\x00\x000\x02!\x03\x00', 0, 175, 37, 37, 92, 0, 0, -80],
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=190 command_id=0>,
2019-11-07 22:21:55 WARNING (MainThread) [zigpy.zcl] Unknown cluster-specific command 0,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] ZCL request 0x0000: b'\x04\x00\x000\x02!\x03\x00',
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy.zcl] [0xd367:2:0xfc00] No handler for cluster command 0,
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd367>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b10be000400003002210300',
2019-11-07 22:21:55 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c47000c0005000267d325b0,
2019-11-07 22:22:03 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:22:25 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:22:29 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c48000c0005000267d322af,
2019-11-07 22:22:31 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c49000c0005000267d345af,
2019-11-07 22:22:35 INFO (MainThread) [homeassistant.components.zha.api] Permitting joins for 250s,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 14 under 15 request id, data: b'0efa00',
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 15, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x0e\xfa\x00', 2, 0),
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x124700180011000f0001fcff000036000003000efa000200,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12470009000200220f,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 15],
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e48000700aa00,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1748000800010001,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17480021001a002201fcff01020000000000360003000efa0000aff12b5c000013,
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x0e\xfa\x00', 0, 175, 241, 43, 92, 0, 0, 19],
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [250, <Bool.false: 0>],
2019-11-07 22:22:35 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'0efa00',
2019-11-07 22:22:36 DEBUG (SyncWorker_7) [asyncio] Using selector: EpollSelector,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e49000700a600,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04490007000000,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04490012000b00220f01fcff000000000000,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 15: 00,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xfa'),
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b4a000900020021fa,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b4a000800010021,
2019-11-07 22:22:36 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:22:58 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:23:20 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:23:42 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:24:04 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:24:26 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:24:48 DEBUG (SyncWorker_0) [asyncio] Using selector: EpollSelector,
2019-11-07 22:25:10 DEBUG (SyncWorker_9) [asyncio] Using selector: EpollSelector,
2019-11-07 22:25:32 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:25:54 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:26:16 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:26:38 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:27:00 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:27:22 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:27:44 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:28:05 DEBUG (SyncWorker_5) [asyncio] Using selector: EpollSelector,
2019-11-07 22:28:27 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:28:49 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 16 under 17 request id, data: b'103c00',
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (17, 17, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x10<\x00', 2, 0),
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x124b0018001100110001fcff00003600000300103c000200,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x124b00090002002211,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 34, 17],
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e4c000700aa00,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [170, 0],
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1),
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x174c000800010001,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x174c0021001a002201fcff0102000000000036000300103c0000aff1475c000013,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, 34, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x10<\x00', 0, 175, 241, 71, 92, 0, 0, 19],
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request 0x0036: [60, <Bool.false: 0>],
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'103c00',
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e4d000700a600,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [166, 0],
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,),
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x044d0007000000,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x044d0012000b00221101fcff000000000000,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 17: 00,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x11 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<'),
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b4e0009000200213c,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b4e000800010021,
2019-11-07 22:29:00 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS,
2019-11-07 22:29:11 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:29:33 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:29:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4f000c0005000267d322af,
2019-11-07 22:29:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c50000c0005000267d322af,
2019-11-07 22:29:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c51000c0005000267d322af,
2019-11-07 22:29:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c52000c0005000267d345af,
2019-11-07 22:29:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c53000c0005000267d34ab0,
2019-11-07 22:29:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c54000c0005000267d34ab0,
2019-11-07 22:29:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c55000c0005000267d34ab0,
2019-11-07 22:29:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c56000c0005000267d34ab0,
2019-11-07 22:29:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c57000c0005000267d322af,
2019-11-07 22:29:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c58000c0005000267d34ab0,
2019-11-07 22:29:46 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c59000c0005000267d34fb1,
2019-11-07 22:29:46 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c5a000c0005000267d37fb2,
2019-11-07 22:29:46 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c5b000c0005000267d377b1,
2019-11-07 22:29:55 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:30:16 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:30:38 DEBUG (SyncWorker_4) [asyncio] Using selector: EpollSelector,
2019-11-07 22:31:00 DEBUG (SyncWorker_5) [asyncio] Using selector: EpollSelector,
2019-11-07 22:31:22 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:31:43 DEBUG (SyncWorker_5) [asyncio] Using selector: EpollSelector,
2019-11-07 22:32:05 DEBUG (SyncWorker_3) [asyncio] Using selector: EpollSelector,
2019-11-07 22:32:27 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:32:49 DEBUG (SyncWorker_6) [asyncio] Using selector: EpollSelector,
2019-11-07 22:33:11 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
2019-11-07 22:33:33 DEBUG (SyncWorker_1) [asyncio] Using selector: EpollSelector,
2019-11-07 22:33:55 DEBUG (SyncWorker_2) [asyncio] Using selector: EpollSelector,
zigpy / zigpy-deconz

Philips HUE bulb not paired #83
