17:17:03.017 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=A6 00 01 18 00]
17:17:03.044 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=1, reTx=false, data=A6 80 01 18 00 02]
17:17:03.044 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=7, reTx=false, data=A6 00 01 18 00]
17:17:03.045 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:03.046 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:17:03.046 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:03.141 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=A6 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 D1 00 00 00]
17:17:03.142 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:03.143 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
17:17:03.143 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=D1], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:17:03.144 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:17:03.144 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:17:03.145 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:17:04.019 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:04.020 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=A7 00 01 18 00]
17:17:04.047 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=A7 80 01 18 00 02]
17:17:04.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=A7 00 01 18 00]
17:17:04.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:04.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:04.049 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:17:05.021 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:05.021 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=A8 00 01 18 00]
17:17:05.047 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=A8 80 01 18 00 02]
17:17:05.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=A8 00 01 18 00]
17:17:05.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:05.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:05.049 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:17:05.505 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=A8 90 01 59 00 0D 7D 06 93 13 FE FF 23 A4 60 DC D3 00]
17:17:05.507 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:05.507 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:05.508 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:17:05.508 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=7D0D, sourceEui=60A423FFFE139306, lastHopLqi=220, lastHopRssi=-45, relayList=]
17:17:05.550 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=A8 90 01 45 00 00 04 01 06 00 0B 01 40 01 00 00 2E DC D3 0D 7D FF FF 07 18 6D 0A 00 00 10 01 02]
17:17:05.551 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:05.551 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:05.551 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:17:05.552 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2E], lastHopLqi=220, lastHopRssi=-45, sender=7D0D, bindingIndex=255, addressIndex=255, messageContents=18 6D 0A 00 00 10 01]
17:17:05.553 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=7D0D/11, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=2E, rssi=-45, lqi=DC, payload=18 6D 0A 00 00 10 01]
17:17:05.554 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 60A423FFFE139306: Node update. NWK Address=7D0D
17:17:05.555 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 60A423FFFE139306: Node 7D0D is not updated
17:17:05.556 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=109, commandId=10]
17:17:05.557 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 7D0D/11 -> 0000/1, cluster=0006, TID=6D, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
17:17:05.557 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: 7D0D/11 -> 0000/1, cluster=0006, TID=6D, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
17:17:05.559 [DEBUG] [.converter.ZigBeeConverterSwitchOnoff] - 60A423FFFE139306: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Dec 10 17:17:05 CET 2021, implemented=true]
17:17:05.560 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 60A423FFFE139306: Channel zigbee:device:dbb76ab009:60a423fffe139306:60A423FFFE139306_11_switch updated to ON
17:17:05.561 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 60A423FFFE139306: Updating ZigBee channel state zigbee:device:dbb76ab009:60a423fffe139306:60A423FFFE139306_11_switch to ON
17:17:05.562 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:dbb76ab009:60a423fffe139306
17:17:05.563 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:dbb76ab009:60a423fffe139306
17:17:05.564 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:dbb76ab009:60a423fffe139306 in 1830 seconds
17:17:06.022 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:06.023 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=false, data=A9 00 01 18 00]
17:17:06.070 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=A9 80 01 18 00 02]
17:17:06.071 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=5, reTx=false, data=A9 00 01 18 00]
17:17:06.072 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:06.072 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:06.072 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:17:06.304 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'BMP085_temperature' received command 20.56
17:17:06.308 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'BMP085_temperature' changed from 20.64 to 20.56
17:17:06.338 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'BMP085_local_air_pressure' received command 989.63
17:17:06.340 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'BMP085_local_air_pressure' changed from 989.7 to 989.63
17:17:06.370 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'BMP085_absolute_altitude' received command 198.5
17:17:06.372 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'BMP085_absolute_altitude' changed from 197.9 to 198.5
17:17:06.404 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'BMP085_air_pressure_at_sea_level' received command 996.5
17:17:07.024 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:07.025 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=AA 00 01 18 00]
17:17:07.052 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=AA 80 01 18 00 02]
17:17:07.053 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=6, reTx=false, data=AA 00 01 18 00]
17:17:07.054 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:07.054 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:07.054 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:17:08.027 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:08.028 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=AB 00 01 18 00]
17:17:08.054 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=AB 80 01 18 00 02]
17:17:08.055 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=7, reTx=false, data=AB 00 01 18 00]
17:17:08.056 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:08.056 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:17:08.056 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:09.029 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:09.030 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=AC 00 01 18 00]
17:17:09.081 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=AC 80 01 18 00 02]
17:17:09.082 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=0, reTx=false, data=AC 00 01 18 00]
17:17:09.083 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:09.083 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:09.084 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:17:09.362 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=false, data=AC 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 D2 00 00 00]
17:17:09.363 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:09.363 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:09.364 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:17:09.364 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=D2], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:17:09.365 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:17:09.365 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:17:10.031 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:10.032 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=AD 00 01 18 00]
17:17:10.059 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=AD 80 01 18 00 02]
17:17:10.060 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=2, reTx=false, data=AD 00 01 18 00]
17:17:10.060 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:10.061 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:10.061 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:17:11.033 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:11.034 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=AE 00 01 18 00]
17:17:11.061 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=AE 80 01 18 00 02]
17:17:11.061 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=3, reTx=false, data=AE 00 01 18 00]
17:17:11.062 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:11.062 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:11.063 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:17:12.035 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:12.036 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=AF 00 01 18 00]
17:17:12.091 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=AF 80 01 18 00 02]
17:17:12.092 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=4, reTx=false, data=AF 00 01 18 00]
17:17:12.092 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:12.092 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:12.093 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:17:13.038 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:13.039 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=B0 00 01 18 00]
17:17:13.065 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=B0 80 01 18 00 02]
17:17:13.066 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=5, reTx=false, data=B0 00 01 18 00]
17:17:13.067 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:13.067 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:13.068 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:17:14.040 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:14.041 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=B1 00 01 18 00]
17:17:14.067 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=B1 80 01 18 00 02]
17:17:14.068 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=6, reTx=false, data=B1 00 01 18 00]
17:17:14.069 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:14.069 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:14.070 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:17:14.432 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=B1 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 D3 00 00 00]
17:17:14.433 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:14.433 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:14.434 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:17:14.434 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=D3], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:17:14.435 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:17:14.436 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:17:15.042 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:15.043 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=0, reTx=false, data=B2 00 01 18 00]
17:17:15.069 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=false, data=B2 80 01 18 00 02]
17:17:15.070 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=0, reTx=false, data=B2 00 01 18 00]
17:17:15.071 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:15.071 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:15.072 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:17:16.044 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:16.044 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=false, data=B3 00 01 18 00]
17:17:16.101 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=B3 80 01 18 00 02]
17:17:16.101 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=1, reTx=false, data=B3 00 01 18 00]
17:17:16.102 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:16.102 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:16.102 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:17:17.045 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:17.046 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=B4 00 01 18 00]
17:17:17.073 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=B4 80 01 18 00 02]
17:17:17.074 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=2, reTx=false, data=B4 00 01 18 00]
17:17:17.075 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:17.075 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:17.075 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:17:18.048 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:18.057 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=B5 00 01 18 00]
17:17:18.083 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=false, data=B5 80 01 18 00 02]
17:17:18.084 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=3, reTx=false, data=B5 00 01 18 00]
17:17:18.085 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:18.085 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:18.085 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:17:18.711 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=0, reTx=false, data=B5 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 D4 00 00 00]
17:17:18.712 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:18.712 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:18.713 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:17:18.713 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=D4], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:17:18.714 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:17:18.715 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:17:19.058 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:19.059 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=false, data=B6 00 01 18 00]
17:17:19.085 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=false, data=B6 80 01 18 00 02]
17:17:19.087 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=5, reTx=false, data=B6 00 01 18 00]
17:17:19.088 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:19.088 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:19.088 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:17:20.060 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:20.061 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=B7 00 01 18 00]
17:17:20.088 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=B7 80 01 18 00 02]
17:17:20.088 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=6, reTx=false, data=B7 00 01 18 00]
17:17:20.089 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:20.089 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:20.090 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:17:21.029 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Airportairpressure_AirportAirPressureSeaLevel' changed from 996.0 to 996.1
17:17:21.062 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:21.063 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=B8 00 01 18 00]
17:17:21.089 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=B8 80 01 18 00 02]
17:17:21.090 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=7, reTx=false, data=B8 00 01 18 00]
17:17:21.090 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:21.090 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:21.091 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:17:22.064 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:17:22.065 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=false, data=B9 00 01 18 00]
17:17:22.091 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=B9 80 01 18 00 02]
17:17:22.092 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=false, data=B9 00 01 18 00]
17:17:22.093 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:17:22.093 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:17:22.094 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]