16:32:18.795 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=40]
16:32:18.817 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=50]
16:32:18.817 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=50]
16:32:18.839 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=61]
16:32:18.839 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=61]
16:32:18.861 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=71]
16:32:18.862 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=71]
16:32:18.863 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS [01]
16:32:18.864 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_ALLOW_APP_KEY_REQUESTS [61]
16:32:18.865 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS [50]
16:32:18.867 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION [10]
16:32:18.868 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_REJOINS_USING_WELL_KNOWN_KEY_POLICY = UNKNOWN [71]
16:32:18.869 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK [40]
16:32:18.870 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16]
16:32:18.884 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.886 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.887 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_SECURITY_LEVEL, value=5]
16:32:18.905 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.905 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.907 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=10]
16:32:18.928 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.928 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.929 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2]
16:32:18.951 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.951 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.952 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_STACK_PROFILE, value=2]
16:32:18.973 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.975 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.976 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680]
16:32:18.995 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.995 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:18.996 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_MAX_HOPS, value=8]
16:32:19.018 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.018 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.019 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_TX_POWER_MODE, value=1]
16:32:19.041 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.041 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.042 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1]
16:32:19.063 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.065 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.066 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4]
16:32:19.086 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.086 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.087 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1]
16:32:19.108 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.108 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.109 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16]
16:32:19.131 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.131 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.132 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10]
16:32:19.153 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.153 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.154 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15]
16:32:19.175 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_ERROR_INVALID_VALUE]
16:32:19.175 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_ERROR_INVALID_VALUE]
16:32:19.176 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_BINDING_TABLE_SIZE, value=2]
16:32:19.197 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.198 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.199 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16]
16:32:19.225 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.225 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.226 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_FRAGMENT_WINDOW_SIZE, value=1]
16:32:19.247 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.247 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.248 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_FRAGMENT_DELAY_MS, value=50]
16:32:19.268 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.268 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.269 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255]
16:32:19.291 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.291 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.292 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT, value=211]
16:32:19.312 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.312 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.313 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [networkId=0, configId=EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT_SHIFT, value=11]
16:32:19.334 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.334 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.356 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.356 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.378 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=5]
16:32:19.379 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=5]
16:32:19.401 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=10]
16:32:19.401 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=10]
16:32:19.422 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.422 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.444 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.444 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.466 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=7680]
16:32:19.466 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=7680]
16:32:19.488 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=8]
16:32:19.489 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=8]
16:32:19.509 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.510 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.532 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.532 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.554 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=4]
16:32:19.554 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=4]
16:32:19.576 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.576 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.598 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.599 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.619 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=10]
16:32:19.620 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=10]
16:32:19.642 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=15]
16:32:19.642 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=15]
16:32:19.664 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.664 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=2]
16:32:19.686 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.686 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=16]
16:32:19.709 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.709 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=1]
16:32:19.732 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=50]
16:32:19.732 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=50]
16:32:19.755 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=255]
16:32:19.755 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=255]
16:32:19.777 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=211]
16:32:19.777 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=211]
16:32:19.799 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=11]
16:32:19.799 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=11]
16:32:19.800 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1
16:32:19.801 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10
16:32:19.802 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255
16:32:19.803 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 2
16:32:19.804 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 10
16:32:19.805 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT_SHIFT = 11
16:32:19.806 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BINDING_TABLE_SIZE = 2
16:32:19.807 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 1
16:32:19.808 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_FRAGMENT_DELAY_MS = 50
16:32:19.809 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1
16:32:19.810 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4
16:32:19.811 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5
16:32:19.812 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 8
16:32:19.812 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16
16:32:19.813 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT = 211
16:32:19.814 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680
16:32:19.815 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15
16:32:19.816 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16
16:32:19.816 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_FRAGMENT_WINDOW_SIZE = 1
16:32:19.817 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16
16:32:19.818 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2
16:32:19.826 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.834 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.849 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.849 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.871 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.871 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.894 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.894 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.917 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.917 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.939 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.939 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:19.962 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=01]
16:32:19.962 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=01]
16:32:19.983 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=12]
16:32:19.983 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=12]
16:32:20.006 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=40]
16:32:20.006 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=40]
16:32:20.029 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=51]
16:32:20.029 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=51]
16:32:20.051 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=60]
16:32:20.051 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=60]
16:32:20.073 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=00]
16:32:20.073 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [networkId=0, status=EZSP_SUCCESS, decisionId=00]
16:32:20.075 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS [01]
16:32:20.076 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_DENY_APP_KEY_REQUESTS [60]
16:32:20.077 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_ALLOW_TC_KEY_REQUESTS_AND_SEND_CURRENT_KEY [51]
16:32:20.078 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS [12]
16:32:20.079 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_REJOINS_USING_WELL_KNOWN_KEY_POLICY = EZSP_ALLOW_JOINS [00]
16:32:20.080 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK [40]
16:32:20.096 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:20.096 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember initial network parameters are EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]
16:32:20.119 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetEui64Response [networkId=0, eui64=000D6F0011FE5D60]
16:32:20.119 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember local IEEE Address is 000D6F0011FE5D60
16:32:20.141 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:20.141 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: initialize done
16:32:20.143 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 3600
16:32:20.144 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0500
16:32:20.146 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher starting for endpoint 1 with profile ID 0104 (ZIGBEE_HOME_AUTOMATION)
16:32:20.147 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0500
16:32:20.148 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0019
16:32:20.149 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0019
16:32:20.157 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0001
16:32:20.159 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0001
16:32:20.160 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0006
16:32:20.161 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0006
16:32:20.162 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0008
16:32:20.163 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0008
16:32:20.164 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 000F
16:32:20.164 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 000F
16:32:20.165 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0101
16:32:20.166 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0101
16:32:20.167 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0102
16:32:20.167 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0102
16:32:20.168 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0201
16:32:20.169 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0201
16:32:20.170 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0202
16:32:20.170 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0202
16:32:20.171 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0300
16:32:20.172 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0300
16:32:20.172 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0400
16:32:20.173 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0400
16:32:20.174 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0402
16:32:20.175 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0402
16:32:20.175 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0403
16:32:20.176 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0403
16:32:20.177 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0405
16:32:20.177 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0405
16:32:20.178 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0406
16:32:20.179 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0406
16:32:20.180 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0500
16:32:20.180 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0500
16:32:20.181 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0502
16:32:20.182 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0502
16:32:20.183 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0702
16:32:20.183 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0702
16:32:20.184 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0B04
16:32:20.185 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0B04
16:32:20.189 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0005
16:32:20.189 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0005
16:32:20.190 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0006
16:32:20.191 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0006
16:32:20.191 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0008
16:32:20.192 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0008
16:32:20.193 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0402
16:32:20.193 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0402
16:32:20.194 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0000
16:32:20.195 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0000
16:32:20.195 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported client cluster 0019
16:32:20.196 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding client cluster 0019
16:32:20.197 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Adding supported server cluster 0000
16:32:20.197 [DEBUG] [ystems.zigbee.internal.ClusterMatcher] - ClusterMatcher adding server cluster 0000
16:32:20.198 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_11, PanID=12079, EPanId=0E808604AD45A73E
16:32:20.199 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - Link key initialise 5A6967426565416C6C69616E63653039
16:32:20.200 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - Network key initialise 7CE05442D8F65F4611C3368884CB636C
16:32:20.200 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
16:32:20.209 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.226 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.245 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSetConcentratorResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.245 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConcentratorResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.247 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager startup: reinitialize=true, networkState=INITIALISING
16:32:20.249 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - Data store:  Clearing all nodes.
16:32:20.416 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 000D6F0011FE5D60: ZigBee removing network state complete
16:32:20.418 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Startup - reinitialize=true
16:32:20.419 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Adding Endpoint: ProfileID=0104, DeviceID=0050
16:32:20.421 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Adding Endpoint: Input Clusters   0000
16:32:20.422 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Adding Endpoint: Output Clusters  0000
16:32:20.432 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspAddEndpointResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.444 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspAddEndpointResponse [networkId=0, status=EZSP_SUCCESS]
16:32:20.455 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspNetworkInitResponse [networkId=0, status=EMBER_SUCCESS]
16:32:20.455 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspNetworkInitResponse [networkId=0, status=EMBER_SUCCESS]
16:32:20.460 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspStackStatusHandler [networkId=0, status=EMBER_NETWORK_UP]
16:32:20.461 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to true, initialised=false, networkStateUp=false
16:32:20.462 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to true ignored.
16:32:20.476 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetCurrentSecurityStateResponse [networkId=0, status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY, EMBER_GLOBAL_LINK_KEY], trustCenterLongAddress=000D6F0011FE5D60]]
16:32:20.476 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [networkId=0, status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY, EMBER_GLOBAL_LINK_KEY], trustCenterLongAddress=000D6F0011FE5D60]]
16:32:20.498 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP networkStateResponse EMBER_JOINED_NETWORK
16:32:20.499 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Reinitialising Ember NCP network as COORDINATOR
16:32:20.501 [DEBUG] [r.internal.EmberNetworkInitialisation] - Initialising Ember network with configuration EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]
16:32:20.520 [DEBUG] [r.internal.EmberNetworkInitialisation] - Ember network initialisation: Leaving current network. Network status=EMBER_JOINED_NETWORK
16:32:20.541 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspLeaveNetworkResponse [networkId=0, status=EMBER_SUCCESS]
16:32:20.542 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspLeaveNetworkResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.467 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspStackStatusHandler [networkId=0, status=EMBER_NETWORK_DOWN]
16:32:21.468 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false, initialised=false, networkStateUp=false
16:32:21.469 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false ignored.
16:32:21.621 [DEBUG] [r.internal.EmberNetworkInitialisation] - Ember network initialisation: Network leave confirmed
16:32:21.644 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspClearKeyTableResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.644 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspClearKeyTableResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.667 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.667 [DEBUG] [r.internal.EmberNetworkInitialisation] - EzspGetNetworkParametersResponse [networkId=0, status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.669 [DEBUG] [r.internal.EmberNetworkInitialisation] - Error during retrieval of network parameters: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.693 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetInitialSecurityStateResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.693 [DEBUG] [r.internal.EmberNetworkInitialisation] - EzspSetInitialSecurityStateResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.722 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspFormNetworkResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.722 [DEBUG] [r.internal.EmberNetworkInitialisation] - EzspFormNetworkResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.727 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspStackStatusHandler [networkId=0, status=EMBER_NETWORK_UP]
16:32:21.728 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to true, initialised=false, networkStateUp=false
16:32:21.729 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to true ignored.
16:32:21.744 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.764 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP networkState after online wait EMBER_JOINED_NETWORK
16:32:21.787 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetCurrentSecurityStateResponse [networkId=0, status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY, EMBER_GLOBAL_LINK_KEY], trustCenterLongAddress=000D6F0011FE5D60]]
16:32:21.787 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [networkId=0, status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY, EMBER_GLOBAL_LINK_KEY], trustCenterLongAddress=000D6F0011FE5D60]]
16:32:21.789 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Current Security State = EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY, EMBER_GLOBAL_LINK_KEY], trustCenterLongAddress=000D6F0011FE5D60]
16:32:21.807 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetRadioPowerResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.830 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNodeIdResponse [networkId=0, nodeId=0000]
16:32:21.830 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Startup complete. NWK Address = 0000, State = EMBER_JOINED_NETWORK
16:32:21.831 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to true, initialised=true, networkStateUp=false
16:32:21.832 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State up running
16:32:21.850 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendManyToOneRouteRequestResponse [networkId=0, status=EMBER_SUCCESS]
16:32:21.872 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.894 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=255]
16:32:21.894 [DEBUG] [tsystems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [networkId=0, status=EZSP_SUCCESS, value=255]
16:32:21.895 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to ONLINE
16:32:21.896 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Network state ONLINE: Process running. 1 Nodes in network.
16:32:21.898 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Adding local node to network, NWK=0000
16:32:21.899 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Permit join to FFFC/0 for 0 seconds.
16:32:21.900 [DEBUG] [ee.transaction.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]]
16:32:21.901 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:32:21.903 [DEBUG] [.transaction.ZigBeeTransactionManager] - FFFC/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=2, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
16:32:21.904 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:32:21.905 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]
16:32:21.907 [DEBUG] [rtsystems.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]
16:32:21.909 [DEBUG] [.transaction.ZigBeeTransactionManager] - 000D6F0011FE5D60: Creating new Transaction Queue
16:32:21.910 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
16:32:21.912 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=--, permitDuration=0, tcSignificance=true]]
16:32:21.913 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
16:32:21.914 [DEBUG] [.transaction.ZigBeeTransactionManager] - 0000/0: Sending ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=3, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
16:32:21.914 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
16:32:21.916 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]
16:32:21.919 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNodeIdResponse [networkId=0, nodeId=0000]
16:32:21.919 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager transport state updated to ONLINE
16:32:21.920 [DEBUG] [rtsystems.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]
16:32:21.922 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Startup
16:32:21.923 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - Network discovery task: starting
16:32:21.925 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - NWK Discovery for 0000 scheduling node discovery
16:32:21.927 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Network state ONLINE: Notifying node 000D6F0011FE5D60 [0000]
16:32:21.927 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - NWK Discovery for 0000 starting node discovery
16:32:21.931 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
16:32:21.931 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - 000D6F0011FE5D60: NodeDescriptor passed to Ember NCP NodeDescriptor [apsFlags=0, bufferSize=82, complexDescriptorAvailable=false, manufacturerCode=ABCD, logicalType=COORDINATOR, serverCapabilities=[], incomingTransferSize=128, outgoingTransferSize=128, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[FULL_FUNCTION_DEVICE, RECEIVER_ON_WHEN_IDLE, ALTERNATIVE_PAN, MAINS_POWER], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false, stackCompliance=0]
16:32:21.933 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 000D6F0011FE5D60: Data store: Deferring write for 250ms.
16:32:21.933 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - 000D6F0011FE5D60: networkStateUpdated called with state=ONLINE
16:32:21.937 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update
16:32:21.937 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=0, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=--, nwkAddrOfInterest=0000, requestType=1, startIndex=0]]
16:32:21.947 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=0/3
16:32:21.947 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - 000D6F0011FE5D60: DISCOVERY Extension: Adding discoverer for node
16:32:21.948 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:21.949 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: created discoverer
16:32:21.954 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - ZigBee initialise done. channel=CHANNEL_11, PanId=12079  EPanId=0E808604AD45A73E
16:32:21.955 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: start discovery
16:32:21.955 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: starting new tasks []
16:32:21.956 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: has no new tasks to perform
16:32:21.964 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=1, sleepy=0/3
16:32:21.975 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendBroadcastResponse [networkId=0, status=EMBER_SUCCESS, sequence=6B]
16:32:21.981 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0000, clusterId=0036, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6B], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=00 00 01]
16:32:21.993 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [networkId=0, status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0E808604AD45A73E, panId=2F2F, radioTxPower=-1, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0000, nwkUpdateId=0, channels=07FFF800]]
16:32:22.015 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=6C]
16:32:22.037 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0036, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6C], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=01 00 01]
16:32:22.038 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=6C, rssi=0, lqi=FF, payload=01 00 01]
16:32:22.041 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node update. NWK Address=NULL
16:32:22.042 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 000D6F0011FE5D60: Node state updated FROM UNKNOWN TO ONLINE
16:32:22.045 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 000D6F0011FE5D60: Data store: Deferring write for 250ms.
16:32:22.045 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
16:32:22.045 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]
16:32:22.045 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - 000D6F0011FE5D60: DISCOVERY Extension: Creating new discoverer for node
16:32:22.047 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]
16:32:22.050 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: stopped
16:32:22.050 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8036, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6D], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=01 00]
16:32:22.051 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=8036, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=6D, rssi=0, lqi=FF, payload=01 00]
16:32:22.051 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: created discoverer
16:32:22.052 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node update. NWK Address=NULL
16:32:22.054 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node 0000 is not updated from ZigBeeNode [state=ONLINE, IEEE=000D6F0011FE5D60, NWK=----, endpoints=[]]
16:32:22.054 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: start discovery
16:32:22.055 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
16:32:22.055 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: starting new tasks []
16:32:22.056 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=01, status=SUCCESS]
16:32:22.056 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 000D6F0011FE5D60: Node SVC Discovery: has no new tasks to perform
16:32:22.057 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=0000, apsFrame=EmberApsFrame [profileId=0000, clusterId=0036, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6C], messageTag=01, status=EMBER_SUCCESS, messageContents=]
16:32:22.059 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=01 state=RX_ACK
16:32:22.060 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=01, state=RX_ACK, outstanding=2
16:32:22.064 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=152, state=COMPLETE, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=01, permitDuration=0, tcSignificance=true]]
16:32:22.065 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
16:32:22.067 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: transactionComplete, state=COMPLETE, outstanding=0
16:32:22.069 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
16:32:22.071 [DEBUG] [.transaction.ZigBeeTransactionManager] - 0000/0: Sending ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=134, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=02, nwkAddrOfInterest=0000, requestType=1, startIndex=0]]
16:32:22.073 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
16:32:22.076 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=02, nwkAddrOfInterest=0000, requestType=1, startIndex=0]
16:32:22.078 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0001, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=02, rssi=--, lqi=--, payload=02 00 00 01 00]
16:32:22.080 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=0000, TID=01, event=RX_ACK, state=COMPLETE
16:32:22.093 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=6E]
16:32:22.115 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0001, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6E], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=02 00 00 01 00]
16:32:22.116 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0001, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=6E, rssi=0, lqi=FF, payload=02 00 00 01 00]
16:32:22.118 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node update. NWK Address=NULL
16:32:22.119 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node 0000 is not updated from ZigBeeNode [state=ONLINE, IEEE=000D6F0011FE5D60, NWK=----, endpoints=[]]
16:32:22.120 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=02, nwkAddrOfInterest=0000, requestType=1, startIndex=0]
16:32:22.121 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=02, nwkAddrOfInterest=0000, requestType=1, startIndex=0]
16:32:22.123 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8001, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6F], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=02 00 60 5D FE 11 00 6F 0D 00 00 00 00]
16:32:22.124 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=8001, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=6F, rssi=0, lqi=FF, payload=02 00 60 5D FE 11 00 6F 0D 00 00 00 00]
16:32:22.125 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node update. NWK Address=NULL
16:32:22.126 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 000D6F0011FE5D60: Node 0000 is not updated from ZigBeeNode [state=ONLINE, IEEE=000D6F0011FE5D60, NWK=----, endpoints=[]]
16:32:22.128 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0000/0 -> 0000/0, cluster=8001, TID=02, status=SUCCESS, ieeeAddrRemoteDev=000D6F0011FE5D60, nwkAddrRemoteDev=0000, startIndex=null, nwkAddrAssocDevList=[]]
16:32:22.129 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: IeeeAddressResponse [0000/0 -> 0000/0, cluster=8001, TID=02, status=SUCCESS, ieeeAddrRemoteDev=000D6F0011FE5D60, nwkAddrRemoteDev=0000, startIndex=null, nwkAddrAssocDevList=[]]
16:32:22.132 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=0000, apsFrame=EmberApsFrame [profileId=0000, clusterId=0001, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6E], messageTag=02, status=EMBER_SUCCESS, messageContents=]
16:32:22.132 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - NWK Discovery for 0000 IeeeAddressRequest returned IeeeAddressResponse [0000/0 -> 0000/0, cluster=8001, TID=02, status=SUCCESS, ieeeAddrRemoteDev=000D6F0011FE5D60, nwkAddrRemoteDev=0000, startIndex=null, nwkAddrAssocDevList=[]]
16:32:22.132 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=000D6F0011FE5D60 queueTime=196, state=COMPLETE, sendCnt=1, command=IeeeAddressRequest [0000/0 -> 0000/0, cluster=0001, TID=02, nwkAddrOfInterest=0000, requestType=1, startIndex=0]]
16:32:22.133 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
16:32:22.133 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=02 state=RX_ACK
16:32:22.134 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 000D6F0011FE5D60: transactionComplete, state=COMPLETE, outstanding=0
16:32:22.134 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=02, state=RX_ACK, outstanding=1
16:32:22.135 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=0, sleepy=0/3
16:32:22.296 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 000D6F0011FE5D60: Data store: Writing node.
16:32:22.313 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 000D6F0011FE5D60: ZigBee saving network state complete.
16:32:22.884 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=FFFC, apsFrame=EmberApsFrame [profileId=0000, clusterId=0036, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=6B], messageTag=00, status=EMBER_SUCCESS, messageContents=]
16:32:22.885 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
16:32:22.887 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1
16:32:22.891 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=991, state=COMPLETE, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=00, permitDuration=0, tcSignificance=true]]
16:32:22.892 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:32:22.893 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=COMPLETE, outstanding=0
16:32:22.895 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:32:22.896 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=FFFC, TID=00, event=RX_ACK, state=COMPLETE