Hi,

I bought SLZB-06M (https://smlight.tech/product/slzb-06/) which supports v12 of EZSP.
Currently supported in library is v7 but I managed to update the protocol and make it talk.

My current problem is that after resetting network no light bulb joins the network that I created.
Would anyone willing to help me with this case? What is needed to be provided?

I run debug with --zbd 3 --port=com5 --reset arguments.
Here are logs from successful initiation, I will really appreciate any help or tips.

SetUpdatePeriod
AddExtension
Initializing network manager
[00:19:40 DBG] Network state is updated to INITIALISING
[00:19:40 DBG] 70AC08FFFEBF0831: Data store: Node was restored.
[00:19:40 DBG] 70AC08FFFEBF0831: Updating Node 0
[00:19:40 DBG] EZSP Dongle: Initialize with protocol ASH2.
[00:19:40 DBG] Opening port com5 at 115200 baud with FLOWCONTROL_OUT_XONOFF
[00:19:40 DBG] ASH: Connect
[00:19:40 DBG] AshFrameHandler parser task started
[00:19:40 DBG] ASH: Reconnect
[00:19:40 DBG] --> TX ASH frame: AshFrameRst []
[00:19:40 DBG] TX EZSP: EzspVersionRequest [desiredProtocolVersion=12]
[00:19:40 DBG] ASH: TX EZSP queue size: 1
[00:19:41 DBG] <-- RX ASH frame: AshFrameRstAck [version=2, resetCode=11, Reset: Software]
[00:19:43 DBG] ASH: Connected
[00:19:43 DBG] Ember: Link State change to True, initialised=False, networkStateUp=False
[00:19:43 DBG] Ember: Link State change to True ignored.
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=01 00 00 0C]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=01 80 00 0C 02 10 73]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=01 00 00 0C]
[00:19:43 DBG] EzspVersionResponse [protocolVersion=12, stackType=2, stackVersion=0x7310]
[00:19:43 DBG] RX EZSP: EzspVersionResponse [protocolVersion=12, stackType=2, stackVersion=0x7310]
[00:19:43 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetStandaloneBootloaderVersionPlatMicroPhyRequest
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=02 00 01 91 00]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=02 80 01 91 00 03 1A 04 18 0F]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=02 00 01 91 00]
[00:19:43 DBG] RX EZSP: EzspGetStandaloneBootloaderVersionPlatMicroPhyResponse [bootloaderVersion=0x1A03, nodePlat=4, nodeMicro=24, nodePhy=15]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=03 00 01 52 00 1A]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=03 80 01 52 00 00 C8 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=03 00 01 52 00 1A]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=200]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=200]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=04 00 01 52 00 0D]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=04 80 01 52 00 00 05 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=04 00 01 52 00 0D]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=05 00 01 52 00 05]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=05 80 01 52 00 00 10 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=05 00 01 52 00 05]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=06 00 01 52 00 19]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=06 80 01 52 00 00 00 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=06 00 01 52 00 19]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=07 00 01 52 00 0C]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=07 80 01 52 00 00 00 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=07 00 01 52 00 0C]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=08 00 01 52 00 12]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=08 80 01 52 00 00 B8 0B]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=08 00 01 52 00 12]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=09 00 01 52 00 10]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=09 80 01 52 00 00 1E 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=09 00 01 52 00 10]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=0A 00 01 52 00 17]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=0A 80 01 52 00 00 00 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=0A 00 01 52 00 17]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=0B 00 01 52 00 2D]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=0B 80 01 52 00 00 01 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=0B 00 01 52 00 2D]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=0C 00 01 52 00 1E]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=0C 80 01 52 00 00 0C 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=0C 00 01 52 00 1E]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=12]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=12]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=0D 00 01 52 00 2A]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=0D 80 01 52 00 00 00 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=0D 00 01 52 00 2A]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=0E 00 01 52 00 11]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=0E 80 01 52 00 00 20 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=0E 00 01 52 00 11]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=0F 00 01 52 00 03]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=0F 80 01 52 00 00 14 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=0F 00 01 52 00 03]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=20]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=20]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=10 00 01 52 00 2B]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=10 80 01 52 00 00 0F 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=10 00 01 52 00 2B]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=11 00 01 52 00 02]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=11 80 01 52 00 00 1A 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=11 00 01 52 00 02]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=26]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=26]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=12 00 01 52 00 01]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=12 80 01 52 00 00 FF 00]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=12 00 01 52 00 01]
[00:19:43 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
[00:19:43 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:43 DBG] Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 200
[00:19:43 DBG] Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5
[00:19:43 DBG] Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 16
[00:19:43 DBG] Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 0
[00:19:43 DBG] Configuration state EZSP_CONFIG_STACK_PROFILE = 0
[00:19:43 DBG] Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 3000
[00:19:43 DBG] Configuration state EZSP_CONFIG_MAX_HOPS = 30
[00:19:43 DBG] Configuration state EZSP_CONFIG_TX_POWER_MODE = 0
[00:19:43 DBG] Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1
[00:19:43 DBG] Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 12
[00:19:43 DBG] Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 0
[00:19:43 DBG] Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 32
[00:19:43 DBG] Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 20
[00:19:43 DBG] Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15
[00:19:43 DBG] Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 26
[00:19:43 DBG] Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255
[00:19:43 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=13 00 01 56 00 05]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=13 80 01 56 00 00 50]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=13 00 01 56 00 05]
[00:19:43 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
[00:19:43 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=14 00 01 56 00 00]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=14 80 01 56 00 00 03]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=14 00 01 56 00 00]
[00:19:43 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_ALL_JOINS_AND_REJOINS]
[00:19:43 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_ALL_JOINS_AND_REJOINS]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=15 00 01 56 00 04]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=15 80 01 56 00 00 40]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=15 00 01 56 00 04]
[00:19:43 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
[00:19:43 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_APP_KEY_REQUEST_POLICY]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=16 00 01 56 00 06]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=16 80 01 56 00 00 60]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=16 00 01 56 00 06]
[00:19:43 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
[00:19:43 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:43 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=17 00 01 56 00 01]
[00:19:43 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=17 80 01 56 00 00 10]
[00:19:43 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=17 00 01 56 00 01]
[00:19:43 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION]
[00:19:43 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION]
[00:19:43 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:43 DBG] Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS
[00:19:43 DBG] Policy state EZSP_TRUST_CENTER_POLICY = EZSP_DISALLOW_ALL_JOINS_AND_REJOINS
[00:19:43 DBG] Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK
[00:19:43 DBG] Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_DENY_APP_KEY_REQUESTS
[00:19:43 DBG] Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION
[00:19:43 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16]
[00:19:43 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16]
[00:19:43 DBG] ASH: TX EZSP queue size: 1
[00:19:43 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=18 00 01 53 00 1A 10 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=18 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=18 00 01 53 00 1A 10 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=19 00 01 53 00 0D 05 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=19 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=19 00 01 53 00 0D 05 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=1A 00 01 53 00 05 08 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=1A 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=1A 00 01 53 00 05 08 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=1B 00 01 53 00 19 02 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=1B 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=1B 00 01 53 00 19 02 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=1C 00 01 53 00 0C 02 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=1C 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=1C 00 01 53 00 0C 02 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=1D 00 01 53 00 12 00 1E]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=1D 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=1D 00 01 53 00 12 00 1E]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=1E 00 01 53 00 10 1E 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=1E 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=1E 00 01 53 00 10 1E 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=0]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=0]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=1F 00 01 53 00 17 00 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=1F 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=1F 00 01 53 00 17 00 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=20 00 01 53 00 2D 01 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=20 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=20 00 01 53 00 2D 01 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=21 00 01 53 00 1E 04 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=21 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=21 00 01 53 00 1E 04 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=22 00 01 53 00 2A 01 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=22 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=22 00 01 53 00 2A 01 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=23 00 01 53 00 11 10 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=23 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=23 00 01 53 00 11 10 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=24 00 01 53 00 03 0A 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=24 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=24 00 01 53 00 03 0A 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=25 00 01 53 00 2B 0F 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=25 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=25 00 01 53 00 2B 0F 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=26 00 01 53 00 02 10 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=26 80 01 53 00 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=26 00 01 53 00 02 10 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:44 DBG] EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255]
[00:19:44 DBG] TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=27 00 01 53 00 01 FF 00]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=27 80 01 53 00 37]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=27 00 01 53 00 01 FF 00]
[00:19:44 DBG] RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_ID]
[00:19:44 DBG] EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_ID]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=28 00 01 52 00 1A]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=28 80 01 52 00 00 10 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=28 00 01 52 00 1A]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=29 00 01 52 00 0D]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=29 80 01 52 00 00 05 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=29 00 01 52 00 0D]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=2A 00 01 52 00 05]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=2A 80 01 52 00 00 08 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=2A 00 01 52 00 05]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=2B 00 01 52 00 19]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=2B 80 01 52 00 00 02 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=2B 00 01 52 00 19]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=2C 00 01 52 00 0C]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=2C 80 01 52 00 00 02 00]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=2C 00 01 52 00 0C]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=2D 00 01 52 00 12]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=2D 80 01 52 00 00 00 1E]
[00:19:44 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=2D 00 01 52 00 12]
[00:19:44 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680]
[00:19:44 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680]
[00:19:44 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:44 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS]
[00:19:44 DBG] ASH: TX EZSP queue size: 1
[00:19:44 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=2E 00 01 52 00 10]
[00:19:44 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=2E 80 01 52 00 00 1E 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=2E 00 01 52 00 10]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=2F 00 01 52 00 17]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=2F 80 01 52 00 00 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=2F 00 01 52 00 17]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=30 00 01 52 00 2D]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=30 80 01 52 00 00 01 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=30 00 01 52 00 2D]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=31 00 01 52 00 1E]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=31 80 01 52 00 00 04 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=31 00 01 52 00 1E]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=32 00 01 52 00 2A]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=32 80 01 52 00 00 01 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=32 00 01 52 00 2A]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=33 00 01 52 00 11]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=33 80 01 52 00 00 10 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=33 00 01 52 00 11]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=34 00 01 52 00 03]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=34 80 01 52 00 00 0A 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=34 00 01 52 00 03]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=35 00 01 52 00 2B]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=35 80 01 52 00 00 0F 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=35 00 01 52 00 2B]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=36 00 01 52 00 02]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=36 80 01 52 00 00 10 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=36 00 01 52 00 02]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=37 00 01 52 00 01]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=37 80 01 52 00 00 FF 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=37 00 01 52 00 01]
[00:19:45 DBG] RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
[00:19:45 DBG] EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:45 DBG] Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16
[00:19:45 DBG] Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5
[00:19:45 DBG] Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8
[00:19:45 DBG] Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2
[00:19:45 DBG] Configuration state EZSP_CONFIG_STACK_PROFILE = 2
[00:19:45 DBG] Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680
[00:19:45 DBG] Configuration state EZSP_CONFIG_MAX_HOPS = 30
[00:19:45 DBG] Configuration state EZSP_CONFIG_TX_POWER_MODE = 0
[00:19:45 DBG] Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1
[00:19:45 DBG] Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4
[00:19:45 DBG] Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1
[00:19:45 DBG] Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16
[00:19:45 DBG] Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10
[00:19:45 DBG] Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15
[00:19:45 DBG] Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16
[00:19:45 DBG] Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255
[00:19:45 DBG] TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=38 00 01 55 00 05 50]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=38 80 01 55 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=38 00 01 55 00 05 50]
[00:19:45 DBG] RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=39 00 01 55 00 00 01]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=39 80 01 55 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=39 00 01 55 00 00 01]
[00:19:45 DBG] RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspSetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=3A 00 01 55 00 04 40]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=3A 80 01 55 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=3A 00 01 55 00 04 40]
[00:19:45 DBG] RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspSetPolicyRequest [policyId=EZSP_APP_KEY_REQUEST_POLICY, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=3B 00 01 55 00 06 60]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=3B 80 01 55 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=3B 00 01 55 00 06 60]
[00:19:45 DBG] RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspSetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=3C 00 01 55 00 01 12]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=3C 80 01 55 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=3C 00 01 55 00 01 12]
[00:19:45 DBG] RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] EzspSetPolicyResponse [status=EZSP_SUCCESS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=3D 00 01 56 00 05]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=3D 80 01 56 00 00 50]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=3D 00 01 56 00 05]
[00:19:45 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
[00:19:45 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=3E 00 01 56 00 00]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=3E 80 01 56 00 00 01]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=3E 00 01 56 00 00]
[00:19:45 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
[00:19:45 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=3F 00 01 56 00 04]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=3F 80 01 56 00 00 40]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=3F 00 01 56 00 04]
[00:19:45 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
[00:19:45 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_APP_KEY_REQUEST_POLICY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=40 00 01 56 00 06]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=40 80 01 56 00 00 60]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=40 00 01 56 00 06]
[00:19:45 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
[00:19:45 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:45 DBG] TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=41 00 01 56 00 01]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=41 80 01 56 00 00 12]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=False, data=41 00 01 56 00 01]
[00:19:45 DBG] RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS]
[00:19:45 DBG] EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:45 DBG] Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS
[00:19:45 DBG] Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS
[00:19:45 DBG] Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK
[00:19:45 DBG] Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_DENY_APP_KEY_REQUESTS
[00:19:45 DBG] Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS
[00:19:45 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNetworkParametersRequest
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=42 00 01 28 00]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=42 80 01 28 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=False, data=42 00 01 28 00]
[00:19:45 DBG] Ember initial network parameters are EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]
[00:19:45 DBG] RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]]
[00:19:45 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetEui64Request
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=43 00 01 26 00]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=43 80 01 26 00 31 08 BF FE FF 08 AC 70]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=False, data=43 00 01 26 00]
[00:19:45 DBG] RX EZSP: EzspGetEui64Response [eui64=70AC08FFFEBF0831]
[00:19:45 DBG] Ember local IEEE Address is 70AC08FFFEBF0831
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:45 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNetworkParametersRequest
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=44 00 01 28 00]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=44 80 01 28 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=False, data=44 00 01 28 00]
[00:19:45 DBG] EZSP Dongle: initialize done
[00:19:45Initializing done
 DBG] RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]]
[00:19:45 DBG] Adding supported client cluster 6
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:45 DBG] ClusterMatcher starting
[00:19:45 DBG] ClusterMatcher adding client cluster 6
[00:19:45 DBG] Adding supported client cluster 768
[00:19:45 DBG] ClusterMatcher adding client cluster 768
[00:19:45 DBG] Adding supported client cluster 1280
[00:19:45 DBG] ClusterMatcher adding client cluster 1280
PAN ID           = 0
Extended PAN ID  = 0000000000000000
Channel          = CHANNEL_00
[00:19:45 DBG] TX EZSP: EzspGetKeyRequest [keyType=EMBER_CURRENT_NETWORK_KEY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=45 00 01 6A 00 03]
[00:19:45 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=45 80 01 6A 00 00 03 00 03 47 5C 19 84 B4 ED 6B 43 44 1C 5A 41 35 A2 EC 9B 00 10 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00]
[00:19:45 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=False, data=45 00 01 6A 00 03]
[00:19:45 DBG] RX EZSP: EzspGetKeyResponse [status=EMBER_SUCCESS, keyStruct=EmberKeyStruct [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberKeyStructBitmask], type=EMBER_CURRENT_NETWORK_KEY, key=EmberKeyData [contents=0x47 0x5C 0x19 0x84 0xB4 0xED 0x6B 0x43 0x44 0x1C 0x5A 0x41 0x35 0xA2 0xEC 0x9B], outgoingFrameCounter=0x00001000, incomingFrameCounter=0x00000000, sequenceNumber=0x00, partnerEUI64=0000000000000000]]
[00:19:45 DBG] EzspGetKeyResponse [status=EMBER_SUCCESS, keyStruct=EmberKeyStruct [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberKeyStructBitmask], type=EMBER_CURRENT_NETWORK_KEY, key=EmberKeyData [contents=0x47 0x5C 0x19 0x84 0xB4 0xED 0x6B 0x43 0x44 0x1C 0x5A 0x41 0x35 0xA2 0xEC 0x9B], outgoingFrameCounter=0x00001000, incomingFrameCounter=0x00000000, sequenceNumber=0x00, partnerEUI64=0000000000000000]]
[00:19:45 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
Network Key      = 475c1984b4ed6b43441c5a4135a2ec9b
[00:19:45 DBG] TX EZSP: EzspGetKeyRequest [keyType=EMBER_TRUST_CENTER_LINK_KEY]
[00:19:45 DBG] ASH: TX EZSP queue size: 1
[00:19:45 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=46 00 01 6A 00 01]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=46 80 01 6A 00 B2 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=False, data=46 00 01 6A 00 01]
[00:19:46 DBG] RX EZSP: EzspGetKeyResponse [status=EMBER_KEY_INVALID, keyStruct=EmberKeyStruct [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberKeyStructBitmask], type=EMBER_TRUST_CENTER_LINK_KEY, key=EmberKeyData [contents=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00], outgoingFrameCounter=0x00000000, incomingFrameCounter=0x00000000, sequenceNumber=0x00, partnerEUI64=0000000000000000]]
[00:19:46 DBG] EzspGetKeyResponse [status=EMBER_KEY_INVALID, keyStruct=EmberKeyStruct [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberKeyStructBitmask], type=EMBER_TRUST_CENTER_LINK_KEY, key=EmberKeyData [contents=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00], outgoingFrameCounter=0x00000000, incomingFrameCounter=0x00000000, sequenceNumber=0x00, partnerEUI64=0000000000000000]]
Link Key         =
resetingNetwork
[00:19:46*** Resetting network
  * PAN ID           = 1
  * Extended PAN ID  = 0000000000000000
   * Channel          = CHANNEL_18
  * Network Key      = 690b3008e8aed441b350b7aa9af2249c
  * Link Key         = 5a6967426565416c6c69616e63653039
DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
Starting Up
[00:19:46 DBG] ZigBeeNetworkManager startup: reinitialize=True, networkState=INITIALISING
[00:19:46 DBG] EZSP Dongle: Startup - reinitialize=True
[00:19:46 DBG] EZSP Adding Endpoint: ProfileID=0104, DeviceID=0050
[00:19:46 DBG] EZSP Adding Endpoint: Input Clusters   0
[00:19:46 DBG] EZSP Adding Endpoint: Output Clusters  0
[00:19:46 DBG] TX EZSP: EzspAddEndpointRequest [endpoint=1, profileId=0x0104, deviceId=0x0050, appFlags=0, inputClusterList=0x0000, outputClusterList=0x0000]
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=47 00 01 02 00 01 04 01 50 00 00 01 01 00 00 00 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=False, data=47 80 01 02 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=False, data=47 00 01 02 00 01 04 01 50 00 00 01 01 00 00 00 00]
[00:19:46 DBG] RX EZSP: EzspAddEndpointResponse [status=EZSP_SUCCESS]
[00:19:46 DBG] EzspAddEndpointResponse [status=EZSP_SUCCESS]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspNetworkInitRequest
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=False, data=48 00 01 17 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=False, data=48 84 01 17 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=False, data=48 00 01 17 00]
[00:19:46 DBG] RX EZSP: EzspNetworkInitResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] EzspNetworkInitResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetCurrentSecurityStateRequest
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=False, data=48 90 01 19 00 90]
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] RX EZSP: EzspStackStatusHandler [status=EMBER_NETWORK_UP]
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=False, data=49 00 01 69 00]
[00:19:46 DBG] Ember: Link State change to True, initialised=False, networkStateUp=False
[00:19:46 DBG] Ember: Link State change to True ignored.
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=False, data=49 80 01 69 00 00 74 00 31 08 BF FE FF 08 AC 70]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=1, reTx=False, data=49 00 01 69 00]
[00:19:46 DBG] EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberCurrentSecurityBitmask], trustCenterLongAddress=70AC08FFFEBF0831]]
[00:19:46 DBG] RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberCurrentSecurityBitmask], trustCenterLongAddress=70AC08FFFEBF0831]]
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspNetworkStateRequest
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=4A 00 01 18 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=4A 80 01 18 00 02]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=2, reTx=False, data=4A 00 01 18 00]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:46 DBG] EZSP networkStateResponse EMBER_JOINED_NETWORK
[00:19:46 DBG] Reinitialising Ember NCP network as COORDINATOR
[00:19:46 DBG] Initialising Ember network with configuration EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0001, radioTxPower=0, radioChannel=18, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspNetworkStateRequest
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=False, data=4B 00 01 18 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=False, data=4B 80 01 18 00 02]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=3, reTx=False, data=4B 00 01 18 00]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:46 DBG] EzspNetworkStateResponse [status=EMBER_JOINED_NETWORK]
[00:19:46 DBG] EZSP networkStateResponse EMBER_JOINED_NETWORK
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspLeaveNetworkRequest
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=4C 00 01 20 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=False, data=4C 80 01 20 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=4, reTx=False, data=4C 00 01 20 00]
[00:19:46 DBG] RX EZSP: EzspLeaveNetworkResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] EzspLeaveNetworkResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:46 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspClearKeyTableRequest
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=False, data=4D 00 01 B1 00]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=False, data=4D 80 01 B1 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=5, reTx=False, data=4D 00 01 B1 00]
[00:19:46 DBG] RX EZSP: EzspClearKeyTableResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] EzspClearKeyTableResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:46 DBG] TX EZSP: EzspStartScanRequest [scanType=EZSP_ENERGY_SCAN, channelMask=0x07FFF800, duration=1]
[00:19:46 DBG] ASH: TX EZSP queue size: 1
[00:19:46 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=4E 00 01 1A 00 00 00 F8 FF 07 01]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=4E 80 01 1A 00 00 00 00 00]
[00:19:46 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=6, reTx=False, data=4E 00 01 1A 00 00 00 F8 FF 07 01]
[00:19:46 DBG] RX EZSP: EzspStartScanResponse [status=EMBER_SUCCESS]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=False, data=4E 90 01 48 00 0B CB]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=11, maxRssiValue=-53]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=False, data=4E 90 01 48 00 0C C3]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=12, maxRssiValue=-61]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=False, data=4E 90 01 48 00 0D D5]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=13, maxRssiValue=-43]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=False, data=4E 90 01 48 00 0E C0]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=14, maxRssiValue=-64]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=False, data=4E 90 01 48 00 0F B7]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=15, maxRssiValue=-73]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=False, data=4E 90 01 48 00 10 B9]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=16, maxRssiValue=-71]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=4E 90 01 48 00 11 C6]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=17, maxRssiValue=-58]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=4E 90 01 48 00 12 AE]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=18, maxRssiValue=-82]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=False, data=4E 90 01 48 00 13 B5]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=19, maxRssiValue=-75]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=False, data=4E 90 01 48 00 14 C1]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=20, maxRssiValue=-63]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=False, data=4E 90 01 48 00 15 B9]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=21, maxRssiValue=-71]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:46 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=False, data=4E 90 01 48 00 16 AE]
[00:19:46 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=22, maxRssiValue=-82]
[00:19:46 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=False, data=4E 90 01 48 00 17 AF]
[00:19:47 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=23, maxRssiValue=-81]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=False, data=4E 90 01 48 00 18 AC]
[00:19:47 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=24, maxRssiValue=-84]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=False, data=4E 90 01 48 00 19 B3]
[00:19:47 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=25, maxRssiValue=-77]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=False, data=4E 94 01 48 00 1A BC]
[00:19:47 DBG] RX EZSP: EzspEnergyScanResultHandler [channel=26, maxRssiValue=-68]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=False, data=4E 90 01 1C 00 1A 00]
[00:19:47 DBG] RX EZSP: EzspScanCompleteHandler [channel=26, status=EMBER_SUCCESS]
[00:19:47 DBG] EzspScanCompleteHandler [channel=26, status=EMBER_SUCCESS]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:47 DBG] Energy scan reports quietest channel is 24
[00:19:47 DBG] TX EZSP: EzspStartScanRequest [scanType=EZSP_ACTIVE_SCAN, channelMask=0x07FFF800, duration=1]
[00:19:47 DBG] ASH: TX EZSP queue size: 1
[00:19:47 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=False, data=4F 00 01 1A 00 01 00 F8 FF 07 01]
[00:19:47 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=False, data=4F 80 01 1A 00 00 00 00 00]
[00:19:47 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=0, reTx=False, data=4F 00 01 1A 00 01 00 F8 FF 07 01]
[00:19:47 DBG] RX EZSP: EzspStartScanResponse [status=EMBER_SUCCESS]
[00:19:47 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=False, data=4F 90 01 1C 00 1A 00]
[00:19:48 DBG] RX EZSP: EzspScanCompleteHandler [channel=26, status=EMBER_SUCCESS]
[00:19:48 DBG] EzspScanCompleteHandler [channel=26, status=EMBER_SUCCESS]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:48 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNetworkParametersRequest
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=False, data=4F 90 01 19 00 91]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] RX EZSP: EzspStackStatusHandler [status=EMBER_NETWORK_DOWN]
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=False, data=50 00 01 28 00]
[00:19:48 DBG] Ember: Link State change to False, initialised=False, networkStateUp=False
[00:19:48 DBG] Ember: Link State change to False ignored.
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=False, data=50 80 01 28 00 93 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=3, reTx=False, data=50 00 01 28 00]
[00:19:48 DBG] RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]]
[00:19:48 DBG] EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:48 DBG] Error during retrieval of network parameters: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=EMBER_UNKNOWN_DEVICE, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=0x0000, radioTxPower=0, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00000000]]
[00:19:48 DBG] Created random PAN ID: 54906
[00:19:48 DBG] Created random Extended PAN ID: 18B9EF6D70A8A9C5
[00:19:48 DBG] TX EZSP: EzspSetInitialSecurityStateRequest [state=EmberInitialSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberInitialSecurityBitmask], preconfiguredKey=EmberKeyData [contents=0x5A 0x69 0x67 0x42 0x65 0x65 0x41 0x6C 0x6C 0x69 0x61 0x6E 0x63 0x65 0x30 0x39], networkKey=EmberKeyData [contents=0x69 0x0B 0x30 0x08 0xE8 0xAE 0xD4 0x41 0xB3 0x50 0xB7 0xAA 0x9A 0xF2 0x24 0x9C], networkKeySequenceNumber=0, preconfiguredTrustCenterEui64=0000000000000000]]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=False, data=51 00 01 68 00 04 0B 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39 69 0B 30 08 E8 AE D4 41 B3 50 B7 AA 9A F2 24 9C 00 00 00 00 00 00 00 00 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=False, data=51 80 01 68 00 00]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=4, reTx=False, data=51 00 01 68 00 04 0B 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39 69 0B 30 08 E8 AE D4 41 B3 50 B7 AA 9A F2 24 9C 00 00 00 00 00 00 00 00 00]
[00:19:48 DBG] RX EZSP: EzspSetInitialSecurityStateResponse [status=EMBER_SUCCESS]
[00:19:48 DBG] EzspSetInitialSecurityStateResponse [status=EMBER_SUCCESS]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:48 DBG] TX EZSP: EzspFormNetworkRequest [parameters=EmberNetworkParameters [extendedPanId=18B9EF6D70A8A9C5, panId=0xD67A, radioTxPower=0, radioChannel=18, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x00040000]]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=False, data=52 00 01 1E 00 C5 A9 A8 70 6D EF B9 18 7A D6 00 12 00 00 00 00 00 00 04 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=False, data=52 84 01 1E 00 00]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=5, reTx=False, data=52 00 01 1E 00 C5 A9 A8 70 6D EF B9 18 7A D6 00 12 00 00 00 00 00 00 04 00]
[00:19:48 DBG] RX EZSP: EzspFormNetworkResponse [status=EMBER_SUCCESS]
[00:19:48 DBG] EzspFormNetworkResponse [status=EMBER_SUCCESS]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:48 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNetworkParametersRequest
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=False, data=52 90 01 19 00 90]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] RX EZSP: EzspStackStatusHandler [status=EMBER_NETWORK_UP]
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=False, data=53 00 01 28 00]
[00:19:48 DBG] Ember: Link State change to True, initialised=False, networkStateUp=False
[00:19:48 DBG] Ember: Link State change to True ignored.
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=False, data=53 80 01 28 00 00 01 C5 A9 A8 70 6D EF B9 18 7A D6 00 12 00 00 00 00 00 F8 FF 07]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=7, reTx=False, data=53 00 01 28 00]
[00:19:48 DBG] RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=18B9EF6D70A8A9C5, panId=0xD67A, radioTxPower=0, radioChannel=18, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0x0000, nwkUpdateId=0, channels=0x07FFF800]]
[00:19:48 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspNetworkStateRequest
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=False, data=54 00 01 18 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=False, data=54 80 01 18 00 02]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=False, data=54 00 01 18 00]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:48 DBG] EZSP networkState after online wait EMBER_JOINED_NETWORK
[00:19:48 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetCurrentSecurityStateRequest
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=False, data=55 00 01 69 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=False, data=55 80 01 69 00 00 74 00 31 08 BF FE FF 08 AC 70]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=1, reTx=False, data=55 00 01 69 00]
[00:19:48 DBG] RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberCurrentSecurityBitmask], trustCenterLongAddress=70AC08FFFEBF0831]]
[00:19:48 DBG] EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberCurrentSecurityBitmask], trustCenterLongAddress=70AC08FFFEBF0831]]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:48 DBG] EZSP Current Security State = EmberCurrentSecurityState [bitmask=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberCurrentSecurityBitmask], trustCenterLongAddress=70AC08FFFEBF0831]
[00:19:48 DBG] TX EZSP: EzspSetRadioPowerRequest [power=0]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=False, data=56 00 01 99 00 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=False, data=56 80 01 99 00 00]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=2, reTx=False, data=56 00 01 99 00 00]
[00:19:48 DBG] RX EZSP: EzspSetRadioPowerResponse [status=EMBER_SUCCESS]
[00:19:48 DBG] TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNodeIdRequest
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=False, data=57 00 01 27 00]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=False, data=57 80 01 27 00 00 00]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=3, reTx=False, data=57 00 01 27 00]
[00:19:48 DBG] RX EZSP: EzspGetNodeIdResponse [nodeId=0x0000]
[00:19:48 DBG] EZSP Dongle: Startup complete. NWK Address = 0000, State = EMBER_JOINED_NETWORK
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
[00:19:48 DBG] Ember: Link State change to True, initialised=True, networkStateUp=False
[00:19:48 DBG] Network state is updated to ONLINE
[Checking startup
00:19:48 DBG] Ember: Link State up running
[00:19:48 INF] ZigBee console starting up ... [OK]
[00:19:48 DBG] Joining enabled...
TX EZSP: ZigBeeNet.Hardware.Ember.Ezsp.Command.EzspGetNodeIdRequest
[00:19:48 DBG] Permit join to 65532/0 for 0 seconds.
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=False, data=58 00 01 27 00]
[00:19:48 DBG] TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=54, transId=1, PermitDuration=0, TcSignificance=True]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=0, reTx=False, data=58 80 01 27 00 00 00]
[00:19:48 DBG] TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0, cluster=54, addressMode=Device, radius=31, apsCounter=1, payload=1 0 1]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=4, reTx=False, data=58 00 01 27 00]
[00:19:48 DBG] RX EZSP: EzspGetNodeIdResponse [nodeId=0x0000]
[00:19:48 DBG] ZigBeeNetworkManager transport state updated to ONLINE
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:48 DBG] TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=2, PermitDuration=0, TcSignificance=True]
[00:19:48 DBG] TX EZSP: EzspSendBroadcastRequest [destination=0xFFFC, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x01], radius=31, messageTag=0x00, messageContents=0x01 0x00 0x01]
[00:19:48 DBG] TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=31, apsCounter=2, payload=2 0 1]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0x0000, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x02], messageTag=0x00, messageContents=0x02 0x00 0x01]
[00:19:48 DBG] DISCOVERY Extension: Startup
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=False, data=01 00 01 36 00 FC FF 00 00 36 00 00 00 40 11 00 00 01 1F 00 03 01 00 01]
[00:19:48 DBG] ASH: TX EZSP queue size: 1
[00:19:48 DBG] Network discovery task: starting
[00:19:48 DBG] DISCOVERY Extension: Startup
[00:19:48 DBG] DISCOVERY Extension: Starting mesh update
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=False, data=01 80 01 36 00 00 8D]
[00:19:48 DBG] Network discovery task: starting
[00:19:48 DBG] ASH: Frame acked and removed Node 70AC08FFFEBF0831 added ZigBeeNode [IEEE=70AC08FFFEBF0831, NWK=0x0000, Type=COORDINATOR]
AshFrameData [frmNum=0, ackNum=5, reTx=False, data=01 00 01 36 00 FC FF 00 00 36 00 00 00 40 11 00 00 01 1F 00 03 01 00 01]

1 node(s)

0. COORDINATOR: 0
[
00:19:48 cmd> DBG] 70AC08FFFEBF0831: DISCOVERY Extension: Adding discoverer for node
[00:19:48 DBG] 70AC08FFFEBF0831: Data store: Deferring write for 250ms.
[00:19:48 DBG] 70AC08FFFEBF0831: DISCOVERY Extension: Adding discoverer for node
[00:19:48 DBG] RX EZSP: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=0x8D]
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: start discovery
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: start discovery
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: starting new tasks
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: starting new tasks
[00:19:48 DBG] --> TX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=False, data=02 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 02 00 03 02 00 01]
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: has no new tasks to perform
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: has no new tasks to perform
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=False, data=01 90 01 45 00 05 00 00 36 00 00 00 00 01 00 00 8D FF 00 00 00 FF FF 03 01 00 01]
[00:19:48 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x8D], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x01 0x00 0x01]
[00:19:48 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=0, apsCounter=141, payload=1 0 1]
[00:19:48 DBG] 70AC08FFFEBF0831: Node state updated from UNKNOWN to ONLINE
[00:19:48 DBG] 70AC08FFFEBF0831: Node 0 update
[00:19:48 DBG] 70AC08FFFEBF0831: Node state updated from UNKNOWN to ONLINE
Node 70AC08FFFEBF0831 added ZigBeeNode [IEEE=70AC08FFFEBF0831, NWK=0x0000, Type=COORDINATOR]
[00:19:48 DBG] 70AC08FFFEBF0831: Data store: Deferring write for 250ms.
[00:19:48 DBG] 70AC08FFFEBF0831: DISCOVERY Extension: Creating new discoverer for node
[00:19:48 DBG] 70AC08FFFEBF0831: DISCOVERY Extension: Creating new discoverer for node
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: stopped
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: stopped
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: start discovery
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: start discovery
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: starting new tasks
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: starting new tasks
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: has no new tasks to perform
[00:19:48 DBG] 70AC08FFFEBF0831: Node SVC Discovery: has no new tasks to perform
[00:19:48 DBG] RX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=1, PermitDuration=0, TcSignificance=True]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=False, data=02 80 01 34 00 00 8E]
[00:19:48 DBG] ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=6, reTx=False, data=02 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 02 00 03 02 00 01]
[00:19:48 DBG] RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=0x8E]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=False, data=02 90 01 45 00 00 00 00 36 00 00 00 40 01 00 00 8E FF 00 00 00 FF FF 03 02 00 01]
[00:19:48 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x8E], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x02 0x00 0x01]
[00:19:48 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=0, apsCounter=142, payload=2 0 1]
[00:19:48 DBG] RX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=2, PermitDuration=0, TcSignificance=True]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=False, data=02 90 01 3F 00 00 00 00 00 00 36 00 00 00 40 11 00 00 8E 00 00 00]
[00:19:48 DBG] RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x8E], messageTag=0x00, status=EMBER_SUCCESS, messageContents=]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:48 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=False, data=02 90 01 45 00 00 00 00 36 80 00 00 40 01 00 00 8F FF 00 00 00 FF FF 02 02 80]
[00:19:48 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x8036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x8F], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x02 0x80]
[00:19:48 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=32822, addressMode=Device, radius=0, apsCounter=143, payload=2 128]
[00:19:48 DBG] RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=2, Status=INV_REQUESTTYPE]
[00:19:48 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:48 DBG] 70AC08FFFEBF0831: Data store: Writing node.
[00:19:49 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=False, data=02 90 01 3F 00 06 FC FF 00 00 36 00 00 00 00 01 00 00 8D FF 00 00]
[00:19:49 DBG] RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_BROADCAST, indexOrDestination=65532, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x8D], messageTag=0xFF, status=EMBER_SUCCESS, messageContents=]
[00:19:49 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]
join
[00:19:54 DBG] TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=3, PermitDuration=255, TcSignificance=True]
[00:19:54 DBG] TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=31, apsCounter=3, payload=3 255 1]
[00:19:54 DBG] TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0x0000, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x03], messageTag=0x00, messageContents=0x03 0xFF 0x01]
[00:19:54 DBG] ASH: TX EZSP queue size: 1
[00:19:54 DBG] --> TX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=False, data=03 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 03 00 03 03 FF 01]
[00:19:54 DBG] <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=False, data=03 80 01 34 00 00 90]
[00:19:54 DBG] ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=4, reTx=False, data=03 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 03 00 03 03 FF 01]
[00:19:54 DBG] RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=0x90]
[00:19:54 DBG] --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=False]
[00:19:54 DBG] <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=False, data=03 90 01 45 00 00 00 00 36 00 00 00 40 01 00 00 90 FF 00 00 00 FF FF 03 03 FF 01]
[00:19:54 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x90], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x03 0xFF 0x01]
[00:19:54 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=0, apsCounter=144, payload=3 255 1]
[00:19:54 DBG] RX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=3, PermitDuration=255, TcSignificance=True]
[00:19:54 DBG] --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=False]
[00:19:54 DBG] <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=False, data=03 90 01 3F 00 00 00 00 00 00 36 00 00 00 40 11 00 00 90 00 00 00]
[00:19:54 DBG] RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x90], messageTag=0x00, status=EMBER_SUCCESS, messageContents=]
[00:19:54 DBG] --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=False]
[00:19:54 DBG] <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=False, data=03 90 01 45 00 00 00 00 36 80 00 00 40 01 00 00 91 FF 00 00 00 FF FF 02 03 80]
[00:19:54 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x8036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x91], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x03 0x80]
[00:19:54 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=32822, addressMode=Device, radius=0, apsCounter=145, payload=3 128]
[00:19:54 DBG] RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=3, Status=INV_REQUESTTYPE]
[00:19:54 DBG] --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=False]

1 node(s)

0. COORDINATOR: 0

cmd> join
[00:19:57 DBG] TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=4, PermitDuration=255, TcSignificance=True]
[00:19:57 DBG] TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=31, apsCounter=4, payload=4 255 1]
[00:19:57 DBG] TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0x0000, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x04], messageTag=0x00, messageContents=0x04 0xFF 0x01]
[00:19:57 DBG] ASH: TX EZSP queue size: 1
[00:19:57 DBG] --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=False, data=04 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 04 00 03 04 FF 01]
[00:19:57 DBG] <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=False, data=04 80 01 34 00 00 92]
[00:19:57 DBG] ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=False, data=04 00 01 34 00 00 00 00 00 00 36 00 00 00 40 11 00 00 04 00 03 04 FF 01]
[00:19:57 DBG] RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=0x92]
[00:19:57 DBG] --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=False]
[00:19:57 DBG] <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=False, data=04 90 01 45 00 00 00 00 36 00 00 00 40 01 00 00 92 FF 00 00 00 FF FF 03 04 FF 01]
[00:19:57 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x92], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x04 0xFF 0x01]
[00:19:57 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=54, addressMode=Device, radius=0, apsCounter=146, payload=4 255 1]
[00:19:57 DBG] RX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=54, transId=4, PermitDuration=255, TcSignificance=True]
[00:19:57 DBG] --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=False]
[00:19:57 DBG] <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=False, data=04 90 01 3F 00 00 00 00 00 00 36 00 00 00 40 11 00 00 92 00 00 00]
[00:19:57 DBG] RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x0036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x92], messageTag=0x00, status=EMBER_SUCCESS, messageContents=]
[00:19:57 DBG] --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=False]
[00:19:57 DBG] <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=False, data=04 90 01 45 00 00 00 00 36 80 00 00 40 01 00 00 93 FF 00 00 00 FF FF 02 04 80]
[00:19:57 DBG] RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0x0000, clusterId=0x8036, sourceEndpoint=0, destinationEndpoint=0, options=System.Collections.Generic.HashSet`1[ZigBeeNet.Hardware.Ember.Ezsp.Structure.EmberApsOption], groupId=0, sequence=0x93], lastHopLqi=255, lastHopRssi=0, sender=0x0000, bindingIndex=255, addressIndex=255, messageContents=0x04 0x80]
[00:19:57 DBG] RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=32822, addressMode=Device, radius=0, apsCounter=147, payload=4 128]
[00:19:57 DBG] RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=4, Status=INV_REQUESTTYPE]
[00:19:57 DBG] --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=False]

1 node(s)

0. COORDINATOR: 0

cmd>