2018-02-14 12:28:48.741 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Command for channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_switch_onoff --> ON 2018-02-14 12:28:48.745 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0/0 -> 1150/3, cluster=0006, TID=99] 2018-02-14 12:28:48.748 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=153, commandId=1] 2018-02-14 12:28:48.749 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=1150/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, sequence=153, payload=01 99 01] 2018-02-14 12:28:48.751 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=153], messageTag=153, messageContents=01 99 01] 2018-02-14 12:28:48.752 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:28:48.753 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=153], messageTag=153, messageContents=01 99 01] 2018-02-14 12:28:48.756 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=99 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 99 99 03 01 99 01] 2018-02-14 12:28:48.794 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Polling... 2018-02-14 12:28:48.829 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102548F94: Command for channel zigbee:device:3e5f29cf:0017880102548f94:0017880102548F94_11_color_color --> ON 2018-02-14 12:28:48.833 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0/0 -> 27819/11, cluster=0008, TID=9A, level=254, transitionTime=10] 2018-02-14 12:28:48.835 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=154, commandId=4] 2018-02-14 12:28:48.836 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=8, addressMode=DEVICE, radius=31, sequence=154, payload=01 9A 04 FE 0A 00] 2018-02-14 12:28:48.837 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=154], messageTag=154, messageContents=01 9A 04 FE 0A 00] 2018-02-14 12:28:48.838 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:28:48.839 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:28:48.971 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=99 80 58 30] 2018-02-14 12:28:48.973 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:48.975 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:48.976 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:48.978 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=154], messageTag=154, messageContents=01 9A 04 FE 0A 00] 2018-02-14 12:28:48.981 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=9A 00 34 00 AB 6C 04 01 08 00 01 0B 40 11 00 00 9A 9A 06 01 9A 04 FE 0A 00] 2018-02-14 12:28:49.044 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880101210D6A: Command for channel zigbee:device:3e5f29cf:0017880101210d6a:0017880101210D6A_11_color_color --> ON 2018-02-14 12:28:49.047 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0/0 -> 33387/11, cluster=0008, TID=9B, level=254, transitionTime=10] 2018-02-14 12:28:49.049 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=155, commandId=4] 2018-02-14 12:28:49.051 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=8, addressMode=DEVICE, radius=31, sequence=155, payload=01 9B 04 FE 0A 00] 2018-02-14 12:28:49.053 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=155], messageTag=155, messageContents=01 9B 04 FE 0A 00] 2018-02-14 12:28:49.054 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:28:49.056 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:28:49.094 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=2] 2018-02-14 12:28:49.096 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=true, data=9A 00 34 00 AB 6C 04 01 08 00 01 0B 40 11 00 00 9A 9A 06 01 9A 04 FE 0A 00] 2018-02-14 12:28:49.101 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:28:49.311 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=3] 2018-02-14 12:28:49.316 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=155], messageTag=155, messageContents=01 9B 04 FE 0A 00] 2018-02-14 12:28:49.323 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=9B 00 34 00 6B 82 04 01 08 00 01 0B 40 11 00 00 9B 9B 06 01 9B 04 FE 0A 00] 2018-02-14 12:28:49.343 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=9A 80 58 30] 2018-02-14 12:28:49.346 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.348 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.349 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.350 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:28:49.352 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3] 2018-02-14 12:28:49.548 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=3] 2018-02-14 12:28:49.556 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=true, data=9B 00 34 00 6B 82 04 01 08 00 01 0B 40 11 00 00 9B 9B 06 01 9B 04 FE 0A 00] 2018-02-14 12:28:49.578 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:28:49.688 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4] 2018-02-14 12:28:49.690 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=9B 80 58 30] 2018-02-14 12:28:49.691 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.692 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.692 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:28:49.693 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4] 2018-02-14 12:28:53.351 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00178801021DC6C4: Polling... 2018-02-14 12:29:01.238 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880101210D6A: Polling... 2018-02-14 12:29:11.574 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102548F94: Polling... 2018-02-14 12:29:17.321 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Polling... 2018-02-14 12:29:18.485 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Command for channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_switch_onoff --> ON 2018-02-14 12:29:18.491 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0/0 -> 1150/3, cluster=0006, TID=9C] 2018-02-14 12:29:18.495 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=156, commandId=1] 2018-02-14 12:29:18.497 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=1150/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, sequence=156, payload=01 9C 01] 2018-02-14 12:29:18.499 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=156], messageTag=156, messageContents=01 9C 01] 2018-02-14 12:29:18.502 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:29:18.504 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=156], messageTag=156, messageContents=01 9C 01] 2018-02-14 12:29:18.507 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=9C 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 9C 9C 03 01 9C 01] 2018-02-14 12:29:18.561 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880101210D6A: Command for channel zigbee:device:3e5f29cf:0017880101210d6a:0017880101210D6A_11_color_color --> ON 2018-02-14 12:29:18.563 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0/0 -> 33387/11, cluster=0008, TID=9D, level=254, transitionTime=10] 2018-02-14 12:29:18.565 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=157, commandId=4] 2018-02-14 12:29:18.566 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=8, addressMode=DEVICE, radius=31, sequence=157, payload=01 9D 04 FE 0A 00] 2018-02-14 12:29:18.567 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=157], messageTag=157, messageContents=01 9D 04 FE 0A 00] 2018-02-14 12:29:18.568 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:29:18.569 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:29:18.691 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102548F94: Command for channel zigbee:device:3e5f29cf:0017880102548f94:0017880102548F94_11_color_color --> ON 2018-02-14 12:29:18.695 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0/0 -> 27819/11, cluster=0008, TID=9E, level=254, transitionTime=10] 2018-02-14 12:29:18.698 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=158, commandId=4] 2018-02-14 12:29:18.700 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=8, addressMode=DEVICE, radius=31, sequence=158, payload=01 9E 04 FE 0A 00] 2018-02-14 12:29:18.703 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=158], messageTag=158, messageContents=01 9E 04 FE 0A 00] 2018-02-14 12:29:18.704 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 2 2018-02-14 12:29:18.706 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:29:18.721 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=9C 80 58 30] 2018-02-14 12:29:18.724 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:18.725 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:18.726 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:18.728 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=157], messageTag=157, messageContents=01 9D 04 FE 0A 00] 2018-02-14 12:29:18.731 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=9D 00 34 00 6B 82 04 01 08 00 01 0B 40 11 00 00 9D 9D 06 01 9D 04 FE 0A 00] 2018-02-14 12:29:18.844 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=5] 2018-02-14 12:29:18.846 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=true, data=9D 00 34 00 6B 82 04 01 08 00 01 0B 40 11 00 00 9D 9D 06 01 9D 04 FE 0A 00] 2018-02-14 12:29:18.850 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:29:19.062 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=6] 2018-02-14 12:29:19.063 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=8, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=158], messageTag=158, messageContents=01 9E 04 FE 0A 00] 2018-02-14 12:29:19.065 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=9E 00 34 00 AB 6C 04 01 08 00 01 0B 40 11 00 00 9E 9E 06 01 9E 04 FE 0A 00] 2018-02-14 12:29:19.071 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=9D 80 58 30] 2018-02-14 12:29:19.072 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.073 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.073 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.074 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:29:19.075 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6] 2018-02-14 12:29:19.278 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=6] 2018-02-14 12:29:19.285 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=true, data=9E 00 34 00 AB 6C 04 01 08 00 01 0B 40 11 00 00 9E 9E 06 01 9E 04 FE 0A 00] 2018-02-14 12:29:19.305 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Sent queue larger than window [1 > 1]. 2018-02-14 12:29:19.517 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=7] 2018-02-14 12:29:19.522 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=9E 80 58 30] 2018-02-14 12:29:19.525 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.528 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.530 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:19.532 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7] 2018-02-14 12:29:22.392 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102F1AE8C: Polling... 2018-02-14 12:29:43.191 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 001788010201C153: Node service discovery running 2018-02-14 12:29:43.198 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 11705/0, cluster=0032, TID=9F, startIndex=0] 2018-02-14 12:29:43.202 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=11705/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, sequence=159, payload=00 00] 2018-02-14 12:29:43.205 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=11705, apsFrame=EmberApsFrame [profileId=0, clusterId=50, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=159], messageTag=159, messageContents=00 00] 2018-02-14 12:29:43.208 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1 2018-02-14 12:29:43.211 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=11705, apsFrame=EmberApsFrame [profileId=0, clusterId=50, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=159], messageTag=159, messageContents=00 00] 2018-02-14 12:29:43.217 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=9F 00 34 00 B9 2D 00 00 32 00 00 00 40 11 00 00 9F 9F 02 00 00] 2018-02-14 12:29:43.436 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=9F 80 58 30] 2018-02-14 12:29:43.437 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:43.438 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:43.439 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspInvalidCommandResponse [reason=EZSP_ERROR_VERSION_NOT_SET] 2018-02-14 12:29:43.439 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0] 2018-02-14 12:29:53.228 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 001788010201C153: ManagementRoutingRequest returned CommandResult [TIMEOUT] 2018-02-14 12:29:53.230 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 001788010201C153: Node service discovery request ROUTES failed. Retry 334, wait 519777ms before retry. 2018-02-14 12:29:53.800 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00178801021DC6C4: Polling... 2018-02-14 12:29:54.202 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Polling... 2018-02-14 12:30:03.327 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880101210D6A: Polling... 2018-02-14 12:30:14.359 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102548F94: Polling...