[17:31:15.768,777] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:15.769,021] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:15.769,080] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:15.769,119] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:15.769,154] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:15.769,191] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:15.769,225] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:15.769,256] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:15.769,291] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:15.774,883] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:15.775,743] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:15.779,641] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:15.779,791] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:15.779,826] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:15.780,897] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:15.781,051] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:15.781,090] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:15.781,119] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:15.781,150] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:15.781,177] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:15.781,202] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:15.781,229] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:15.781,255] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:15.781,282] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:15.781,309] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:15.781,334] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:15.781,449] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:15.781,500] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:15.781,544] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:15.787,303] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:15.788,617] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:15.789,513] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:15.790,514] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:15.792,953] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:15.794,328] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:15.797,838] [NWK_MGR/LSTN] INFO : !Done [17:31:15.797,483] [Z_STACK/LSTN] INFO : !Done [17:31:15.818,085] [NWK_MGR/LSTN] INFO : Receive message... [17:31:15.818,295] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:15.832,443] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:15.832,569] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:15.832,605] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:15.831,928] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:15.836,582] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:15.836,734] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:15.836,934] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:15.836,992] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:15.837,055] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:15.837,098] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:15.837,138] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:15.837,174] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:15.837,208] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:15.837,245] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:15.837,279] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:15.837,315] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:15.837,349] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:15.837,381] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:15.837,416] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:15.837,449] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:15.837,484] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:15.842,310] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:15.842,483] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:15.842,521] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:15.844,870] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:15.845,016] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:15.845,054] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:15.845,083] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:15.845,114] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:15.845,140] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:15.845,166] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:15.845,193] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:15.845,219] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:15.845,246] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:15.845,272] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:15.845,298] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:15.845,412] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:15.845,463] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:15.845,509] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:15.845,547] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:15.851,681] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:15.854,085] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:15.854,214] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:15.854,443] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:15.854,508] [NWK_MGR/LSTN] INFO : !Done [17:31:15.857,422] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:15.860,368] [Z_STACK/LSTN] INFO : !Done [17:31:16.170,831] [NWK_MGR/LSTN] INFO : Receive message... [17:31:16.171,055] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.185,473] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.188,299] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [17:31:16.188,443] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:16.188,480] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:16.190,174] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:16.190,318] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.190,352] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:16.193,441] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.197,537] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:16.197,777] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.197,814] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:16.196,237] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:16.196,506] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:16.196,570] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:16.196,631] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:16.196,673] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:16.196,713] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:16.196,747] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:16.196,782] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.196,819] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:16.196,853] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:16.196,890] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:16.196,924] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:16.196,955] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:16.196,990] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:16.197,023] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:16.197,059] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:16.203,869] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.204,030] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:16.204,072] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:16.204,101] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:16.204,132] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:16.204,158] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:16.204,184] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:16.204,211] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:16.204,238] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:16.204,265] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:16.204,291] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:16.204,317] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:16.204,431] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:16.204,482] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:16.204,526] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:16.204,564] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:16.204,600] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:16.204,634] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.204,672] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:16.204,924] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:16.204,982] [NWK_MGR/LSTN] INFO : !Done [17:31:16.208,530] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:16.210,148] [Z_STACK/LSTN] INFO : !Done [17:31:16.293,465] [NWK_MGR/LSTN] INFO : Receive message... [17:31:16.293,851] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.309,305] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:16.309,435] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.309,470] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:16.308,755] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.315,074] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:16.315,203] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.315,237] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:16.313,404] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.313,532] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:16.313,853] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:16.313,916] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:16.313,977] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:16.314,256] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:16.314,322] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:16.314,360] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:16.314,395] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.314,432] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:16.314,467] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:16.314,503] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:16.314,536] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:16.314,567] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:16.314,603] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:16.314,636] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:16.314,672] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:16.321,756] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:16.324,698] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.324,861] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:16.324,902] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:16.324,932] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:16.324,962] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:16.324,989] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:16.325,015] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:16.325,043] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:16.325,070] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:16.325,097] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:16.325,123] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:16.325,149] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:16.325,264] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:16.325,314] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:16.325,358] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:16.325,397] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:16.325,434] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:16.325,468] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.325,505] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:16.328,905] [Z_STACK/LSTN] INFO : !Done [17:31:16.330,175] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:16.331,131] [NWK_MGR/LSTN] INFO : !Done [17:31:16.835,703] [NWK_MGR/LSTN] INFO : Receive message... [17:31:16.835,905] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.851,783] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:16.851,923] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.851,960] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:16.849,078] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.855,204] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:16.855,333] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.855,369] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:16.853,887] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.854,035] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:16.854,231] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:16.854,287] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:16.854,349] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:16.854,394] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:16.854,434] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:16.854,470] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:16.854,505] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.854,542] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:16.854,575] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:16.854,612] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:16.854,645] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:16.854,677] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:16.854,711] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:16.854,744] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:16.854,780] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:16.861,301] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.861,492] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:16.861,536] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:16.861,872] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:16.861,922] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:16.861,950] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:16.861,977] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:16.862,004] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:16.862,032] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:16.862,059] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:16.862,085] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:16.862,111] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:16.862,231] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:16.862,285] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:16.862,329] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:16.862,370] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:16.862,405] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:16.862,439] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.862,475] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:16.862,651] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:16.862,706] [NWK_MGR/LSTN] INFO : !Done [17:31:16.867,361] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:16.868,319] [Z_STACK/LSTN] INFO : !Done [17:31:16.887,984] [NWK_MGR/LSTN] INFO : Receive message... [17:31:16.888,186] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.905,008] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:16.908,692] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:16.908,849] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.908,885] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:16.911,206] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:16.911,330] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:16.911,365] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:16.909,979] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.910,106] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:16.910,301] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:16.910,356] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:16.910,417] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:16.910,458] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:16.910,497] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:16.910,532] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:16.910,566] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.910,603] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:16.910,637] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:16.910,673] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:16.910,706] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:16.910,738] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:16.910,772] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:16.910,806] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:16.910,843] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:16.917,825] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:16.918,022] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:16.918,067] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:16.918,096] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:16.918,128] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:16.918,155] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:16.918,181] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:16.918,208] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:16.918,234] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:16.918,261] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:16.918,286] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:16.918,312] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:16.918,429] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:16.918,481] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:16.918,524] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:16.918,563] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:16.918,600] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:16.918,633] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:16.918,670] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:16.918,920] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:16.918,984] [NWK_MGR/LSTN] INFO : !Done [17:31:16.924,306] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:16.925,723] [Z_STACK/LSTN] INFO : !Done [17:31:17.082,898] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.083,123] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.093,911] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.103,956] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.104,102] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.104,138] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.110,502] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.110,656] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.110,694] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.105,810] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [17:31:17.105,941] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:17.105,975] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:17.106,003] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.106,036] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.106,224] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.106,278] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.106,338] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.106,380] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.106,419] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.106,454] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.106,488] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.106,524] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.106,558] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.106,595] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.106,629] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.106,660] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.106,694] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.106,727] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.106,763] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.113,809] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.113,984] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.114,024] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.114,053] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.114,084] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:17.114,111] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:17.114,137] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:17.114,165] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:17.114,191] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:17.114,218] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:17.114,244] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:17.114,270] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:17.114,386] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:17.114,439] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:17.114,483] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:17.114,521] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:17.114,558] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:17.114,593] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.114,630] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:17.114,867] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:17.114,926] [NWK_MGR/LSTN] INFO : !Done [17:31:17.119,657] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.120,771] [Z_STACK/LSTN] INFO : !Done [17:31:17.147,663] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.147,859] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.164,584] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.164,709] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.164,746] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.164,047] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.168,532] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.168,680] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.169,080] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.169,158] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.169,222] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.169,264] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.169,303] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.169,338] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.169,372] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.169,409] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.169,443] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.169,480] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.169,514] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.169,545] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.173,816] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.176,407] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.177,441] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.178,795] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.178,936] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.178,972] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.181,909] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.182,066] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.182,105] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.182,135] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.182,166] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:17.182,193] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:17.182,219] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:17.182,245] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:17.182,271] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:17.182,298] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:17.182,324] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:17.182,350] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:17.182,466] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:17.182,515] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:17.182,559] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:17.182,598] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:17.182,634] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:17.182,668] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.182,704] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:17.182,983] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:17.183,046] [NWK_MGR/LSTN] INFO : !Done [17:31:17.187,885] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.192,533] [Z_STACK/LSTN] INFO : !Done [17:31:17.563,101] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.563,301] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.578,472] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.578,607] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.578,641] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.577,925] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.587,738] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.587,900] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.587,936] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.584,518] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.584,633] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.584,829] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.584,885] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.584,943] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.584,984] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.585,023] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.585,058] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.585,093] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.585,129] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.585,163] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.585,199] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.585,233] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.585,265] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.585,299] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.585,333] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.585,369] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.591,242] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.591,492] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.591,534] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.591,564] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.591,595] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:17.591,621] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:17.591,648] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:17.591,675] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:17.591,702] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:17.591,729] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:17.591,755] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:17.591,780] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:17.591,897] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:17.591,949] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:17.591,992] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:17.592,031] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:17.592,066] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:17.592,101] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.592,138] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:17.592,458] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:17.592,526] [NWK_MGR/LSTN] INFO : !Done [17:31:17.597,373] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.600,473] [Z_STACK/LSTN] INFO : !Done [17:31:17.617,276] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.617,473] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.630,484] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.630,614] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.630,650] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.629,953] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.635,008] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.635,167] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.635,368] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.635,427] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.635,489] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.635,531] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.635,569] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.635,604] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.635,638] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.635,675] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.635,709] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.635,745] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.635,779] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.635,810] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.635,845] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.635,878] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.635,914] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.636,128] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.636,187] [Z_STACK/LSTN] INFO : !Done [17:31:17.640,464] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.640,620] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.640,655] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.644,440] [NWK_MGR/LSTN] INFO : Waiting for synchronous response... [17:31:17.645,417] [NWK_MGR/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:17.645,516] [NWK_MGR/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:17.645,550] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.649,426] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.649,533] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.649,679] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.649,720] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:17.649,748] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:17.649,774] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:17.649,802] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:17.649,829] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:17.649,856] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:17.649,882] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:17.649,907] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:17.650,028] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:17.650,082] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:17.650,128] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:17.650,168] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:17.650,203] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:17.650,238] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.650,275] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:17.650,465] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:17.650,529] [NWK_MGR/LSTN] INFO : !Done [17:31:17.813,804] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.814,022] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.829,376] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.829,509] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.828,846] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.829,543] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.834,122] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.834,259] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.834,459] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.834,518] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.834,580] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.834,622] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.834,662] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.834,697] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.834,731] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.834,768] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.834,801] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.835,052] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.835,098] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.835,131] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.835,166] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.835,199] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.835,236] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.825,534] [NWK_MGR/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:17.842,280] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.842,415] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.842,451] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.845,145] [NWK_MGR/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:17.845,262] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.845,327] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.845,360] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.845,388] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.845,418] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:17.845,444] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:17.845,470] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:17.845,497] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:17.845,523] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:17.845,550] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:17.849,943] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:17.850,066] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:17.850,202] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:17.850,258] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:17.850,309] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:17.850,350] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:17.850,387] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:17.850,421] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.850,458] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:17.850,648] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:17.850,703] [NWK_MGR/LSTN] INFO : !Done [17:31:17.854,283] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.855,412] [Z_STACK/LSTN] INFO : !Done [17:31:17.965,792] [NWK_MGR/LSTN] INFO : Receive message... [17:31:17.966,001] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.981,010] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:17.981,480] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:17.986,322] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.986,405] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:17.989,020] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:17.989,147] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:17.989,181] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:17.987,525] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.987,874] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:17.988,085] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:17.988,144] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:17.988,206] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:17.988,248] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:17.988,287] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:17.988,322] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:17.988,357] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:17.988,393] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:17.988,427] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:17.988,464] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:17.988,497] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:17.988,529] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:17.988,563] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:17.988,596] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:17.988,632] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:17.994,698] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:17.994,888] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:17.994,932] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:17.994,962] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:17.997,918] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:17.998,917] [Z_STACK/LSTN] INFO : !Done [17:31:17.994,994] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:18.002,957] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:18.003,056] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:18.003,092] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:18.003,122] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:18.003,150] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:18.003,177] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:18.003,203] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:18.003,331] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:18.003,385] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:18.003,436] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:18.003,476] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:18.003,512] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:18.003,547] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.003,584] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:18.003,770] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:18.003,825] [NWK_MGR/LSTN] INFO : !Done [17:31:18.637,881] [NWK_MGR/LSTN] INFO : Receive message... [17:31:18.638,082] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.652,900] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.656,177] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [17:31:18.656,321] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:18.656,355] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:18.656,474] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:18.656,538] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.656,568] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:18.662,022] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:18.662,193] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.662,232] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:18.657,868] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.658,002] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:18.658,392] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:18.658,469] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:18.658,536] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:18.658,578] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:18.658,618] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:18.658,654] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:18.658,689] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.658,726] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:18.658,759] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:18.658,796] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:18.658,829] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:18.658,860] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:18.658,895] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:18.658,928] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:18.658,964] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:18.665,880] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.666,070] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:18.666,111] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:18.666,142] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:18.666,172] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:18.666,198] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:18.666,225] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:18.666,252] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:18.666,278] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:18.666,305] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:18.666,331] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:18.666,356] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:18.666,472] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:18.666,524] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:18.666,569] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:18.666,609] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:18.666,644] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:18.666,679] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.666,715] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:18.666,935] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:18.666,994] [NWK_MGR/LSTN] INFO : !Done [17:31:18.671,979] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:18.672,954] [Z_STACK/LSTN] INFO : !Done [17:31:18.695,338] [Z_STACK/LSTN] INFO : Receive message... [17:31:18.695,520] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [17:31:18.695,568] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [17:31:18.695,601] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:31:18.695,653] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.706,441] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:18.706,570] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.706,605] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:18.705,923] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.711,835] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.711,969] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:18.712,166] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:18.712,225] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:18.712,285] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:18.712,327] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:18.712,366] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:18.712,401] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:18.712,436] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.712,472] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:18.712,506] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:18.712,542] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:18.712,576] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:18.712,607] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:18.712,642] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:18.712,675] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:18.712,711] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:18.716,510] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:18.716,628] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.716,660] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:18.718,808] [NWK_MGR/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:18.718,942] [NWK_MGR/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:18.718,978] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.719,036] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:18.719,067] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:18.719,094] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:18.719,125] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:18.719,150] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:18.719,176] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:18.719,203] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:18.719,229] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:18.719,256] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:18.719,282] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:18.719,308] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:18.719,424] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:18.719,475] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:18.719,519] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:18.719,791] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:18.719,856] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:18.719,893] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.719,931] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:18.720,120] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:18.720,180] [NWK_MGR/LSTN] INFO : !Done [17:31:18.726,312] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:18.727,668] [Z_STACK/LSTN] INFO : !Done [17:31:18.881,262] [NWK_MGR/LSTN] INFO : Receive message... [17:31:18.881,460] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.896,949] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:18.897,082] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.897,121] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:18.896,473] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.901,284] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.901,439] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:18.901,748] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:18.901,817] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:18.901,882] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:18.901,925] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:18.901,963] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:18.901,999] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:18.902,033] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.902,070] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:18.902,104] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:18.902,140] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:18.902,174] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:18.902,205] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:18.902,240] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:18.902,273] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:18.902,309] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:18.904,617] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:18.904,774] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.904,811] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:18.909,684] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.909,874] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:18.909,914] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:18.909,944] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:18.909,976] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:18.910,002] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:18.910,029] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:18.910,056] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:18.910,083] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:18.910,110] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:18.910,137] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:18.910,162] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:18.910,279] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:18.910,333] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:18.910,377] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:18.910,417] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:18.910,454] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:18.910,488] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.910,525] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:18.910,731] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:18.910,792] [NWK_MGR/LSTN] INFO : !Done [17:31:18.915,564] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:18.916,558] [Z_STACK/LSTN] INFO : !Done [17:31:18.945,431] [NWK_MGR/LSTN] INFO : Receive message... [17:31:18.949,527] [Z_STACK/LSTN] INFO : Receive message... [17:31:18.949,789] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [17:31:18.949,840] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [17:31:18.949,873] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:31:18.949,925] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.960,870] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:18.961,009] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.961,046] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:18.960,229] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:18.967,061] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.967,197] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:18.967,394] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:18.967,450] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:18.967,510] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:18.967,551] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:18.967,590] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:18.967,625] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:18.967,659] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.967,695] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:18.967,729] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:18.967,766] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:18.967,799] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:18.967,831] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:18.967,866] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:18.967,899] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:18.967,935] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:18.968,194] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:18.968,254] [Z_STACK/LSTN] INFO : !Done [17:31:18.972,119] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:18.972,269] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:18.972,306] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:18.975,185] [NWK_MGR/LSTN] INFO : Waiting for synchronous response... [17:31:18.976,217] [NWK_MGR/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:18.976,318] [NWK_MGR/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:18.976,352] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:18.976,412] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:18.976,442] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:18.976,470] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:18.976,501] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:18.976,527] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:18.976,554] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:18.976,580] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:18.976,607] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:18.976,633] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:18.976,659] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:18.976,684] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:18.976,802] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:18.976,852] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:18.976,896] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:18.976,936] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:18.976,972] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:18.977,007] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:18.977,044] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:18.977,248] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:18.977,306] [NWK_MGR/LSTN] INFO : !Done [17:31:19.337,226] [NWK_MGR/LSTN] INFO : Receive message... [17:31:19.337,419] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.355,395] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [17:31:19.355,523] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:19.355,560] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:19.356,843] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.357,308] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:19.357,426] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.357,462] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:19.363,613] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.363,750] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:19.363,949] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:19.364,005] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:19.364,068] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:19.364,112] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:19.364,150] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:19.364,185] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:19.364,220] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.364,257] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:19.364,291] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:19.364,328] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:19.364,361] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:19.364,392] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:19.364,427] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:19.364,460] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:19.364,497] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:19.361,501] [NWK_MGR/LSTN] INFO : Waiting for synchronous response... [17:31:19.371,409] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:19.371,540] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.371,576] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:19.372,968] [NWK_MGR/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:31:19.373,094] [NWK_MGR/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:31:19.373,130] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.373,189] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:19.373,220] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:19.373,248] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:19.373,278] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:19.373,304] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:19.373,330] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:19.373,357] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:19.373,383] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:19.373,409] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:19.373,435] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:19.373,461] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:19.377,866] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:19.378,997] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:19.379,883] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:19.381,928] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:19.384,992] [Z_STACK/LSTN] INFO : !Done [17:31:19.386,259] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:19.386,392] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:19.386,434] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.386,473] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:19.386,683] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:19.386,746] [NWK_MGR/LSTN] INFO : !Done [17:31:19.471,294] [Z_STACK/LSTN] INFO : Receive message... [17:31:19.471,480] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [17:31:19.471,531] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [17:31:19.471,565] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:31:19.471,618] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.483,842] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:19.483,981] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.484,017] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:19.483,260] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.486,674] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.486,830] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:19.487,030] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:19.487,088] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:19.487,149] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:19.487,383] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:19.487,441] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:19.487,477] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:19.487,513] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.487,550] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:19.487,584] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:19.487,621] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:19.487,655] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:19.487,686] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:19.487,720] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:19.487,753] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:19.487,789] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:19.491,248] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:19.491,406] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.491,443] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:19.495,728] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.495,887] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:19.495,925] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:19.495,955] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:19.495,986] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:19.496,012] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:19.496,039] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:19.496,065] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:19.496,092] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:19.496,119] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:19.496,145] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:19.496,170] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:19.496,285] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:19.496,336] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:19.496,378] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:19.496,417] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:19.496,453] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:19.496,487] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.496,524] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:19.496,958] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:19.497,038] [NWK_MGR/LSTN] INFO : !Done [17:31:19.500,692] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:19.503,583] [Z_STACK/LSTN] INFO : !Done [17:31:19.849,504] [NWK_MGR/LSTN] INFO : Receive message... [17:31:19.849,868] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.865,310] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:19.867,649] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:19.867,778] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.867,811] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:19.872,207] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:19.872,337] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:19.872,372] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:19.870,921] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.871,057] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:19.871,252] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:19.871,310] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:19.871,371] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:19.871,413] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:19.871,451] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:19.871,486] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:19.871,520] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.871,557] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:19.871,592] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:19.871,628] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:19.871,662] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:19.871,694] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:19.871,728] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:19.871,761] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:19.871,797] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:19.879,838] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:19.879,970] [Z_STACK/LSTN] INFO : !Done [17:31:19.881,085] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:19.881,267] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:19.881,312] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:19.881,342] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:19.881,373] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:19.881,400] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:19.881,426] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:19.881,453] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:19.881,479] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:19.881,506] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:19.881,532] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:19.884,654] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:19.885,819] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:19.888,640] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:19.889,922] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:19.890,860] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:19.893,533] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:19.905,097] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:19.907,967] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:19.911,993] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:19.920,972] [NWK_MGR/LSTN] INFO : !Done [17:31:19.997,976] [NWK_MGR/LSTN] INFO : Receive message... [17:31:19.998,181] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:20.013,365] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:31:20.013,491] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:20.013,528] [Z_STACK/READ] INFO : Client Read: (len 27): [17:31:20.012,851] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:31:20.018,993] [NWK_MGR/READ] INFO : Received 48 bytes, subSys 0x71, cmdId 0x0E [17:31:20.019,128] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:31:20.019,163] [NWK_MGR/READ] INFO : Client Read: (len 52): [17:31:20.017,401] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:20.017,542] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [17:31:20.017,858] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 30:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:E8:66:31:34:57:49:01:00:4B:12:00:38:00:41:00:00:00:00:00:00:00:00:4A:06:08:01:10:00:18:00:50:0D [17:31:20.017,922] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [17:31:20.017,983] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [17:31:20.018,027] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [17:31:20.018,066] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [17:31:20.018,101] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [17:31:20.018,136] [Z_STACK/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:20.018,173] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [17:31:20.018,208] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [17:31:20.018,244] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [17:31:20.018,277] [Z_STACK/LSTN] PKTBODY: devTypes : [17:31:20.018,310] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [17:31:20.018,345] [Z_STACK/LSTN] PKTBODY: router = 0 [17:31:20.018,377] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [17:31:20.018,413] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [17:31:20.024,264] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:31:20.024,457] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [17:31:20.024,736] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [17:31:20.024,770] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [17:31:20.024,801] [NWK_MGR/LSTN] MISC1 : - devstate:9 [17:31:20.024,828] [NWK_MGR/LSTN] MISC1 : - panid:3368 [17:31:20.024,854] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [17:31:20.024,882] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [17:31:20.024,909] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [17:31:20.024,935] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [17:31:20.024,961] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [17:31:20.024,987] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [17:31:20.025,105] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] [ucst] 12:00:72:0B:08:0B:10:01:18:0D:20:E8:66:29:34:57:49:01:00:4B:12:00 [17:31:20.025,158] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON009] NwkZigbeeNwkInfoCnf [17:31:20.025,204] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [17:31:20.025,243] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [17:31:20.025,279] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [17:31:20.025,313] [NWK_MGR/LSTN] PKTBODY: panId = 0x00003368 (13160) [17:31:20.025,351] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [17:31:20.031,298] [Z_STACK/LSTN] INFO : ...sent 52 bytes to Client [17:31:20.032,276] [Z_STACK/LSTN] INFO : !Done [17:31:20.025,530] [NWK_MGR/LSTN] INFO : ...sent 22 bytes to Client [17:31:20.035,882] [NWK_MGR/LSTN] INFO : !Done [17:32:09.675,883] [Z_STACK/LSTN] INFO : Receive message... [17:32:09.676,024] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:43 [17:32:09.676,083] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [17:32:09.676,118] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:09.676,171] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:32:09.690,767] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [17:32:09.690,898] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:09.690,935] [Z_STACK/READ] INFO : Client Read: (len 27): [17:32:09.690,105] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:68:33:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [17:32:09.696,114] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:09.696,273] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [17:32:09.696,319] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [17:32:09.696,351] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:09.696,416] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:36:00 [17:32:09.708,008] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [17:32:09.708,134] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:09.708,170] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:09.707,590] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [17:32:09.712,957] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:09.713,112] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [17:32:09.713,161] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [17:32:09.713,194] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:09.713,259] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [ASNC] 03:45:B6:00:00:00 [17:32:09.716,952] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 03:45:B6:00:00:00 [17:32:09.724,162] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:65:36:00 [17:32:09.736,449] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [17:32:09.745,751] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 03:45:B6:00:00:00 [17:32:09.745,991] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 03:45:B6:00:00:00 [17:32:09.758,326] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [17:32:09.758,489] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:09.758,525] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:09.758,604] [Z_STACK/READ] INFO : Received 3 bytes, subSys 0x45, cmdId 0xB6 [17:32:09.758,647] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xB6 [17:32:09.758,676] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01378 (received 7 messages)... [17:32:09.758,703] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01378)... [17:32:09.758,777] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:09.758,823] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B01378 [17:32:09.758,851] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:09.758,878] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000B6, len:0x00000003, pData:0xB6B01380 [17:32:09.758,909] [Z_STACK/HNDL] DEBUG : znp_misc handleAsyncMsgs: Unrecognized cmdID:b6 [17:32:09.758,937] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01378)... [17:32:09.758,965] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01378 (processed 7 messages)... [17:32:09.758,994] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:09.761,195] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:09.761,319] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:09.761,354] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:09.762,561] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:09.762,712] [NWK_MGR/LSTN] MISC1 : Sending ZigBee Generic Confirmation [17:32:09.762,804] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>SMPLAPP] [ucst] 04:00:72:00:08:00:10:00 [17:32:09.762,854] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkZigbeeGenericCnf [17:32:09.763,107] [NWK_MGR/LSTN] PKTBODY: cmdId = ZIGBEE_GENERIC_CNF [17:32:09.763,153] [NWK_MGR/LSTN] PKTBODY: status = STATUS_SUCCESS [17:32:09.760,496] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:09.760,677] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:43:10:00 [17:32:09.760,748] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:09.760,803] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_MGMT_PERMIT_JOIN_REQ [17:32:09.760,844] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:09.771,232] [NWK_MGR/LSTN] INFO : ...sent 8 bytes to Client [17:32:09.772,192] [NWK_MGR/LSTN] INFO : !Done [17:32:09.773,144] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:09.776,138] [Z_STACK/LSTN] INFO : !Done [17:32:11.546,885] [NWK_MGR/READ] INFO : Received 18 bytes, subSys 0x51, cmdId 0x95 [17:32:11.547,001] [NWK_MGR/READ] INFO : RPC_CMD_AREQ cmdId: 0x95 [17:32:11.547,038] [NWK_MGR/READ] INFO : [DBG] Allocated @ 0xB6B004F8 (received 3 messages)... [17:32:11.547,067] [NWK_MGR/READ] INFO : Filling new message (@ 0xB6B004F8)... [17:32:11.537,290] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0C:45:CA:3B:FB:E7:C9:27:21:00:4B:12:00:00:00 [17:32:11.540,145] [Z_STACK/READ] INFO : Received 12 bytes, subSys 0x45, cmdId 0xCA [17:32:11.540,288] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xCA [17:32:11.540,325] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B004D8 (received 8 messages)... [17:32:11.540,354] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B004D8)... [17:32:11.539,557] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0C:45:CA:3B:FB:E7:C9:27:21:00:4B:12:00:00:00 [17:32:11.551,569] [NWK_MGR/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:11.551,675] [NWK_MGR/HNDL] INFO : [DBG] Processing @ 0xB6B004F8 [17:32:11.551,708] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:11.551,737] [NWK_MGR/HNDL] INFO : [DBG] asyncCB: subSys:0x00000011, cmdId:0x00000095, len:0x00000012, pData:0xB6B00500 [17:32:11.551,784] [NWK_MGR/HNDL] MISC1 : Processing ZDO TC Device Ind: 00124B002127C9E7 [17:32:11.551,819] [NWK_MGR/HNDL] INFO : NwkMgr AddDevice State Machine Started on 0x00124b002127c9e7 [17:32:11.551,846] [NWK_MGR/HNDL] INFO : nwkAddr FB3B, parent 0000 [17:32:11.551,876] [NWK_MGR/HNDL] INFO : AddDevice: No state machines, extending list to 16 [17:32:11.551,905] [NWK_MGR/HNDL] INFO : AddDevice: Initiated new state machine [17:32:11.551,938] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B004F8)... [17:32:11.551,964] [NWK_MGR/HNDL] INFO : [DBG] Clearing @ 0xB6B004F8 (processed 3 messages)... [17:32:11.551,993] [NWK_MGR/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:11.545,516] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:11.545,743] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B004D8 [17:32:11.545,781] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:11.545,810] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000CA, len:0x0000000C, pData:0xB6B004E0 [17:32:11.545,843] [Z_STACK/HNDL] INFO : zstackpb Sending TC Device Ind: nwkAddr:0xFB3B, extAddr:0x00124B002127C9E7, parentAddr:0x0000 [17:32:11.545,963] [Z_STACK/HNDL] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 12:00:51:95:08:95:01:10:BB:F6:03:19:E7:C9:27:21:00:4B:12:00:20:00 [17:32:11.546,024] [Z_STACK/HNDL] PKTTYPE: [ Z_STACK>>NWK_MGR ] zdoTcDeviceInd [17:32:11.546,075] [Z_STACK/HNDL] PKTBODY: cmdID = [17:32:11.546,116] [Z_STACK/HNDL] PKTBODY: nwkAddr = 0x0000FB3B (64315) [17:32:11.546,155] [Z_STACK/HNDL] PKTBODY: extendedAddr = 00:12:4B:00:21:27:C9:E7 [17:32:11.546,189] [Z_STACK/HNDL] PKTBODY: parentAddr = 0x00000000 (0) [17:32:11.546,506] [Z_STACK/HNDL] INFO : ...sent 22 bytes to Client [17:32:11.546,569] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B004D8)... [17:32:11.546,601] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B004D8 (processed 8 messages)... [17:32:11.546,630] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:11.824,610] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0D:45:C1:3B:FB:3B:FB:E7:C9:27:21:00:4B:12:00:8E [17:32:11.826,405] [Z_STACK/READ] INFO : Received 13 bytes, subSys 0x45, cmdId 0xC1 [17:32:11.826,540] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC1 [17:32:11.826,578] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01388 (received 9 messages)... [17:32:11.826,607] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01388)... [17:32:11.825,923] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0D:45:C1:3B:FB:3B:FB:E7:C9:27:21:00:4B:12:00:8E [17:32:11.829,469] [NWK_MGR/READ] INFO : Received 31 bytes, subSys 0x51, cmdId 0x48 [17:32:11.829,703] [NWK_MGR/READ] INFO : RPC_CMD_AREQ cmdId: 0x48 [17:32:11.829,747] [NWK_MGR/READ] INFO : [DBG] Allocated @ 0xB6B00518 (received 4 messages)... [17:32:11.829,777] [NWK_MGR/READ] INFO : Filling new message (@ 0xB6B00518)... [17:32:11.834,000] [NWK_MGR/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:11.834,351] [NWK_MGR/HNDL] INFO : [DBG] Processing @ 0xB6B00518 [17:32:11.834,387] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:11.834,416] [NWK_MGR/HNDL] INFO : [DBG] asyncCB: subSys:0x00000011, cmdId:0x00000048, len:0x0000001F, pData:0xB6B00520 [17:32:11.834,472] [NWK_MGR/HNDL] MISC1 : NwkMgr processZdoDeviceAnnounce: ieeeaddr 00124B002127C9E7, nwkaddr FB3B, capInfo 0E [17:32:11.834,509] [NWK_MGR/HNDL] INFO : AddDevice: Retrying state 1, remaining tries: 4 [17:32:11.834,541] [NWK_MGR/HNDL] MISC1 : NwkMgr sendZdoIeeeAddrReq: ieeeaddr 0000 [17:32:11.834,581] [NWK_MGR/HNDL] INFO : preparing to send 8 bytes, subSys 0x11, cmdId 0x31, pData: [17:32:11.834,611] [NWK_MGR/HNDL] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:11.834,682] [NWK_MGR/HNDL] PKT_HEX: [ Z_STACK<>NWK_MGR ] [ucst] 1F:00:51:48:08:48:10:BB:F6:03:18:BB:F6:03:21:E7:C9:27:21:00:4B:12:00:2A:0A:08:00:10:01:18:01:20:01:28:00 [17:32:11.828,664] [Z_STACK/HNDL] PKTTYPE: [ Z_STACK>>NWK_MGR ] zdoDeviceAnnounceInd [17:32:11.828,721] [Z_STACK/HNDL] PKTBODY: cmdID = ZDO_DEVICE_ANNOUNCE [17:32:11.828,763] [Z_STACK/HNDL] PKTBODY: srcAddr = 0x0000FB3B (64315) [17:32:11.828,800] [Z_STACK/HNDL] PKTBODY: devAddr = 0x0000FB3B (64315) [17:32:11.828,837] [Z_STACK/HNDL] PKTBODY: devExtAddr = 00:12:4B:00:21:27:C9:E7 [17:32:11.828,871] [Z_STACK/HNDL] PKTBODY: capInfo : [17:32:11.828,903] [Z_STACK/HNDL] PKTBODY: panCoord = 0 [17:32:11.828,991] [Z_STACK/HNDL] PKTBODY: ffd = 1 [17:32:11.829,024] [Z_STACK/HNDL] PKTBODY: mainsPower = 1 [17:32:11.829,059] [Z_STACK/HNDL] PKTBODY: rxOnWhenIdle = 1 [17:32:11.829,092] [Z_STACK/HNDL] PKTBODY: security = 0 [17:32:11.840,351] [Z_STACK/LSTN] INFO : Receive message... [17:32:11.844,439] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:65:01:00 [17:32:11.851,623] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:01:00 [17:32:11.853,919] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x01 [17:32:11.854,050] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:11.854,085] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:11.854,188] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:11.858,792] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:11.858,908] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:11.858,942] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:11.862,190] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:06:00 [17:32:11.862,444] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:06:00 [17:32:11.865,158] [NWK_MGR/HNDL] INFO : [MUTEX] Unlock SRSP Mutex [17:32:11.865,313] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00518)... [17:32:11.865,355] [NWK_MGR/HNDL] INFO : [DBG] Clearing @ 0xB6B00518 (processed 4 messages)... [17:32:11.865,386] [NWK_MGR/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:11.858,208] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:31:10:00 [17:32:11.858,365] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:11.858,432] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_IEEE_ADDR_REQ [17:32:11.858,475] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:11.869,337] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:11.869,504] [Z_STACK/LSTN] INFO : !Done [17:32:11.870,829] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01388)... [17:32:11.870,954] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01388 (processed 9 messages)... [17:32:11.870,990] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:11.894,329] [Z_STACK/READ] INFO : Received 13 bytes, subSys 0x45, cmdId 0x81 [17:32:11.894,493] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x81 [17:32:11.894,531] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01388 (received 10 messages)... [17:32:11.894,560] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01388)... [17:32:11.894,872] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:11.894,949] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B01388 [17:32:11.894,980] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:11.895,008] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x00000081, len:0x0000000D, pData:0xB6B01390 [17:32:11.895,040] [Z_STACK/HNDL] DEBUG : znp_misc handleAsyncMsgs: Unrecognized cmdID:81 [17:32:11.895,068] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01388)... [17:32:11.895,094] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01388 (processed 10 messages)... [17:32:11.895,124] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:20.646,946] [Z_STACK/LSTN] INFO : Receive message... [17:32:20.647,139] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:15 [17:32:20.647,200] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:45, len::4 [17:32:20.647,237] [Z_STACK/LSTN] INFO : preparing to send 4 bytes, subSys 0x05, cmdId 0x45, pData: [17:32:20.647,268] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:20.647,329] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:45:00 [17:32:20.657,147] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x45 [17:32:20.657,282] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:20.657,317] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:20.656,583] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:45:00 [17:32:20.661,205] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:20.661,414] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:15:10:00 [17:32:20.661,485] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:20.665,218] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:20.665,353] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:20.665,389] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:20.661,540] [Z_STACK/LSTN] PKTBODY: cmdID = DEV_NWK_ROUTE_REQ [17:32:20.664,766] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:20.669,135] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:20.669,313] [NWK_MGR/MAIN] MISC1 : NwkMgr sendZdoIeeeAddrReq: ieeeaddr 0000 [17:32:20.669,372] [NWK_MGR/MAIN] INFO : preparing to send 8 bytes, subSys 0x11, cmdId 0x31, pData: [17:32:20.669,405] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:20.669,479] [NWK_MGR/MAIN] PKT_HEX: [ Z_STACK<>NPISRVR ] [SRSP] 01:65:01:00 [17:32:20.686,445] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:01:00 [17:32:20.689,832] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x01 [17:32:20.689,961] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:20.689,997] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:20.691,402] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:0B:00 [17:32:20.691,645] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:0B:00 [17:32:20.693,977] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:20.696,271] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:20.703,004] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:31:10:00 [17:32:20.712,064] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:20.712,194] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:20.712,229] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:20.715,282] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:20.711,451] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:20.711,584] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_IEEE_ADDR_REQ [17:32:20.711,635] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:20.723,627] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:20.727,928] [Z_STACK/LSTN] INFO : !Done [17:32:20.730,446] [Z_STACK/READ] INFO : Received 13 bytes, subSys 0x45, cmdId 0x81 [17:32:20.730,582] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x81 [17:32:20.730,620] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01388 (received 11 messages)... [17:32:20.730,649] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01388)... [17:32:20.730,729] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:20.730,776] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B01388 [17:32:20.730,806] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:20.730,834] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x00000081, len:0x0000000D, pData:0xB6B01390 [17:32:20.730,865] [Z_STACK/HNDL] DEBUG : znp_misc handleAsyncMsgs: Unrecognized cmdID:81 [17:32:20.730,892] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01388)... [17:32:20.730,918] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01388 (processed 11 messages)... [17:32:20.730,947] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:29.730,102] [Z_STACK/LSTN] INFO : Receive message... [17:32:29.730,295] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:15 [17:32:29.730,354] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:45, len::4 [17:32:29.730,389] [Z_STACK/LSTN] INFO : preparing to send 4 bytes, subSys 0x05, cmdId 0x45, pData: [17:32:29.730,418] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:29.730,476] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:45:00 [17:32:29.744,322] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x45 [17:32:29.744,451] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:29.744,488] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:29.743,829] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:45:00 [17:32:29.748,289] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:29.748,501] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:15:10:00 [17:32:29.748,574] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:29.748,628] [Z_STACK/LSTN] PKTBODY: cmdID = DEV_NWK_ROUTE_REQ [17:32:29.748,669] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:29.748,877] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:29.748,941] [Z_STACK/LSTN] INFO : !Done [17:32:29.752,513] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:29.752,664] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:29.752,700] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:29.755,840] [NWK_MGR/MAIN] INFO : Waiting for synchronous response... [17:32:29.756,886] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Mutex [17:32:29.759,821] [NWK_MGR/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:29.760,849] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:29.761,790] [NWK_MGR/MAIN] MISC1 : NwkMgr sendZdoIeeeAddrReq: ieeeaddr 0000 [17:32:29.766,927] [Z_STACK/LSTN] INFO : Receive message... [17:32:29.766,205] [NWK_MGR/MAIN] INFO : preparing to send 8 bytes, subSys 0x11, cmdId 0x31, pData: [17:32:29.766,330] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:29.766,411] [NWK_MGR/MAIN] PKT_HEX: [ Z_STACK<>NPISRVR ] [SRSP] 01:65:01:00 [17:32:29.782,043] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:01:00 [17:32:29.785,273] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x01 [17:32:29.785,405] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:29.785,440] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:29.787,332] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:00:00 [17:32:29.787,575] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:00:00 [17:32:29.789,922] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:29.790,063] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:29.790,159] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:31:10:00 [17:32:29.794,393] [NWK_MGR/MAIN] INFO : Waiting for synchronous response... [17:32:29.795,532] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:29.796,456] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Mutex [17:32:29.797,297] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_IEEE_ADDR_REQ [17:32:29.798,950] [NWK_MGR/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:29.801,925] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:29.803,419] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:29.803,559] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:29.803,594] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:29.807,367] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:29.808,479] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:29.808,624] [Z_STACK/LSTN] INFO : !Done [17:32:29.826,303] [Z_STACK/READ] INFO : Received 13 bytes, subSys 0x45, cmdId 0x81 [17:32:29.826,465] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x81 [17:32:29.826,503] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01388 (received 12 messages)... [17:32:29.826,532] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01388)... [17:32:29.826,623] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:29.826,668] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B01388 [17:32:29.826,697] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:29.826,725] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x00000081, len:0x0000000D, pData:0xB6B01390 [17:32:29.826,756] [Z_STACK/HNDL] DEBUG : znp_misc handleAsyncMsgs: Unrecognized cmdID:81 [17:32:29.826,784] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01388)... [17:32:29.826,810] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01388 (processed 12 messages)... [17:32:29.826,839] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:38.819,431] [Z_STACK/LSTN] INFO : Receive message... [17:32:38.819,560] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:15 [17:32:38.819,614] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:45, len::4 [17:32:38.819,649] [Z_STACK/LSTN] INFO : preparing to send 4 bytes, subSys 0x05, cmdId 0x45, pData: [17:32:38.819,679] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:38.819,737] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:45:00 [17:32:38.834,896] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x45 [17:32:38.835,024] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:38.835,062] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:38.834,347] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:45:00 [17:32:38.838,848] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:38.839,063] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:15:10:00 [17:32:38.839,137] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:38.839,191] [Z_STACK/LSTN] PKTBODY: cmdID = DEV_NWK_ROUTE_REQ [17:32:38.839,232] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:38.839,441] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:38.839,502] [Z_STACK/LSTN] INFO : !Done [17:32:38.842,974] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:38.843,127] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:38.843,164] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:38.845,989] [NWK_MGR/MAIN] INFO : Waiting for synchronous response... [17:32:38.846,116] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Mutex [17:32:38.846,149] [NWK_MGR/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:38.846,177] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:38.846,224] [NWK_MGR/MAIN] MISC1 : NwkMgr sendZdoIeeeAddrReq: ieeeaddr 0000 [17:32:38.846,269] [NWK_MGR/MAIN] INFO : preparing to send 8 bytes, subSys 0x11, cmdId 0x31, pData: [17:32:38.846,300] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [17:32:38.846,371] [NWK_MGR/MAIN] PKT_HEX: [ Z_STACK<>NPISRVR ] [SRSP] 01:65:01:00 [17:32:38.861,995] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:01:00 [17:32:38.865,460] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x01 [17:32:38.865,761] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:38.865,806] [Z_STACK/READ] INFO : Client Read: (len 4): [17:32:38.867,549] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:00:00 [17:32:38.867,824] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 0D:45:81:00:34:57:49:01:00:4B:12:00:00:00:00:00 [17:32:38.869,906] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [17:32:38.872,158] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [17:32:38.874,001] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:38.876,342] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:31:10:00 [17:32:38.878,052] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [17:32:38.878,175] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [17:32:38.878,210] [NWK_MGR/READ] INFO : Client Read: (len 8): [17:32:38.877,376] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [17:32:38.877,500] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_IEEE_ADDR_REQ [17:32:38.877,549] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [17:32:38.884,126] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [17:32:38.885,432] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [17:32:38.888,723] [Z_STACK/LSTN] INFO : !Done [17:32:38.906,600] [Z_STACK/READ] INFO : Received 13 bytes, subSys 0x45, cmdId 0x81 [17:32:38.906,759] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x81 [17:32:38.906,799] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B01388 (received 13 messages)... [17:32:38.906,827] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B01388)... [17:32:38.906,916] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [17:32:38.906,964] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B01388 [17:32:38.906,993] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [17:32:38.907,021] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x00000081, len:0x0000000D, pData:0xB6B01390 [17:32:38.907,052] [Z_STACK/HNDL] DEBUG : znp_misc handleAsyncMsgs: Unrecognized cmdID:81 [17:32:38.907,080] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B01388)... [17:32:38.907,106] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B01388 (processed 13 messages)... [17:32:38.907,135] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [17:32:47.895,886] [NWK_MGR/MAIN] INFO : AddDevice: Retrying state 2, remaining tries: 0 [17:32:47.896,059] [NWK_MGR/MAIN] INFO : AddDevice: Failed to get response from target node, state 2 [17:32:47.896,103] [NWK_MGR/MAIN] INFO : device 00124B002127C9E7 [17:32:47.896,138] [NWK_MGR/MAIN] INFO : [17:32:47.896,167] [NWK_MGR/MAIN] DEBUG : - AddDevice Failed 1 on 00124B002127C9E7 [17:32:47.896,197] [NWK_MGR/MAIN] INFO : zNwkSrv_AD_FreeStateMachine: Entered pState=0xb6d00520 [17:32:47.896,225] [NWK_MGR/MAIN] INFO : zNwkSrv_AD_FreeStateMachine: State machine freed [17:32:47.896,252] [NWK_MGR/MAIN] INFO : nwkMgrDb_FreeDeviceInfo: Entered pDeviceInfo=0xb6d006e8 [17:32:47.896,279] [NWK_MGR/MAIN] INFO : nwkMgrDb_FreeDeviceInfoSubstructures: Entered pDeviceInfo=0xb6d006e8 [17:32:47.896,306] [NWK_MGR/MAIN] INFO : nwkMgrDb_FreeDeviceInfoSubstructures: Exit [17:32:47.896,335] [NWK_MGR/MAIN] INFO : zNwkSrv_AD_FreeStateMachine: Exit