search

zsmartsystems / com.zsmartsystems.zigbee

ZigBee Cluster Library Java framework supporting multiple dongles
Eclipse Public License 1.0
142 stars 88 forks source link

IKEA Tradfri remote command error #1252

Closed dajudge closed 3 years ago

dajudge commented 3 years ago

I joined a IKEA TRADFRI remote (https://www.ikea.com/us/en/p/tradfri-remote-control-00443130/) via a ConBee USB Dongle. Commands from pressing buttons don't seem to be processed properly, though.

I also tried it w/ a CC2531 to basically the same results.

Now I'm well aware that the TRADFRI remote isn't the pinnacle of ZigBee compliance, but I didn't have any problems at least w/ the standard-compliant buttons on the remote w/ ZigPy.

Steps to reproduce the behavior:

Expected behavior

Additional context Here's the (assumed) relevant part of the log:

08:31:10.488 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 32 00 21 00 1A 00 22 01 00 00 00 02 EF 70 01 04 01 06 00 03 00 01 2C 02 00 AF FF 44 D0 21 00 CF 08 FA
08:31:10.488 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=50, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=GROUP(0), profileId=0104, clusterId=0006, lqi=255, rssi=207, data=01 2C 02]
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2C 02]
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Unknown local endpoint for APS frame ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2C 02]
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Incoming message from node 70EF did not translate to command
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done

Here's the node's persisted data (I serialize to JSON):

{
    "ieee" : "c4:14:23:fe:ff:81:8e:58",
    "nodeDescriptor" : {
      "userDescriptorAvailable" : false,
      "incomingTransferSize" : 82,
      "manufacturerCode" : 4476,
      "complexDescriptorAvailable" : false,
      "logicalType" : "END_DEVICE",
      "macCapabilities" : [ "REDUCED_FUNCTION_DEVICE" ],
      "serverCapabilities" : [ ],
      "frequencyBands" : [ "FREQ_2400_MHZ" ],
      "apsFlags" : 0,
      "bufferSize" : 82
    },
    "bindingTable" : [ ],
    "endpoints" : [ {
      "endpointId" : 1,
      "profileId" : 260,
      "deviceId" : 2080,
      "deviceVersion" : 1,
      "inputClusterIds" : [ ],
      "outputClusterIds" : [ ],
      "inputClusters" : [ {
        "label" : null,
        "clusterId" : 0,
        "attributes" : {
          "0" : {
            "id" : 0,
            "name" : "ZCL Version",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "16384" : {
            "id" : 16384,
            "name" : "SW Build ID",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "1" : {
            "id" : 1,
            "name" : "Application Version",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "2" : {
            "id" : 2,
            "name" : "Stack Version",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "3" : {
            "id" : 3,
            "name" : "HW Version",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "4" : {
            "id" : 4,
            "name" : "Manufacturer Name",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "5" : {
            "id" : 5,
            "name" : "Model Identifier",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "6" : {
            "id" : 6,
            "name" : "Date Code",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "7" : {
            "id" : 7,
            "name" : "Power Source",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "8" : {
            "id" : 8,
            "name" : "Generic Device Class",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "9" : {
            "id" : 9,
            "name" : "Generic Device Type",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "10" : {
            "id" : 10,
            "name" : "Product Code",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "11" : {
            "id" : 11,
            "name" : "Product URL",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "16" : {
            "id" : 16,
            "name" : "Location Description",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "17" : {
            "id" : 17,
            "name" : "Physical Environment",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "18" : {
            "id" : 18,
            "name" : "Device Enabled",
            "dataType" : "BOOLEAN",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "19" : {
            "id" : 19,
            "name" : "Alarm Mask",
            "dataType" : "BITMAP_8_BIT",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "20" : {
            "id" : 20,
            "name" : "Disable Local Config",
            "dataType" : "BITMAP_8_BIT",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          }
        },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      }, {
        "label" : null,
        "clusterId" : 32,
        "attributes" : {
          "0" : {
            "id" : 0,
            "name" : "Checkin Interval",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : true,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "1" : {
            "id" : 1,
            "name" : "Long Poll Interval",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : true,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "2" : {
            "id" : 2,
            "name" : "Short Poll Interval",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : true,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "3" : {
            "id" : 3,
            "name" : "Fast Poll Timeout",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : true,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "4" : {
            "id" : 4,
            "name" : "Checkin Interval Min",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "5" : {
            "id" : 5,
            "name" : "Long Poll Interval Min",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "6" : {
            "id" : 6,
            "name" : "Fast Poll Timeout Max",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          }
        },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      }, {
        "label" : null,
        "clusterId" : 4096,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      }, {
        "label" : null,
        "clusterId" : 1,
        "attributes" : {
          "0" : {
            "id" : 0,
            "name" : "Mains Voltage",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "32" : {
            "id" : 32,
            "name" : "Battery Voltage",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "1" : {
            "id" : 1,
            "name" : "Mains Frequency",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "33" : {
            "id" : 33,
            "name" : "Battery Percentage Remaining",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : true,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "16" : {
            "id" : 16,
            "name" : "Mains Alarm Mask",
            "dataType" : "BITMAP_8_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "48" : {
            "id" : 48,
            "name" : "Battery Manufacturer",
            "dataType" : "CHARACTER_STRING",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "17" : {
            "id" : 17,
            "name" : "Mains Voltage Min Threshold",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "49" : {
            "id" : 49,
            "name" : "Battery Size",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "18" : {
            "id" : 18,
            "name" : "Mains Voltage Max Threshold",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "50" : {
            "id" : 50,
            "name" : "Battery A Hr Rating",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "19" : {
            "id" : 19,
            "name" : "Mains Voltage Dwell Trip Point",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "51" : {
            "id" : 51,
            "name" : "Battery Quantity",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "52" : {
            "id" : 52,
            "name" : "Battery Rated Voltage",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "53" : {
            "id" : 53,
            "name" : "Battery Alarm Mask",
            "dataType" : "BITMAP_8_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "54" : {
            "id" : 54,
            "name" : "Battery Voltage Min Threshold",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "55" : {
            "id" : 55,
            "name" : "Battery Voltage Threshold 1",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "56" : {
            "id" : 56,
            "name" : "Battery Voltage Threshold 2",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "57" : {
            "id" : 57,
            "name" : "Battery Voltage Threshold 3",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "58" : {
            "id" : 58,
            "name" : "Battery Percentage Min Threshold",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "59" : {
            "id" : 59,
            "name" : "Battery Percentage Threshold 1",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "60" : {
            "id" : 60,
            "name" : "Battery Percentage Threshold 2",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "61" : {
            "id" : 61,
            "name" : "Battery Percentage Threshold 3",
            "dataType" : "UNSIGNED_8_BIT_INTEGER",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "62" : {
            "id" : 62,
            "name" : "Battery Alarm State",
            "dataType" : "BITMAP_32_BIT",
            "mandatory" : false,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          }
        },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      }, {
        "label" : null,
        "clusterId" : 3,
        "attributes" : {
          "0" : {
            "id" : 0,
            "name" : "Identify Time",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : true,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          }
        },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      }, {
        "label" : null,
        "clusterId" : 64636,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : false
      } ],
      "outputClusters" : [ {
        "label" : null,
        "clusterId" : 4096,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      }, {
        "label" : null,
        "clusterId" : 3,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      }, {
        "label" : null,
        "clusterId" : 4,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      }, {
        "label" : null,
        "clusterId" : 6,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      }, {
        "label" : null,
        "clusterId" : 8,
        "attributes" : { },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      }, {
        "label" : null,
        "clusterId" : 25,
        "attributes" : {
          "0" : {
            "id" : 0,
            "name" : "Upgrade Server ID",
            "dataType" : "IEEE_ADDRESS",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "1" : {
            "id" : 1,
            "name" : "File Offset",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "2" : {
            "id" : 2,
            "name" : "Current File Version",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "3" : {
            "id" : 3,
            "name" : "Current ZigBee Stack Version",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "4" : {
            "id" : 4,
            "name" : "Downloaded File Version",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "5" : {
            "id" : 5,
            "name" : "Downloaded ZigBee Stack Version",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "6" : {
            "id" : 6,
            "name" : "Image Upgrade Status",
            "dataType" : "ENUMERATION_8_BIT",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "7" : {
            "id" : 7,
            "name" : "Manufacturer ID",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "8" : {
            "id" : 8,
            "name" : "Image Type ID",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "9" : {
            "id" : 9,
            "name" : "Minimum Block Request Period",
            "dataType" : "UNSIGNED_16_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          },
          "10" : {
            "id" : 10,
            "name" : "Image Stamp",
            "dataType" : "UNSIGNED_32_BIT_INTEGER",
            "mandatory" : true,
            "implemented" : false,
            "readable" : true,
            "writable" : false,
            "reportable" : false,
            "minimumReportingPeriod" : 0,
            "maximumReportingPeriod" : 0,
            "reportingChange" : null,
            "reportingTimeout" : 0,
            "manufacturerCode" : null,
            "lastReportTime" : null,
            "lastValue" : null
          }
        },
        "supportedCommandsReceived" : [ ],
        "supportedCommandsGenerated" : [ ],
        "supportedAttributes" : null,
        "client" : true
      } ]
    } ],
    "networkAddress" : 28911,
    "powerDescriptor" : {
      "powerLevel" : "FULL",
      "currentPowerSource" : "MAINS",
      "currentPowerMode" : "RECEIVER_ON_IDLE",
      "availablePowerSources" : [ "MAINS" ]
    }
  }

Here is the entire log from that run:

08:30:58.071 [main] DEBUG c.z.z.t.ZigBeeTransactionQueue - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:58.073 [main] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=4000, maxRetries=0]
08:30:58.073 [main] DEBUG c.z.z.t.ZigBeeTransactionQueue - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
08:30:58.183 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
08:30:58.183 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state is updated to INITIALISING
08:30:58.183 [NotificationService-thread-1] INFO  com.dajudge.jarvis.zigbee.Startup - Network state changed to INITIALISING
08:30:58.282 [main] INFO  c.d.j.zigbee.datastore.JsonDataStore - Read network nodes: [00212EFFFF05059E, 588E81FFFE11CC42, 588E81FFFE2314C4]
08:30:58.293 [main] INFO  c.d.j.zigbee.datastore.JsonDataStore - Read node: com.zsmartsystems.zigbee.database.ZigBeeNodeDao@3fce8fd9
08:30:58.293 [main] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 00212EFFFF05059E: Data store: Node was restored.
08:30:58.294 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - 00212EFFFF05059E: Updating node NWK=0000
08:30:58.298 [main] INFO  c.d.j.zigbee.datastore.JsonDataStore - Read node: com.zsmartsystems.zigbee.database.ZigBeeNodeDao@609bcfb6
08:30:58.335 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE11CC42: Endpoint 1. Unsupported cluster 1000 - using ZclCustomCluster
08:30:58.337 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE11CC42: Endpoint 1. Unsupported cluster FC7C - using ZclCustomCluster
08:30:58.338 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE11CC42: Endpoint 1. Unsupported cluster 1000 - using ZclCustomCluster
08:30:58.346 [main] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE11CC42: Data store: Node was restored.
08:30:58.346 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE11CC42: Updating node NWK=98C6
08:30:58.350 [main] INFO  c.d.j.zigbee.datastore.JsonDataStore - Read node: com.zsmartsystems.zigbee.database.ZigBeeNodeDao@282cb7c7
08:30:58.351 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE2314C4: Endpoint 1. Unsupported cluster 1000 - using ZclCustomCluster
08:30:58.351 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE2314C4: Endpoint 1. Unsupported cluster FC7C - using ZclCustomCluster
08:30:58.351 [main] DEBUG c.z.zigbee.ZigBeeEndpoint - 588E81FFFE2314C4: Endpoint 1. Unsupported cluster 1000 - using ZclCustomCluster
08:30:58.352 [main] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE2314C4: Data store: Node was restored.
08:30:58.352 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Updating node NWK=70EF
08:30:58.353 [main] DEBUG c.z.z.d.conbee.ZigBeeDongleConBee - ConBee transport initialize
08:30:58.353 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Ignoring invalid transport state transition from UNINITIALISED to UNINITIALISED
08:30:58.353 [main] DEBUG c.z.zigbee.serial.ZigBeeSerialPort - Opening port /dev/ttyACM0 at 115200 baud with FLOWCONTROL_OUT_NONE.
08:30:58.361 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeReceiveHandler thread started
08:30:58.364 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.365 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.365 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.365 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=1]
08:30:58.365 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 01 00 08 00 00 00 00 F0 FF
08:30:58.365 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.367 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 01 00 08 00 A2 00 02 4C FF
08:30:58.368 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=1, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out QueryVersionRequest [sequence=0]
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QueryVersionRequest [sequence=2]
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0D 02 00 09 00 00 00 00 00 E8 FF
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.369 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.371 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0D 02 00 09 00 00 07 6F 26 4C FF
08:30:58.372 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QueryVersionResponse [sequence=2, version=644810496]
08:30:58.372 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.372 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.372 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=3]
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 03 00 08 00 00 00 00 EE FF
08:30:58.373 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 03 00 08 00 A2 00 6F DD FE
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=3, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=DEVICE_TYPE]
08:30:58.375 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=4, parameter=DEVICE_TYPE]
08:30:58.376 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 04 00 08 00 01 00 09 E0 FF
08:30:58.376 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.376 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.378 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 04 00 09 00 02 00 09 01 DD FF
08:30:58.378 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=4, status=SUCCESS, parameter=DEVICE_TYPE, value=01]
08:30:58.378 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.379 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.379 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.379 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.379 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.379 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.379 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=5]
08:30:58.379 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 05 00 08 00 00 00 00 EC FF
08:30:58.380 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.382 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 05 00 08 00 A2 00 09 41 FF
08:30:58.382 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=5, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=MAC_ADDRESS]
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=6, parameter=MAC_ADDRESS]
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 06 00 08 00 01 00 01 E6 FF
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.383 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.385 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 06 00 10 00 09 00 01 9E 05 05 FF FF 2E 21 00 E1 FC
08:30:58.385 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=6, status=SUCCESS, parameter=MAC_ADDRESS, value=9E 05 05 FF FF 2E 21 00]
08:30:58.385 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.385 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.385 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.385 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.386 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.386 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.386 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=7]
08:30:58.386 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 07 00 08 00 00 00 00 EA FF
08:30:58.386 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 07 00 08 00 A2 00 01 47 FF
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=7, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=NWK_PANID]
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=8, parameter=NWK_PANID]
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 08 00 08 00 01 00 05 E0 FF
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.388 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.390 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 08 00 0A 00 03 00 05 57 B4 D1 FE
08:30:58.390 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=8, status=SUCCESS, parameter=NWK_PANID, value=57 B4]
08:30:58.390 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.390 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.390 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.390 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.390 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.390 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.390 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=9]
08:30:58.391 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 09 00 08 00 00 00 00 E8 FF
08:30:58.391 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.392 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 09 00 08 00 A2 00 05 41 FF
08:30:58.392 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=9, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.392 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=APS_EXTENDED_PANID]
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=10, parameter=APS_EXTENDED_PANID]
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 0A 00 08 00 01 00 0B D8 FF
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.393 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.394 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 0A 00 10 00 09 00 0B 00 00 00 00 00 00 00 00 C8 FF
08:30:58.394 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=10, status=SUCCESS, parameter=APS_EXTENDED_PANID, value=00 00 00 00 00 00 00 00]
08:30:58.395 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.395 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.395 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=11]
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 0B 00 08 00 00 00 00 E6 FF
08:30:58.395 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 0B 00 08 00 A2 00 0B 39 FF
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=11, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=NWK_ADDRESS]
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=12, parameter=NWK_ADDRESS]
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 0C 00 08 00 01 00 07 DA FF
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.397 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.399 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 0C 00 0A 00 03 00 07 00 00 D6 FF
08:30:58.399 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=12, status=SUCCESS, parameter=NWK_ADDRESS, value=00 00]
08:30:58.399 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.399 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.399 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=13]
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 0D 00 08 00 00 00 00 E4 FF
08:30:58.399 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 0D 00 08 00 A2 00 07 3B FF
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=13, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=NWK_EXTENDED_PANID]
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=14, parameter=NWK_EXTENDED_PANID]
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 0E 00 08 00 01 00 08 D7 FF
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.401 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.403 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 0E 00 10 00 09 00 08 9E 05 05 FF FF 2E 21 00 D2 FC
08:30:58.403 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=14, status=SUCCESS, parameter=NWK_EXTENDED_PANID, value=9E 05 05 FF FF 2E 21 00]
08:30:58.403 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.403 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.403 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.403 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.403 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.403 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.403 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=15]
08:30:58.404 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 0F 00 08 00 00 00 00 E2 FF
08:30:58.404 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 0F 00 08 00 A2 00 08 38 FF
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=15, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out ReadParameterRequest [sequence=0, parameter=CURRENT_CHANNEL]
08:30:58.405 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadParameterRequest [sequence=16, parameter=CURRENT_CHANNEL]
08:30:58.406 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 0A 10 00 08 00 01 00 1C C1 FF
08:30:58.406 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.406 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.407 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0A 10 00 09 00 02 00 1C 0F B0 FF
08:30:58.407 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadParameterResponse [sequence=16, status=SUCCESS, parameter=CURRENT_CHANNEL, value=0F]
08:30:58.408 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_OFFLINE, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.408 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.408 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=17]
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 11 00 08 00 00 00 00 E0 FF
08:30:58.408 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 11 00 08 00 A2 00 1C 22 FF
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=17, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out DeviceStateRequest [sequence=0]
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=18]
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 12 00 08 00 00 00 00 DF FF
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.410 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.412 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 12 00 08 00 A2 00 1C 21 FF
08:30:58.412 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=18, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.412 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.412 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.412 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.412 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Default profile ID set to 0104 [ZIGBEE_HOME_AUTOMATION]
08:30:58.413 [main] DEBUG c.z.z.d.conbee.ZigBeeDongleConBee - Unsupported configuration option "RADIO_TX_POWER" in Telegesis dongle
08:30:58.414 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported client cluster 0500
08:30:58.415 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher starting for endpoint 1 with profile ID 0104 (ZIGBEE_HOME_AUTOMATION)
08:30:58.415 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding client cluster 0500
08:30:58.415 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported server cluster 0019
08:30:58.415 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding server cluster 0019
08:30:58.416 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported server cluster 0000
08:30:58.416 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding server cluster 0000
08:30:58.418 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported client cluster 0000
08:30:58.418 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding client cluster 0000
08:30:58.418 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported client cluster 0003
08:30:58.418 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding client cluster 0003
08:30:58.418 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported client cluster 0006
08:30:58.418 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding client cluster 0006
08:30:58.419 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported server cluster 0000
08:30:58.419 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding server cluster 0000
08:30:58.419 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported server cluster 0003
08:30:58.419 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding server cluster 0003
08:30:58.419 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Adding supported server cluster 0006
08:30:58.419 [main] DEBUG c.z.zigbee.internal.ClusterMatcher - ClusterMatcher adding server cluster 0006
08:30:58.419 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - ZigBeeNetworkManager startup: reinitialize=false, networkState=INITIALISING
08:30:58.419 [main] DEBUG c.z.z.d.conbee.ZigBeeDongleConBee - ConBee transport startup
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=19]
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 13 00 08 00 00 00 00 DE FF
08:30:58.419 [ConBeeDongle-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.420 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 13 00 08 00 A2 00 1C 20 FF
08:30:58.420 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=19, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Ignoring invalid transport state transition from UNINITIALISED to ONLINE
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out DeviceStateRequest [sequence=0]
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=20]
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 14 00 08 00 00 00 00 DD FF
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.421 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.423 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 14 00 08 00 A2 00 1C 1F FF
08:30:58.423 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=20, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.423 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.423 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.423 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.423 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state is updated to ONLINE
08:30:58.423 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state ONLINE: Process running. 3 Nodes in network.
08:30:58.423 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - 00212EFFFF05059E: Adding local node to network, NWK=0000
08:30:58.423 [main] INFO  com.dajudge.jarvis.zigbee.Startup - Application start
08:30:58.424 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - Permit join to FFFC/0 for 0 seconds.
08:30:58.426 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=--, permitDuration=0, tcSignificance=true]]
08:30:58.426 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
08:30:58.426 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - FFFC/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
08:30:58.426 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 1 outstanding
08:30:58.427 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]
08:30:58.428 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFC/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=00, rssi=--, lqi=--, payload=00 00 01]
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=21]
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 15 00 08 00 00 00 00 DC FF
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - 00212EFFFF05059E: Creating new Transaction Queue
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:58.429 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=--, permitDuration=0, tcSignificance=true]]
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - 0000/0: Sending ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=1, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 2 outstanding
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=01, rssi=--, lqi=--, payload=01 00 01]
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2
08:30:58.430 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 15 00 08 00 A2 00 1C 1E FF
08:30:58.430 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2 sync
08:30:58.431 [NotificationService-thread-1] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2 exit
08:30:58.431 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=21, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.431 [NotificationService-thread-1] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Startup
08:30:58.431 [NotificationService-thread-1] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - DISCOVERY Extension: Startup
08:30:58.431 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.431 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(FFFC/0), txOptions=0, radius=8, clusterId=54, requestId=0, profileId=0, data=00 00 01]
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=22, sourceEndpoint=0, destinationAddress=NWK(FFFC/0), txOptions=0, radius=8, clusterId=54, requestId=0, profileId=0, data=00 00 01]
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 16 00 19 00 12 00 00 00 02 FC FF 00 00 00 36 00 00 03 00 00 00 01 00 08 6E FD
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.437 [NotificationService-thread-1] DEBUG c.z.z.a.d.ZigBeeNetworkDiscoverer - Network discovery task: starting
08:30:58.437 [NotificationService-thread-1] DEBUG c.z.z.a.d.ZigBeeNetworkDiscoverer - NWK Discovery for 0000 scheduling node discovery
08:30:58.438 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state ONLINE: Notifying node 00212EFFFF05059E [0000]
08:30:58.438 [NetworkManager-thread-1] DEBUG c.z.z.a.d.ZigBeeNetworkDiscoverer - NWK Discovery for 0000 starting node discovery
08:30:58.438 [NetworkManager-thread-1] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=0, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=--, nwkAddrOfInterest=0000, requestType=1, startIndex=0]]
08:30:58.438 [NetworkManager-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=0/3
08:30:58.439 [NotificationService-thread-3] INFO  com.dajudge.jarvis.zigbee.Startup - Node added: ZigBeeNode [state=UNKNOWN, IEEE=00212EFFFF05059E, NWK=0000, Type=COORDINATOR, endpoints=[]]
08:30:58.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 16 00 09 00 02 00 22 00 AB FF
08:30:58.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=22, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:30:58.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.441 [NotificationService-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:58.441 [NotificationService-thread-4] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 00212EFFFF05059E: Data store: Deferring write for 250ms.
08:30:58.442 [NotificationService-thread-2] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - 00212EFFFF05059E: Basic Server Extension: Updating attributes
08:30:58.442 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state ONLINE: Notifying node 588E81FFFE11CC42 [98C6]
08:30:58.442 [NotificationService-thread-6] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - 00212EFFFF05059E: DISCOVERY Extension: Adding discoverer for node
08:30:58.442 [NotificationService-thread-2] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Updating attributes
08:30:58.442 [NotificationService-thread-7] INFO  com.dajudge.jarvis.zigbee.Startup - Node added: ZigBeeNode [state=UNKNOWN, IEEE=588E81FFFE11CC42, NWK=98C6, Type=END_DEVICE, endpoints=[1]]
08:30:58.443 [NotificationService-thread-1] DEBUG c.z.zigbee.ZigBeeNetworkManager - Network state ONLINE: Notifying node 588E81FFFE2314C4 [70EF]
08:30:58.443 [NotificationService-thread-5] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE11CC42: Data store: Deferring write for 250ms.
08:30:58.443 [NotificationService-thread-4] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - 588E81FFFE11CC42: Basic Server Extension: Updating attributes
08:30:58.443 [NotificationService-thread-4] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Updating attributes
08:30:58.443 [NotificationService-thread-3] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE2314C4: Data store: Deferring write for 250ms.
08:30:58.443 [NotificationService-thread-9] INFO  com.dajudge.jarvis.zigbee.Startup - Node added: ZigBeeNode [state=UNKNOWN, IEEE=588E81FFFE2314C4, NWK=70EF, Type=END_DEVICE, endpoints=[1]]
08:30:58.444 [NotificationService-thread-10] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - 588E81FFFE2314C4: Basic Server Extension: Updating attributes
08:30:58.444 [NotificationService-thread-10] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Updating attributes
08:30:58.444 [NotificationService-thread-9] INFO  com.dajudge.jarvis.zigbee.Startup - Network state changed to ONLINE
08:30:58.446 [NotificationService-thread-6] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: created discoverer
08:30:58.446 [NotificationService-thread-6] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: start discovery
08:30:58.447 [NotificationService-thread-6] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: starting new tasks []
08:30:58.447 [NotificationService-thread-6] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: has no new tasks to perform
08:30:58.447 [NotificationService-thread-2] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - 588E81FFFE2314C4: DISCOVERY Extension: Adding discoverer for node
08:30:58.447 [NotificationService-thread-2] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: created discoverer
08:30:58.447 [NotificationService-thread-2] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: start discovery
08:30:58.447 [NotificationService-thread-2] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: starting new tasks [NWK_ADDRESS]
08:30:58.447 [NotificationService-thread-2] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: scheduled [NWK_ADDRESS]
08:30:58.450 [NotificationService-thread-7] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - 588E81FFFE11CC42: DISCOVERY Extension: Adding discoverer for node
08:30:58.450 [NotificationService-thread-7] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE11CC42: Node SVC Discovery: created discoverer
08:30:58.450 [NotificationService-thread-7] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE11CC42: Node SVC Discovery: start discovery
08:30:58.450 [NotificationService-thread-7] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE11CC42: Node SVC Discovery: starting new tasks [NWK_ADDRESS]
08:30:58.450 [NotificationService-thread-7] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE11CC42: Node SVC Discovery: scheduled [NWK_ADDRESS]
08:30:58.481 [TransactionManager-thread-1] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=0/3
08:30:58.675 [NetworkManager-thread-2] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE11CC42: Node SVC Discovery: running NWK_ADDRESS
08:30:58.676 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - 588E81FFFE11CC42: Creating new Transaction Queue
08:30:58.676 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE11CC42: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:58.676 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE11CC42: Updated sleepy state from false to true
08:30:58.676 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE11CC42: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=588E81FFFE11CC42 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=--, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]]
08:30:58.677 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=2, sleepy=0/3
08:30:58.677 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - 98C6/0: Sending ZigBeeTransaction [ieeeAddress=588E81FFFE11CC42 queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]]
08:30:58.677 [NetworkManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 3 outstanding
08:30:58.678 [NetworkManager-thread-2] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]
08:30:58.678 [NetworkManager-thread-2] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=98C6/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=02, rssi=--, lqi=--, payload=02 42 CC 11 FE FF 81 8E 58 00 00]
08:30:58.679 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2
08:30:58.679 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2 sync
08:30:58.679 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2 send
08:30:58.679 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=23]
08:30:58.679 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 17 00 08 00 00 00 00 DA FF
08:30:58.680 [NetworkManager-thread-2] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 2 exit
08:30:58.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 17 00 08 00 A2 00 22 16 FF
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=23, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(0000/0), txOptions=64, radius=8, clusterId=54, requestId=1, profileId=0, data=01 00 01]
08:30:58.682 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=24, sourceEndpoint=0, destinationAddress=NWK(0000/0), txOptions=64, radius=8, clusterId=54, requestId=1, profileId=0, data=01 00 01]
08:30:58.683 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 18 00 19 00 12 00 01 00 02 00 00 00 00 00 36 00 00 03 00 01 00 01 40 08 25 FF
08:30:58.683 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.683 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.685 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 18 00 09 00 02 00 22 01 A8 FF
08:30:58.685 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=24, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:30:58.685 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.685 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.685 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 19 00 07 00 AE 00 24 FF
08:30:58.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=25, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]]
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=25, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]]
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=25]
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 19 00 08 00 01 00 00 C7 FF
08:30:58.687 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.689 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 19 00 20 00 19 00 26 02 00 00 00 02 00 00 00 00 00 36 80 02 00 01 00 00 AF DD 38 D0 21 00 1E E1 FB
08:30:58.690 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=25, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(0), destinationAddress=NWK(0), profileId=0000, clusterId=8036, lqi=221, rssi=30, data=01 00]
08:30:58.690 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 00]
08:30:58.690 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 00212EFFFF05059E: Node update. NWK Address=0000
08:30:58.691 [ConBeeReceiveHandler] DEBUG com.zsmartsystems.zigbee.ZigBeeNode - 00212EFFFF05059E: Node state updated from UNKNOWN to ONLINE
08:30:58.693 [DatabaseManager-thread-1] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 00212EFFFF05059E: Data store: Writing node.
08:30:58.696 [NotificationService-thread-7] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:58.696 [NotificationService-thread-10] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - 00212EFFFF05059E: Basic Server Extension: Updating attributes
08:30:58.696 [NotificationService-thread-10] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Updating attributes
08:30:58.696 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - 00212EFFFF05059E: DISCOVERY Extension: Creating new discoverer for node
08:30:58.695 [NotificationService-thread-6] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 00212EFFFF05059E: Data store: Deferring write for 250ms.
08:30:58.696 [NotificationService-thread-2] INFO  com.dajudge.jarvis.zigbee.Startup - Node added: ZigBeeNode [state=ONLINE, IEEE=00212EFFFF05059E, NWK=0000, Type=COORDINATOR, endpoints=[]]
08:30:58.697 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: stopped
08:30:58.697 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: created discoverer
08:30:58.697 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: start discovery
08:30:58.697 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: starting new tasks []
08:30:58.697 [NotificationService-thread-9] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 00212EFFFF05059E: Node SVC Discovery: has no new tasks to perform
08:30:58.706 [DatabaseManager-thread-1] INFO  c.d.j.zigbee.datastore.JsonDataStore - Write node: 00212EFFFF05059E -> [NodeStore{ieee='42:cc:11:fe:ff:81:8e:58'}, NodeStore{ieee='c4:14:23:fe:ff:81:8e:58'}, NodeStore{ieee='9e:05:05:ff:ff:2e:21:00'}]
08:30:58.724 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX CMD: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
08:30:58.725 [ConBeeReceiveHandler] DEBUG c.z.z.t.ZigBeeTransactionManager - notifyTransactionCommand: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS] 
08:30:58.725 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:58.726 [NotificationService-thread-9] INFO  com.dajudge.jarvis.zigbee.Startup - Command received: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(98C6/0), txOptions=64, radius=8, clusterId=0, requestId=2, profileId=0, data=02 42 CC 11 FE FF 81 8E 58 00 00]
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=26, sourceEndpoint=0, destinationAddress=NWK(98C6/0), txOptions=64, radius=8, clusterId=0, requestId=2, profileId=0, data=02 42 CC 11 FE FF 81 8E 58 00 00]
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 1A 00 21 00 1A 00 02 00 02 C6 98 00 00 00 00 00 00 0B 00 02 42 CC 11 FE FF 81 8E 58 00 00 40 08 5F F9
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:58.726 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.727 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 1A 00 09 00 02 00 26 02 A1 FF
08:30:58.727 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=26, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:30:58.727 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:58.727 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:58.727 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:58.758 [DatabaseManager-thread-1] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE11CC42: Data store: Writing node.
08:30:58.764 [DatabaseManager-thread-1] INFO  c.d.j.zigbee.datastore.JsonDataStore - Write node: 588E81FFFE11CC42 -> [NodeStore{ieee='c4:14:23:fe:ff:81:8e:58'}, NodeStore{ieee='9e:05:05:ff:ff:2e:21:00'}, NodeStore{ieee='42:cc:11:fe:ff:81:8e:58'}]
08:30:58.775 [DatabaseManager-thread-1] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE2314C4: Data store: Writing node.
08:30:58.779 [DatabaseManager-thread-1] INFO  c.d.j.zigbee.datastore.JsonDataStore - Write node: 588E81FFFE2314C4 -> [NodeStore{ieee='9e:05:05:ff:ff:2e:21:00'}, NodeStore{ieee='42:cc:11:fe:ff:81:8e:58'}, NodeStore{ieee='c4:14:23:fe:ff:81:8e:58'}]
08:30:58.947 [DatabaseManager-thread-1] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 00212EFFFF05059E: Data store: Writing node.
08:30:58.960 [DatabaseManager-thread-1] INFO  c.d.j.zigbee.datastore.JsonDataStore - Write node: 00212EFFFF05059E -> [NodeStore{ieee='42:cc:11:fe:ff:81:8e:58'}, NodeStore{ieee='c4:14:23:fe:ff:81:8e:58'}, NodeStore{ieee='9e:05:05:ff:ff:2e:21:00'}]
08:30:59.092 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 1B 00 07 00 A6 00 2A FF
08:30:59.092 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=27, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:59.092 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=27, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:59.093 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:30:59.093 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:59.093 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=27]
08:30:59.093 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 1B 00 07 00 00 00 DA FF
08:30:59.093 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:59.095 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 1B 00 13 00 0C 00 26 01 02 00 00 00 00 00 00 00 00 00 99 FF
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=27, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=0000/0), requestId=1]
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=28]
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 1C 00 07 00 00 00 D9 FF
08:30:59.096 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:59.098 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 1C 00 13 00 0C 00 22 00 02 FC FF 00 00 00 00 00 00 00 A2 FD
08:30:59.099 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=28, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=FFFC/0), requestId=0]
08:30:59.099 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:59.099 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:59.099 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:59.769 [NetworkManager-thread-4] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: running NWK_ADDRESS
08:30:59.769 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - 588E81FFFE2314C4: Creating new Transaction Queue
08:30:59.769 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:30:59.770 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: Updated sleepy state from false to true
08:30:59.770 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=--, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:30:59.770 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=2, sleepy=1/3
08:30:59.770 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - 70EF/0: Sending ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=03, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:30:59.771 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 4 outstanding
08:30:59.771 [NetworkManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=03, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]
08:30:59.771 [NetworkManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=70EF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=03, rssi=--, lqi=--, payload=03 C4 14 23 FE FF 81 8E 58 00 00]
08:30:59.771 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:30:59.771 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:30:59.772 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:30:59.772 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=29]
08:30:59.772 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 1D 00 08 00 00 00 00 D4 FF
08:30:59.772 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 1D 00 08 00 A2 00 22 10 FF
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=29, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(70EF/0), txOptions=64, radius=8, clusterId=0, requestId=3, profileId=0, data=03 C4 14 23 FE FF 81 8E 58 00 00]
08:30:59.774 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=30, sourceEndpoint=0, destinationAddress=NWK(70EF/0), txOptions=64, radius=8, clusterId=0, requestId=3, profileId=0, data=03 C4 14 23 FE FF 81 8E 58 00 00]
08:30:59.775 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 1E 00 21 00 1A 00 03 00 02 EF 70 00 00 00 00 00 00 0B 00 03 C4 14 23 FE FF 81 8E 58 00 00 40 08 7C F9
08:30:59.775 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:30:59.775 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:30:59.777 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 1E 00 09 00 02 00 22 03 A0 FF
08:30:59.777 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=30, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:30:59.777 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:30:59.777 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:30:59.777 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 1F 00 07 00 AA 00 22 FF
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=31, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=31, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:04.242 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=31]
08:31:04.243 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 1F 00 08 00 01 00 00 C1 FF
08:31:04.243 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:04.246 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 1F 00 21 00 1A 00 22 01 00 00 00 02 EF 70 01 04 01 06 00 03 00 01 2A 02 00 AF FF 3E D0 21 00 C9 29 FA
08:31:04.246 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=31, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=GROUP(0), profileId=0104, clusterId=0006, lqi=255, rssi=201, data=01 2A 02]
08:31:04.246 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2A 02]
08:31:04.246 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:04.247 [ConBeeReceiveHandler] DEBUG com.zsmartsystems.zigbee.ZigBeeNode - 588E81FFFE2314C4: Node state updated from UNKNOWN to ONLINE
08:31:04.247 [NotificationService-thread-9] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
08:31:04.247 [NotificationService-thread-6] INFO  com.dajudge.jarvis.zigbee.Startup - Node added: ZigBeeNode [state=ONLINE, IEEE=588E81FFFE2314C4, NWK=70EF, Type=END_DEVICE, endpoints=[1]]
08:31:04.247 [NotificationService-thread-10] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE2314C4: Data store: Deferring write for 250ms.
08:31:04.247 [NotificationService-thread-7] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - 588E81FFFE2314C4: Basic Server Extension: Updating attributes
08:31:04.247 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeDiscoveryExtension - 588E81FFFE2314C4: DISCOVERY Extension: Creating new discoverer for node
08:31:04.248 [NotificationService-thread-7] DEBUG c.z.z.a.b.ZigBeeBasicServerExtension - Basic Server Extension: Updating attributes
08:31:04.248 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: stopped
08:31:04.248 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Unknown local endpoint for APS frame ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2A 02]
08:31:04.248 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionFuture - TransactionFuture interrupted after 4476ms: ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=4478, state=DISPATCHED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=03, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:04.249 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Incoming message from node 70EF did not translate to command
08:31:04.248 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: created discoverer
08:31:04.249 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:04.249 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:04.249 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: start discovery
08:31:04.249 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:04.249 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: starting new tasks [NWK_ADDRESS]
08:31:04.249 [NetworkManager-thread-4] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: NetworkAddressRequest returned null
08:31:04.249 [NotificationService-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: scheduled [NWK_ADDRESS]
08:31:04.250 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=--, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:04.250 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=2, sleepy=2/3
08:31:04.250 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - FFFF/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=04, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:04.251 [NetworkManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 5 outstanding
08:31:04.251 [NetworkManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=04, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]
08:31:04.251 [NetworkManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=04, rssi=--, lqi=--, payload=04 C4 14 23 FE FF 81 8E 58 00 00]
08:31:04.251 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:31:04.252 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:31:04.252 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:31:04.252 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=32]
08:31:04.252 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 20 00 08 00 00 00 00 D1 FF
08:31:04.252 [NetworkManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:31:04.254 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 20 00 08 00 A2 00 22 0D FF
08:31:04.254 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=32, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:04.254 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:04.255 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:04.255 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:31:04.255 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(FFFF/0), txOptions=0, radius=8, clusterId=0, requestId=4, profileId=0, data=04 C4 14 23 FE FF 81 8E 58 00 00]
08:31:04.255 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=33, sourceEndpoint=0, destinationAddress=NWK(FFFF/0), txOptions=0, radius=8, clusterId=0, requestId=4, profileId=0, data=04 C4 14 23 FE FF 81 8E 58 00 00]
08:31:04.255 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 21 00 21 00 1A 00 04 00 02 FF FF 00 00 00 00 00 00 0B 00 04 C4 14 23 FE FF 81 8E 58 00 00 00 08 18 F9
08:31:04.256 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:31:04.256 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:04.258 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 21 00 09 00 02 00 22 04 9C FF
08:31:04.258 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=33, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:31:04.259 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:31:04.259 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:04.259 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:04.498 [DatabaseManager-thread-1] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - 588E81FFFE2314C4: Data store: Writing node.
08:31:04.501 [DatabaseManager-thread-1] INFO  c.d.j.zigbee.datastore.JsonDataStore - Write node: 588E81FFFE2314C4 -> [NodeStore{ieee='42:cc:11:fe:ff:81:8e:58'}, NodeStore{ieee='9e:05:05:ff:ff:2e:21:00'}, NodeStore{ieee='c4:14:23:fe:ff:81:8e:58'}]
08:31:05.271 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 22 00 07 00 A6 00 23 FF
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=34, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=34, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=34]
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 22 00 07 00 00 00 D3 FF
08:31:05.272 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:05.273 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 23 00 0C 00 05 00 02 EF 70 FF D2 7E FC
08:31:05.274 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 22 00 13 00 0C 00 22 03 02 EF 70 00 00 00 00 00 00 00 35 FE
08:31:05.274 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=34, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=70EF/0), requestId=3]
08:31:05.274 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:05.274 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.274 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:05.278 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 23 00 07 00 AA 00 1E FF
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=35, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=35, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=35]
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 23 00 08 00 01 00 00 BD FF
08:31:05.279 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:05.281 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 23 00 2A 00 23 00 22 02 00 00 00 02 EF 70 00 00 00 00 80 0C 00 03 00 C4 14 23 FE FF 81 8E 58 EF 70 00 AF FF 44 D0 21 00 D2 F2 F3
08:31:05.281 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=35, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=NWK(0), profileId=0000, clusterId=8000, lqi=255, rssi=210, data=03 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:05.282 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=03 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:05.282 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:05.282 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:05.283 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX CMD: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=03, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:05.284 [ConBeeReceiveHandler] DEBUG c.z.z.t.ZigBeeTransactionManager - notifyTransactionCommand: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=03, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]] 
08:31:05.285 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:05.285 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.285 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:05.285 [NotificationService-thread-6] INFO  com.dajudge.jarvis.zigbee.Startup - Command received: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=03, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:05.285 [NotificationService-thread-10] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=5515, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=03, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:05.285 [NotificationService-thread-10] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 4 outstanding
08:31:05.285 [NotificationService-thread-10] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: transactionComplete, state=COMPLETE, outstanding=0
08:31:05.286 [NotificationService-thread-10] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=1/3
08:31:05.664 [NetworkManager-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: running NWK_ADDRESS
08:31:05.664 [NetworkManager-thread-5] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=--, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:05.664 [NetworkManager-thread-5] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=2, sleepy=1/3
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.t.ZigBeeTransactionManager - 70EF/0: Sending ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=05, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 5 outstanding
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=05, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=70EF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=05, rssi=--, lqi=--, payload=05 C4 14 23 FE FF 81 8E 58 00 00]
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=36]
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 24 00 08 00 00 00 00 CD FF
08:31:05.665 [NetworkManager-thread-5] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 24 00 08 00 A2 00 22 09 FF
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=36, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(70EF/0), txOptions=64, radius=8, clusterId=0, requestId=5, profileId=0, data=05 C4 14 23 FE FF 81 8E 58 00 00]
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=37, sourceEndpoint=0, destinationAddress=NWK(70EF/0), txOptions=64, radius=8, clusterId=0, requestId=5, profileId=0, data=05 C4 14 23 FE FF 81 8E 58 00 00]
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 25 00 21 00 1A 00 05 00 02 EF 70 00 00 00 00 00 00 0B 00 05 C4 14 23 FE FF 81 8E 58 00 00 40 08 71 F9
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:31:05.667 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:05.669 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 25 00 09 00 02 00 22 05 97 FF
08:31:05.669 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=37, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:31:05.669 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:31:05.669 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:05.669 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:06.298 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 26 00 07 00 A6 00 1F FF
08:31:06.298 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=38, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:06.298 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=38, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:06.299 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:06.299 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:06.299 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=38]
08:31:06.299 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 26 00 07 00 00 00 CF FF
08:31:06.299 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:06.300 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 27 00 0C 00 05 00 02 EF 70 FF D0 7C FC
08:31:06.301 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 26 00 13 00 0C 00 22 04 02 FF FF 00 00 00 00 00 00 00 91 FD
08:31:06.301 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=38, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=FFFF/0), requestId=4]
08:31:06.301 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:06.301 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:06.301 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:07.329 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 27 00 07 00 A6 00 1E FF
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=39, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=39, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=39]
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 27 00 07 00 00 00 CE FF
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:07.330 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 28 00 0C 00 05 00 02 EF 70 FF D1 7A FC
08:31:07.332 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 27 00 13 00 0C 00 22 05 02 EF 70 00 00 00 00 00 00 00 2E FE
08:31:07.332 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=39, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=70EF/0), requestId=5]
08:31:07.332 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:07.332 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:07.332 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 28 00 07 00 AA 00 19 FF
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=40, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=40, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:07.338 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=40]
08:31:07.339 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 28 00 08 00 01 00 00 B8 FF
08:31:07.339 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 28 00 2A 00 23 00 22 02 00 00 00 02 EF 70 00 00 00 00 80 0C 00 05 00 C4 14 23 FE FF 81 8E 58 EF 70 00 AF FF 41 D0 21 00 D2 EE F3
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=40, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=NWK(0), profileId=0000, clusterId=8000, lqi=255, rssi=210, data=05 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=05 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX CMD: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=05, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:07.341 [ConBeeReceiveHandler] DEBUG c.z.z.t.ZigBeeTransactionManager - notifyTransactionCommand: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=05, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]] 
08:31:07.342 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:07.342 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:07.342 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:07.342 [NotificationService-thread-8] INFO  com.dajudge.jarvis.zigbee.Startup - Command received: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=05, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:07.342 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=588E81FFFE2314C4 queueTime=1678, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 70EF/0, cluster=0000, TID=05, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:07.342 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 4 outstanding
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=05, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:07.342 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE2314C4: transactionComplete, state=COMPLETE, outstanding=0
08:31:07.342 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=1/3
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: NetworkAddressRequest confirmed by unicast
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: request NWK_ADDRESS successful. Advancing to null.
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: complete
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Updating node NWK=70EF
08:31:07.342 [NetworkManager-thread-5] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:07.343 [NetworkManager-thread-5] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 29 00 07 00 AA 00 18 FF
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=41, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=41, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=41]
08:31:08.353 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 29 00 08 00 01 00 00 B7 FF
08:31:08.354 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.354 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 2A 00 0C 00 05 00 02 EF 70 FF CF 7A FC
08:31:08.355 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 29 00 2A 00 23 00 22 02 00 00 00 02 EF 70 00 00 00 00 80 0C 00 04 00 C4 14 23 FE FF 81 8E 58 EF 70 00 AF FF 44 D0 21 00 CF EE F3
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=41, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=NWK(0), profileId=0000, clusterId=8000, lqi=255, rssi=207, data=04 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=04 00 C4 14 23 FE FF 81 8E 58 EF 70]
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX CMD: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=04, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.z.t.ZigBeeTransactionManager - notifyTransactionCommand: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=04, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]] 
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.356 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.356 [NotificationService-thread-9] INFO  com.dajudge.jarvis.zigbee.Startup - Command received: NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=04, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:08.356 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=4106, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=04, ieeeAddr=588E81FFFE2314C4, requestType=0, startIndex=0]]
08:31:08.356 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 3 outstanding
08:31:08.356 [NetworkManager-thread-4] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [70EF/0 -> 0000/0, cluster=8000, TID=04, status=SUCCESS, ieeeAddrRemoteDev=588E81FFFE2314C4, nwkAddrRemoteDev=70EF, startIndex=null, nwkAddrAssocDevList=[]]
08:31:08.356 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: transactionComplete, state=COMPLETE, outstanding=1
08:31:08.356 [NotificationService-thread-6] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=1, sleepy=1/3
08:31:08.356 [NetworkManager-thread-4] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: NetworkAddressRequest confirmed by broadcast
08:31:08.356 [NetworkManager-thread-4] DEBUG c.z.z.a.d.ZigBeeNodeServiceDiscoverer - 588E81FFFE2314C4: Node SVC Discovery: closing scheduler thread
08:31:08.427 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=10001, state=FAILED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
08:31:08.428 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 2 outstanding
08:31:08.428 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: transactionComplete, state=FAILED, outstanding=0
08:31:08.428 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: transactionComplete exceeded max retries 1
08:31:08.428 [TransactionManager-thread-2] DEBUG c.z.z.transaction.ZigBeeTransaction - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=10002, state=CANCELLED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
08:31:08.428 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=10002, state=CANCELLED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
08:31:08.429 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 2 outstanding
08:31:08.429 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionQueue - Broadcast: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
08:31:08.429 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=1/3
08:31:08.429 [TransactionManager-thread-2] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=1/3
08:31:08.430 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=10001, state=FAILED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
08:31:08.431 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 1 outstanding
08:31:08.431 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: transactionComplete, state=FAILED, outstanding=0
08:31:08.431 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionQueue - 00212EFFFF05059E: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=10002, state=WAITING, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
08:31:08.431 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=1/3
08:31:08.431 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - 0000/0: Sending ZigBeeTransaction [ieeeAddress=00212EFFFF05059E queueTime=10003, state=WAITING, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 2 outstanding
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=06, rssi=--, lqi=--, payload=01 00 01]
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:31:08.432 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=42]
08:31:08.433 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 2A 00 08 00 00 00 00 C7 FF
08:31:08.433 [TransactionManager-thread-3] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:31:08.434 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 2A 00 08 00 A2 00 22 03 FF
08:31:08.435 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=42, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.435 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.435 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.435 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:31:08.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(0000/0), txOptions=64, radius=8, clusterId=54, requestId=6, profileId=0, data=01 00 01]
08:31:08.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=43, sourceEndpoint=0, destinationAddress=NWK(0000/0), txOptions=64, radius=8, clusterId=54, requestId=6, profileId=0, data=01 00 01]
08:31:08.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 2B 00 19 00 12 00 06 00 02 00 00 00 00 00 36 00 00 03 00 01 00 01 40 08 0D FF
08:31:08.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:31:08.436 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 2B 00 09 00 02 00 22 06 90 FF
08:31:08.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=43, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:31:08.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:31:08.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.439 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.440 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 2C 00 07 00 AE 00 11 FF
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=44, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=44, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=true, dataRequest=true, configChanged=false]
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=44]
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 2C 00 08 00 01 00 00 B4 FF
08:31:08.441 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.443 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 2C 00 20 00 19 00 26 02 00 00 00 02 00 00 00 00 00 36 80 02 00 01 00 00 AF FF 44 D0 21 00 CF EF FA
08:31:08.443 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=44, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(0), destinationAddress=NWK(0), profileId=0000, clusterId=8036, lqi=255, rssi=207, data=01 00]
08:31:08.443 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 00]
08:31:08.443 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 00212EFFFF05059E: Node update. NWK Address=0000
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 00212EFFFF05059E: Node 0000 is not updated
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX CMD: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.z.t.ZigBeeTransactionManager - notifyTransactionCommand: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS] 
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.444 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=45]
08:31:08.444 [NotificationService-thread-6] INFO  com.dajudge.jarvis.zigbee.Startup - Command received: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
08:31:08.445 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 2D 00 07 00 00 00 C8 FF
08:31:08.445 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.447 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 2D 00 13 00 0C 00 22 06 02 00 00 00 00 00 00 00 00 00 86 FF
08:31:08.448 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=45, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=0000/0), requestId=6]
08:31:08.448 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.448 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.448 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.481 [TransactionManager-thread-3] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=1/3
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction complete: ZigBeeTransaction [ieeeAddress=588E81FFFE11CC42 queueTime=10002, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]]
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerRemoved: 1 outstanding
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE11CC42: transactionComplete, state=FAILED, outstanding=0
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionQueue - 588E81FFFE11CC42: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=588E81FFFE11CC42 queueTime=10002, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]]
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=2, sleepy=0/3
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - 98C6/0: Sending ZigBeeTransaction [ieeeAddress=588E81FFFE11CC42 queueTime=10002, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]]
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.z.t.ZigBeeTransactionManager - transactionListenerAdded: 2 outstanding
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX CMD: NetworkAddressRequest [0000/0 -> 98C6/0, cluster=0000, TID=02, ieeeAddr=588E81FFFE11CC42, requestType=0, startIndex=0]
08:31:08.678 [TransactionManager-thread-4] DEBUG c.z.zigbee.ZigBeeNetworkManager - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=98C6/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=07, rssi=--, lqi=--, payload=02 42 CC 11 FE FF 81 8E 58 00 00]
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 sync
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 send
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: DeviceStateRequest [sequence=46]
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 07 2E 00 08 00 00 00 00 C3 FF
08:31:08.679 [TransactionManager-thread-4] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - TX CONBEE queue: 1 exit
08:31:08.680 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 07 2E 00 08 00 A2 00 22 FF FE
08:31:08.680 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateResponse [sequence=46, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.680 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.680 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out before
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out EnqueueSendDataRequest [sequence=0, sourceEndpoint=0, destinationAddress=NWK(98C6/0), txOptions=64, radius=8, clusterId=0, requestId=7, profileId=0, data=02 42 CC 11 FE FF 81 8E 58 00 00]
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: EnqueueSendDataRequest [sequence=47, sourceEndpoint=0, destinationAddress=NWK(98C6/0), txOptions=64, radius=8, clusterId=0, requestId=7, profileId=0, data=02 42 CC 11 FE FF 81 8E 58 00 00]
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 12 2F 00 21 00 1A 00 07 00 02 C6 98 00 00 00 00 00 00 0B 00 02 42 CC 11 FE FF 81 8E 58 00 00 40 08 45 F9
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState out after
08:31:08.681 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.683 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 12 2F 00 09 00 02 00 22 07 8B FF
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: EnqueueSendDataResponse [sequence=47, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=false, configChanged=false]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 30 00 07 00 A6 00 15 FF
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=48, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=48, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=48]
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 30 00 07 00 00 00 C5 FF
08:31:08.684 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 30 00 13 00 0C 00 22 07 02 C6 98 00 00 D0 00 00 00 00 54 FD
08:31:08.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=48, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=98C6/0), requestId=7]
08:31:08.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.686 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.854 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 31 00 07 00 A6 00 14 FF
08:31:08.854 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=49, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.854 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=49, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]]
08:31:08.855 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=true, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.855 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.855 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: QuerySendDataRequest [sequence=49]
08:31:08.855 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 04 31 00 07 00 00 00 C4 FF
08:31:08.855 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:08.856 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 04 31 00 13 00 0C 00 22 02 02 C6 98 00 00 D0 00 00 00 00 58 FD
08:31:08.857 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: QuerySendDataResponse [sequence=49, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], destinationAddress=(NWK=98C6/0), requestId=2]
08:31:08.857 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:08.857 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:08.857 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:09.376 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 32 00 0C 00 05 00 02 EF 70 FF CF 72 FC
08:31:10.485 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 0E 33 00 07 00 AA 00 0E FF
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: DeviceStateChanged [sequence=51, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame but sentFrame is null: DeviceStateChanged [sequence=51, status=SUCCESS, state=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]]
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=true, dataRequest=true, configChanged=false]
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState read
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: ReadReceivedDataRequest [sequence=50]
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE TX: 17 32 00 08 00 01 00 00 AE FF
08:31:10.486 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:10.488 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 17 32 00 21 00 1A 00 22 01 00 00 00 02 EF 70 01 04 01 06 00 03 00 01 2C 02 00 AF FF 44 D0 21 00 CF 08 FA
08:31:10.488 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX Frame: ReadReceivedDataResponse [sequence=50, status=SUCCESS, networkState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false], sourceAddress=NWK(28911), destinationAddress=GROUP(0), profileId=0104, clusterId=0006, lqi=255, rssi=207, data=01 2C 02]
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - RX APS: ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2C 02]
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node update. NWK Address=70EF
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - 588E81FFFE2314C4: Node 70EF is not updated
08:31:10.489 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Unknown local endpoint for APS frame ZigBeeApsFrame [sourceAddress=70EF/1, destinationAddress=0000/0, profile=0104, cluster=0006, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=01 2C 02]
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.zigbee.ZigBeeNetworkManager - Incoming message from node 70EF did not translate to command
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState=DeviceState [networkState=NET_CONNECTED, dataConfirm=false, dataIndication=false, dataRequest=true, configChanged=false]
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-sync
08:31:10.490 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - ConBeeDeviceState-done
08:31:11.508 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 33 00 0C 00 05 00 02 EF 70 FF CF 71 FC
08:31:12.535 [ConBeeReceiveHandler] DEBUG c.z.z.d.c.i.ConBeeFrameHandler - CONBEE RX: Data 1C 34 00 0C 00 05 00 02 EF 70 FF CC 73 FC
08:31:13.491 [main] INFO  com.dajudge.jarvis.zigbee.Startup - Application exit
08:31:13.491 [main] DEBUG c.z.zigbee.ZigBeeNetworkManager - ZigBeeNetworkManager shutdown: networkState=ONLINE
08:31:13.491 [main] DEBUG c.z.z.d.ZigBeeNetworkDatabaseManager - Data store: Deferred Write Time set to 0ms
cdjackson commented 3 years ago

I guess it's worth firstly pointing out that the ConBee is not currently supported.

Expected behavior

No error in log

I can't see any error at the moment (at least not from a search of the above)

commandReceived() invocation on listener from NetworkManager

Please can you say what command you are expecting to see

dajudge commented 3 years ago

Nevermind. Going for zigpy which works nicely.

Closing.