openhab> log:display
00:19:02.313 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=20 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 B0 C8 00]
00:19:02.314 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.314 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.315 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:02.316 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=176, lastHopRssi=-56, relayList=]
00:19:02.339 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=20 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 B0 C8 00]
00:19:02.341 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.341 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.342 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:02.342 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=176, lastHopRssi=-56, relayList=]
00:19:02.343 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=20 90 01 45 00 00 04 01 06 00 01 01 40 01 00 00 3C B0 C8 49 C3 FF FF 05 08 DE 0B 01 00 02]
00:19:02.344 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.345 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.346 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:02.346 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=3C], lastHopLqi=176, lastHopRssi=-56, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 DE 0B 01 00]
00:19:02.348 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=3C, rssi=-56, lqi=B0, payload=08 DE 0B 01 00]
00:19:02.349 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.351 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.352 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=222, commandId=11]
00:19:02.353 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/1 -> 0000/1, cluster=0006, TID=DE, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.355 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/1 -> 0000/1, cluster=0006, TID=DE, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.356 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=105, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/1, cluster=0006, TID=DE]]
00:19:02.356 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch received SUCCESS in response to ON
00:19:02.357 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:02.358 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:02.359 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
00:19:02.360 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=20 90 01 3F 00 00 49 C3 04 01 06 00 01 01 40 11 00 00 11 DE 00 00]
00:19:02.360 [DEBUG] [.transaction.ZigBeeTransactionManager] - C349/3: Sending ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=98, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=DF]]
00:19:02.361 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.361 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
00:19:02.362 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:02.362 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=DF]
00:19:02.363 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=223, commandId=1]
00:19:02.363 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.365 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=C349/3, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=47, rssi=--, lqi=--, payload=01 DF 01]
00:19:02.365 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=11], messageTag=DE, status=EMBER_SUCCESS, messageContents=]
00:19:02.366 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=DE state=RX_ACK
00:19:02.367 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=DE, state=RX_ACK, outstanding=1
00:19:02.368 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=47], messageTag=DF, messageContents=01 DF 01]
00:19:02.368 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:02.369 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=21 00 01 34 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 47 DF 03 01 DF 01]
00:19:02.380 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=20 90 01 45 00 00 04 01 06 00 01 01 40 01 00 00 3D B4 C9 49 C3 FF FF 07 18 4B 0A 00 00 10 01 02]
00:19:02.381 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.381 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.382 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:02.382 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=3D], lastHopLqi=180, lastHopRssi=-55, sender=C349, bindingIndex=255, addressIndex=255, messageContents=18 4B 0A 00 00 10 01]
00:19:02.383 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=3D, rssi=-55, lqi=B4, payload=18 4B 0A 00 00 10 01]
00:19:02.384 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.386 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.386 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=75, commandId=10]
00:19:02.388 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: C349/1 -> 0000/1, cluster=0006, TID=4B, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.389 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: C349/1 -> 0000/1, cluster=0006, TID=4B, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.391 [DEBUG] [.converter.ZigBeeConverterSwitchOnoff] - 804B50FFFEE11DD7: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Sun Nov 21 00:19:02 CET 2021, implemented=true]
00:19:02.392 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch updated to ON
00:19:02.393 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Updating ZigBee channel state zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch to ON
00:19:02.394 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.395 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_1_Switch' changed from OFF to ON
00:19:02.395 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.396 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7 in 1830 seconds
00:19:02.400 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=21 80 01 34 00 00 12]
00:19:02.401 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=4, reTx=false, data=21 00 01 34 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 47 DF 03 01 DF 01]
00:19:02.402 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.402 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.403 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=12]
00:19:02.403 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:02.410 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
00:19:02.420 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=21 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 B0 C8 00]
00:19:02.421 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.421 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.423 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=176, lastHopRssi=-56, relayList=]
00:19:02.423 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:02.424 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=21 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 A8 C6 00]
00:19:02.426 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.426 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.427 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:02.427 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=168, lastHopRssi=-58, relayList=]
00:19:02.441 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=21 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 A8 C6 00]
00:19:02.442 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.442 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.443 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:02.443 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=168, lastHopRssi=-58, relayList=]
00:19:02.461 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=false, data=21 90 01 45 00 00 04 01 06 00 03 01 40 01 00 00 3E A8 C6 49 C3 FF FF 05 08 DF 0B 01 00 02]
00:19:02.462 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.462 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.463 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=3E], lastHopLqi=168, lastHopRssi=-58, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 DF 0B 01 00]
00:19:02.463 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:02.465 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/3, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=3E, rssi=-58, lqi=A8, payload=08 DF 0B 01 00]
00:19:02.466 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.468 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.469 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=223, commandId=11]
00:19:02.470 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/3 -> 0000/1, cluster=0006, TID=DF, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.471 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/3 -> 0000/1, cluster=0006, TID=DF, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.472 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch received SUCCESS in response to ON
00:19:02.472 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=210, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=DF]]
00:19:02.474 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:02.475 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:02.476 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
00:19:02.477 [DEBUG] [.transaction.ZigBeeTransactionManager] - C349/2: Sending ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=206, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E0]]
00:19:02.478 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
00:19:02.479 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E0]
00:19:02.480 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=224, commandId=1]
00:19:02.481 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=21 90 01 3F 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 12 DF 00 00]
00:19:02.482 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=C349/2, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=48, rssi=--, lqi=--, payload=01 E0 01]
00:19:02.483 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.483 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.483 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=48], messageTag=E0, messageContents=01 E0 01]
00:19:02.483 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:02.484 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:02.484 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=12], messageTag=DF, status=EMBER_SUCCESS, messageContents=]
00:19:02.484 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=22 00 01 34 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 48 E0 03 01 E0 01]
00:19:02.486 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=21 90 01 45 00 00 04 01 06 00 03 01 40 01 00 00 3F A8 C6 49 C3 FF FF 07 18 4C 0A 00 00 10 01 02]
00:19:02.487 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.487 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.487 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=3F], lastHopLqi=168, lastHopRssi=-58, sender=C349, bindingIndex=255, addressIndex=255, messageContents=18 4C 0A 00 00 10 01]
00:19:02.487 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:02.488 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/3, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=3F, rssi=-58, lqi=A8, payload=18 4C 0A 00 00 10 01]
00:19:02.489 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.490 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.491 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=76, commandId=10]
00:19:02.492 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: C349/3 -> 0000/1, cluster=0006, TID=4C, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.492 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: C349/3 -> 0000/1, cluster=0006, TID=4C, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.493 [DEBUG] [.converter.ZigBeeConverterSwitchOnoff] - 804B50FFFEE11DD7: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Sun Nov 21 00:19:02 CET 2021, implemented=true]
00:19:02.494 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch updated to ON
00:19:02.495 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Updating ZigBee channel state zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch to ON
00:19:02.496 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.496 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.497 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7 in 1830 seconds
00:19:02.499 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_3_Switch' changed from OFF to ON
00:19:02.576 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=22 80 01 34 00 00 13]
00:19:02.577 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=22 00 01 34 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 48 E0 03 01 E0 01]
00:19:02.579 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.579 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:02.580 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=DF state=RX_ACK
00:19:02.580 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=13]
00:19:02.581 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=DF, state=RX_ACK, outstanding=1
00:19:02.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=22 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 A8 C6 00]
00:19:02.585 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.585 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.586 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:02.586 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=168, lastHopRssi=-58, relayList=]
00:19:02.589 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=22 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 A4 C5 00]
00:19:02.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:02.591 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=164, lastHopRssi=-59, relayList=]
00:19:02.593 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=22 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 A4 C5 00]
00:19:02.594 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.594 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.595 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=164, lastHopRssi=-59, relayList=]
00:19:02.595 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:02.597 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=22 90 01 45 00 00 04 01 06 00 02 01 40 01 00 00 40 A4 C5 49 C3 FF FF 05 08 E0 0B 01 00 02]
00:19:02.598 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.598 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.599 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=40], lastHopLqi=164, lastHopRssi=-59, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 E0 0B 01 00]
00:19:02.599 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:02.600 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/2, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=40, rssi=-59, lqi=A4, payload=08 E0 0B 01 00]
00:19:02.601 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.601 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=22 90 01 3F 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 13 E0 00 00]
00:19:02.602 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.603 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.603 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:02.603 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=224, commandId=11]
00:19:02.604 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/2 -> 0000/1, cluster=0006, TID=E0, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.605 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/2 -> 0000/1, cluster=0006, TID=E0, commandIdentifier=1, statusCode=SUCCESS]
00:19:02.606 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.606 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=335, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E0]]
00:19:02.607 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=13], messageTag=E0, status=EMBER_SUCCESS, messageContents=]
00:19:02.607 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch received SUCCESS in response to ON
00:19:02.607 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:02.608 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=E0 state=RX_ACK
00:19:02.609 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:02.609 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=E0, state=RX_ACK, outstanding=0
00:19:02.609 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
00:19:02.617 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=22 90 01 45 00 00 04 01 06 00 02 01 40 01 00 00 41 A4 C5 49 C3 FF FF 07 18 4D 0A 00 00 10 01 02]
00:19:02.618 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:02.619 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:02.619 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:02.620 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=41], lastHopLqi=164, lastHopRssi=-59, sender=C349, bindingIndex=255, addressIndex=255, messageContents=18 4D 0A 00 00 10 01]
00:19:02.622 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/2, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=41, rssi=-59, lqi=A4, payload=18 4D 0A 00 00 10 01]
00:19:02.625 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:02.626 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:02.627 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=77, commandId=10]
00:19:02.628 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: C349/2 -> 0000/1, cluster=0006, TID=4D, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.629 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: C349/2 -> 0000/1, cluster=0006, TID=4D, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
00:19:02.631 [DEBUG] [.converter.ZigBeeConverterSwitchOnoff] - 804B50FFFEE11DD7: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Sun Nov 21 00:19:02 CET 2021, implemented=true]
00:19:02.632 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch updated to ON
00:19:02.633 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Updating ZigBee channel state zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch to ON
00:19:02.634 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.636 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7
00:19:02.637 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_2_Switch' changed from OFF to ON
00:19:02.639 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:97efda9994:804b50fffee11dd7 in 1830 seconds
00:19:03.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:03.587 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=23 00 01 18 00]
00:19:03.636 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=false, data=23 80 01 18 00 02]
00:19:03.637 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=3, reTx=false, data=23 00 01 18 00]
00:19:03.638 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:03.638 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:03.640 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:04.589 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:04.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=24 00 01 18 00]
00:19:04.618 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=24 80 01 18 00 02]
00:19:04.620 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=4, reTx=false, data=24 00 01 18 00]
00:19:04.621 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:04.621 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:04.622 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:04.876 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Used' changed from 600 to 599
00:19:05.593 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:05.595 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=25 00 01 18 00]
00:19:05.622 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=25 80 01 18 00 02]
00:19:05.623 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=5, reTx=false, data=25 00 01 18 00]
00:19:05.624 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:05.624 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:05.625 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:06.597 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:06.599 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=26 00 01 18 00]
00:19:06.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=26 80 01 18 00 02]
00:19:06.627 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=6, reTx=false, data=26 00 01 18 00]
00:19:06.628 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:06.628 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:06.630 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:07.307 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'TZ30001obwwnmqTS011F' received command ON
00:19:07.311 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_1_Switch' received command ON
00:19:07.314 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_3_Switch' received command ON
00:19:07.317 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'TZ30001obwwnmqTS011F_804B50FFFEE11DD7_2_Switch' received command ON
00:19:07.322 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Command for channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch --> ON [OnOffType]
00:19:07.323 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=0, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/1, cluster=0006, TID=--]]
00:19:07.324 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
00:19:07.325 [DEBUG] [.transaction.ZigBeeTransactionManager] - C349/1: Sending ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=2, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/1, cluster=0006, TID=E1]]
00:19:07.326 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
00:19:07.327 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> C349/1, cluster=0006, TID=E1]
00:19:07.328 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=225, commandId=1]
00:19:07.329 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=C349/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=49, rssi=--, lqi=--, payload=01 E1 01]
00:19:07.330 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Command for channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch --> ON [OnOffType]
00:19:07.330 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=49], messageTag=E1, messageContents=01 E1 01]
00:19:07.330 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch waiting for response to ON
00:19:07.331 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:07.331 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=0, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=--]]
00:19:07.332 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
00:19:07.332 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=27 00 01 34 00 00 49 C3 04 01 06 00 01 01 40 11 00 00 49 E1 03 01 E1 01]
00:19:07.333 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch waiting for response to ON
00:19:07.340 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 804B50FFFEE11DD7: Command for channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch --> ON [OnOffType]
00:19:07.341 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=0, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=--]]
00:19:07.342 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
00:19:07.343 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch waiting for response to ON
00:19:07.375 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
00:19:07.386 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=1, reTx=false, data=27 80 01 34 00 00 14]
00:19:07.387 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=7, reTx=false, data=27 00 01 34 00 00 49 C3 04 01 06 00 01 01 40 11 00 00 49 E1 03 01 E1 01]
00:19:07.388 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.389 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.390 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=14]
00:19:07.390 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:07.392 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=27 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 9C C3 00]
00:19:07.394 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.394 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.395 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:07.395 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=156, lastHopRssi=-61, relayList=]
00:19:07.397 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=27 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 98 C2 00]
00:19:07.398 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.398 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.400 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:07.400 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=152, lastHopRssi=-62, relayList=]
00:19:07.408 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=27 90 01 45 00 00 04 01 06 00 01 01 40 01 00 00 42 9C C3 49 C3 FF FF 05 08 E1 0B 01 00 02]
00:19:07.409 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.409 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.410 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:07.410 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=42], lastHopLqi=156, lastHopRssi=-61, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 E1 0B 01 00]
00:19:07.411 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=42, rssi=-61, lqi=9C, payload=08 E1 0B 01 00]
00:19:07.412 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:07.413 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:07.414 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=225, commandId=11]
00:19:07.415 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/1 -> 0000/1, cluster=0006, TID=E1, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.416 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/1 -> 0000/1, cluster=0006, TID=E1, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.416 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=93, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/1, cluster=0006, TID=E1]]
00:19:07.416 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_1_switch received SUCCESS in response to ON
00:19:07.417 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:07.418 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:07.419 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
00:19:07.419 [DEBUG] [.transaction.ZigBeeTransactionManager] - C349/3: Sending ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=88, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=E2]]
00:19:07.420 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
00:19:07.421 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=E2]
00:19:07.421 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=226, commandId=1]
00:19:07.422 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=C349/3, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=4A, rssi=--, lqi=--, payload=01 E2 01]
00:19:07.423 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4A], messageTag=E2, messageContents=01 E2 01]
00:19:07.424 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:07.425 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=false, data=28 00 01 34 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 4A E2 03 01 E2 01]
00:19:07.427 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=27 90 01 3F 00 00 49 C3 04 01 06 00 01 01 40 11 00 00 14 E1 00 00]
00:19:07.428 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.428 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.428 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:07.428 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=14], messageTag=E1, status=EMBER_SUCCESS, messageContents=]
00:19:07.469 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
00:19:07.506 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=28 80 01 34 00 00 15]
00:19:07.508 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=3, reTx=false, data=28 00 01 34 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 4A E2 03 01 E2 01]
00:19:07.509 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.509 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.510 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=15]
00:19:07.511 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=E1 state=RX_ACK
00:19:07.511 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:07.512 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=E1, state=RX_ACK, outstanding=1
00:19:07.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=28 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 9C C3 00]
00:19:07.516 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.516 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.517 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:07.517 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=156, lastHopRssi=-61, relayList=]
00:19:07.519 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=28 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 9C C3 00]
00:19:07.521 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.521 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.522 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:07.522 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=156, lastHopRssi=-61, relayList=]
00:19:07.527 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=28 90 01 45 00 00 04 01 06 00 03 01 40 01 00 00 43 98 C2 49 C3 FF FF 05 08 E2 0B 01 00 02]
00:19:07.528 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.528 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.529 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:07.529 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=43], lastHopLqi=152, lastHopRssi=-62, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 E2 0B 01 00]
00:19:07.530 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/3, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=43, rssi=-62, lqi=98, payload=08 E2 0B 01 00]
00:19:07.530 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=28 90 01 3F 00 00 49 C3 04 01 06 00 01 03 40 11 00 00 15 E2 00 00]
00:19:07.531 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.531 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:07.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:07.532 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:07.533 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=226, commandId=11]
00:19:07.534 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/3 -> 0000/1, cluster=0006, TID=E2, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.535 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/3 -> 0000/1, cluster=0006, TID=E2, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.536 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_3_switch received SUCCESS in response to ON
00:19:07.536 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=205, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/3, cluster=0006, TID=E2]]
00:19:07.537 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.537 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:07.538 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:07.538 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=15], messageTag=E2, status=EMBER_SUCCESS, messageContents=]
00:19:07.539 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
00:19:07.539 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=E2 state=RX_ACK
00:19:07.540 [DEBUG] [.transaction.ZigBeeTransactionManager] - C349/2: Sending ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=199, state=WAITING, sendCnt=0, command=OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E3]]
00:19:07.540 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=E2, state=RX_ACK, outstanding=0
00:19:07.541 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
00:19:07.542 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E3]
00:19:07.543 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=227, commandId=1]
00:19:07.544 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=C349/2, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=4B, rssi=--, lqi=--, payload=01 E3 01]
00:19:07.545 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4B], messageTag=E3, messageContents=01 E3 01]
00:19:07.547 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:07.548 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=29 00 01 34 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 4B E3 03 01 E3 01]
00:19:07.578 [DEBUG] [ing.exec.internal.handler.ExecHandler] - Passing to shell for parsing command.
00:19:07.579 [DEBUG] [ing.exec.internal.handler.ExecHandler] - OS: *NIX (Linux)
00:19:07.580 [TRACE] [ing.exec.internal.handler.ExecHandler] - The command to be executed will be '[sh, -c, /etc/openhab/airsensor/airsensor -v -o]'
00:19:07.581 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_Ausfuhrung' changed from OFF to ON
00:19:07.588 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=false, data=29 80 01 34 00 00 16]
00:19:07.589 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=1, reTx=false, data=29 00 01 34 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 4B E3 03 01 E3 01]
00:19:07.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.592 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.593 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:07.594 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=16]
00:19:07.596 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=29 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 9C C3 00]
00:19:07.597 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.597 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.598 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:07.598 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=156, lastHopRssi=-61, relayList=]
00:19:07.608 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=29 90 01 59 00 49 C3 D7 1D E1 FE FF 50 4B 80 9C C3 00]
00:19:07.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:07.610 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=C349, sourceEui=804B50FFFEE11DD7, lastHopLqi=156, lastHopRssi=-61, relayList=]
00:19:07.677 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=29 90 01 45 00 00 04 01 06 00 02 01 40 01 00 00 44 A0 C4 49 C3 FF FF 05 08 E3 0B 01 00 02]
00:19:07.678 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.678 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.679 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:07.679 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=44], lastHopLqi=160, lastHopRssi=-60, sender=C349, bindingIndex=255, addressIndex=255, messageContents=08 E3 0B 01 00]
00:19:07.681 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=C349/2, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=44, rssi=-60, lqi=A0, payload=08 E3 0B 01 00]
00:19:07.682 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=29 90 01 3F 00 00 49 C3 04 01 06 00 01 02 40 11 00 00 16 E3 00 00]
00:19:07.683 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:07.683 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node update. NWK Address=C349
00:19:07.684 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:07.685 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 804B50FFFEE11DD7: Node C349 is not updated
00:19:07.686 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=227, commandId=11]
00:19:07.688 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [On/Off: C349/2 -> 0000/1, cluster=0006, TID=E3, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.689 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [On/Off: C349/2 -> 0000/1, cluster=0006, TID=E3, commandIdentifier=1, statusCode=SUCCESS]
00:19:07.697 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=804B50FFFEE11DD7 queueTime=355, state=COMPLETE, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> C349/2, cluster=0006, TID=E3]]
00:19:07.698 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 804B50FFFEE11DD7: Channel zigbee:device:97efda9994:804b50fffee11dd7:804B50FFFEE11DD7_2_switch received SUCCESS in response to ON
00:19:07.699 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
00:19:07.699 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:07.700 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 804B50FFFEE11DD7: transactionComplete, state=COMPLETE, outstanding=0
00:19:07.700 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=C349, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=16], messageTag=E3, status=EMBER_SUCCESS, messageContents=]
00:19:07.701 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
00:19:07.701 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=E3 state=RX_ACK
00:19:07.701 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=E3, state=RX_ACK, outstanding=0
00:19:08.016 [WARN ] [fo.internal.handler.SysteminfoHandler] - No information for channel battery#remainingCapacity with device index 0 :
00:19:08.020 [WARN ] [fo.internal.handler.SysteminfoHandler] - No information for channel battery#remainingTime with device index 0 :
00:19:08.028 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_UsedHeapPercent' changed from 39 % to 47 %
00:19:08.601 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:08.603 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=2A 00 01 18 00]
00:19:08.630 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=2A 80 01 18 00 02]
00:19:08.631 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=6, reTx=false, data=2A 00 01 18 00]
00:19:08.632 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:08.633 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:08.634 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:09.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:09.606 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=2B 00 01 18 00]
00:19:09.633 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=false, data=2B 80 01 18 00 02]
00:19:09.634 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=7, reTx=false, data=2B 00 01 18 00]
00:19:09.635 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:09.636 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:09.637 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:10.608 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:10.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=0, reTx=false, data=2C 00 01 18 00]
00:19:10.636 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=2C 80 01 18 00 02]
00:19:10.638 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=0, reTx=false, data=2C 00 01 18 00]
00:19:10.639 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:10.640 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
00:19:10.641 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:11.611 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:11.612 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=false, data=2D 00 01 18 00]
00:19:11.666 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=false, data=2D 80 01 18 00 02]
00:19:11.667 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=1, reTx=false, data=2D 00 01 18 00]
00:19:11.669 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:11.670 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
00:19:11.671 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:12.614 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:12.615 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=2E 00 01 18 00]
00:19:12.625 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Used' changed from 599 to 600
00:19:12.641 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=2E 80 01 18 00 02]
00:19:12.642 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=2, reTx=false, data=2E 00 01 18 00]
00:19:12.643 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:12.643 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:12.644 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:13.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:13.618 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=2F 00 01 18 00]
00:19:13.645 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=2F 80 01 18 00 02]
00:19:13.647 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=3, reTx=false, data=2F 00 01 18 00]
00:19:13.649 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:13.649 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:13.651 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:14.619 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:14.621 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=30 00 01 18 00]
00:19:14.648 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=30 80 01 18 00 02]
00:19:14.649 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=4, reTx=false, data=30 00 01 18 00]
00:19:14.650 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:14.650 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:14.650 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
00:19:15.623 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:15.624 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=31 00 01 18 00]
00:19:15.651 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=31 80 01 18 00 02]
00:19:15.653 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=5, reTx=false, data=31 00 01 18 00]
00:19:15.654 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:15.654 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:15.655 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
00:19:16.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:16.628 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=32 00 01 18 00]
00:19:16.655 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=32 80 01 18 00 02]
00:19:16.656 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=6, reTx=false, data=32 00 01 18 00]
00:19:16.657 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
00:19:16.657 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:16.658 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
00:19:17.052 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Used' changed from 600 to 599
00:19:17.629 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:17.631 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=33 00 01 18 00]
00:19:17.657 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=false, data=33 80 01 18 00 02]
00:19:17.659 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=7, reTx=false, data=33 00 01 18 00]
00:19:17.660 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:17.660 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:17.661 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
00:19:18.186 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Used' changed from 599 to 600
00:19:18.633 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:18.635 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=0, reTx=false, data=34 00 01 18 00]
00:19:18.686 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=34 80 01 18 00 02]
00:19:18.688 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=0, reTx=false, data=34 00 01 18 00]
00:19:18.689 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:18.689 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:18.690 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
00:19:19.637 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:19.638 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=false, data=35 00 01 18 00]
00:19:19.665 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=false, data=35 80 01 18 00 02]
00:19:19.666 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=1, reTx=false, data=35 00 01 18 00]
00:19:19.667 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:19.667 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:19.668 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
00:19:20.640 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:20.641 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=36 00 01 18 00]
00:19:20.648 [DEBUG] [ing.exec.internal.handler.ExecHandler] - Exec [OUTPUT]: '2696'
00:19:20.653 [DEBUG] [ing.exec.internal.handler.ExecHandler] - Transformed response is '2696'
00:19:20.657 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_Ausfuhrung' changed from ON to OFF
00:19:20.662 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_Ruckgabewert' changed from 2668 to 2696
00:19:20.664 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_ZeitpunktderletztenAusfuhrung' changed from 2021-11-21T00:18:07.577399+0100 to 2021-11-21T00:19:20.655505+0100
00:19:20.668 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=36 80 01 18 00 02]
00:19:20.670 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=2, reTx=false, data=36 00 01 18 00]
00:19:20.671 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:20.671 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:20.671 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
00:19:21.643 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:21.645 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=37 00 01 18 00]
00:19:21.696 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=37 80 01 18 00 02]
00:19:21.697 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=3, reTx=false, data=37 00 01 18 00]
00:19:21.699 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:21.699 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:21.700 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
00:19:22.647 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
00:19:22.648 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=38 00 01 18 00]
00:19:22.674 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=38 80 01 18 00 02]
00:19:22.676 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=4, reTx=false, data=38 00 01 18 00]
00:19:22.677 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
00:19:22.677 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
00:19:22.678 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]

openhab>