[07:50:12.834,459] [NWK_MGR/LSTN] INFO : Receive message... [07:50:12.834,689] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:2B:80:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:12.852,989] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:12.853,126] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:12.853,163] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:12.852,367] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:2B:80:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:12.857,197] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:12.857,355] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [07:50:12.857,401] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [07:50:12.857,433] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:12.857,498] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:36:00 [07:50:12.868,471] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [07:50:12.868,600] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:12.868,635] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:12.868,067] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [07:50:12.872,287] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:12.872,443] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [07:50:12.872,491] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [07:50:12.872,524] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:12.872,588] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:36:00 [07:50:12.892,629] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [07:50:12.894,171] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [07:50:12.894,336] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:12.894,372] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:12.896,199] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [07:50:12.896,333] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:12.896,366] [NWK_MGR/READ] INFO : Client Read: (len 8): [07:50:12.898,509] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:12.898,655] [NWK_MGR/LSTN] MISC1 : Sending ZigBee Generic Confirmation [07:50:12.898,746] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>SMPLAPP] [ucst] 04:00:72:00:08:00:10:00 [07:50:12.898,797] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkZigbeeGenericCnf [07:50:12.898,842] [NWK_MGR/LSTN] PKTBODY: cmdId = ZIGBEE_GENERIC_CNF [07:50:12.898,880] [NWK_MGR/LSTN] PKTBODY: status = STATUS_SUCCESS [07:50:12.895,342] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:12.895,520] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:43:10:00 [07:50:12.895,588] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [07:50:12.895,644] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_MGMT_PERMIT_JOIN_REQ [07:50:12.895,686] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:12.908,658] [NWK_MGR/LSTN] INFO : ...sent 8 bytes to Client [07:50:12.911,565] [NWK_MGR/LSTN] INFO : !Done [07:50:12.913,003] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:12.913,164] [Z_STACK/LSTN] INFO : !Done [07:50:41.730,916] [Z_STACK/LSTN] INFO : Receive message... [07:50:41.731,073] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:0 [07:50:41.731,132] [Z_STACK/LSTN] INFO : znp_misc znpReset: mode:1, nvReset:1, shutdown:0 [07:50:41.731,167] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:9, len::5 [07:50:41.731,200] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x01, cmdId 0x09, pData: [07:50:41.731,229] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:41.731,291] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:61:09:00 [07:50:41.768,013] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x09 [07:50:41.768,160] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:41.768,198] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:41.771,097] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [07:50:41.771,362] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:41.771,406] [NWK_MGR/READ] INFO : Client Read: (len 8): [07:50:41.767,234] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:09:00 [07:50:41.769,880] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:41.770,009] [Z_STACK/LSTN] INFO : znp_misc znpReset: parameter:1 [07:50:41.770,047] [Z_STACK/LSTN] INFO : znp_misc sendAPICZNP: subSys:1, cmdID:0, len:1 [07:50:41.770,081] [Z_STACK/LSTN] INFO : trying to send 1 bytes, subSys 0x01, cmdId 0x00 [07:50:41.770,139] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NWK_MGR ] [ucst] 04:00:71:FA:08:00:10:00 [07:50:41.770,543] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [07:50:41.770,596] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_RESET_REQ [07:50:41.770,637] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:41.770,796] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:41.770,850] [Z_STACK/LSTN] INFO : !Done [07:50:41.777,052] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [ASNC] 06:41:80:00:02:00:02:07:02 [07:50:43.649,308] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [SRSP] 01:64:04:00 [07:50:43.653,949] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x04 [07:50:43.654,119] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:43.654,159] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:43.652,301] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:04:00 [07:50:43.658,130] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:43.658,278] [Z_STACK/LSTN] INFO : Receive message... [07:50:43.658,341] [Z_STACK/LSTN] INFO : Will disconnect #6 [07:50:43.658,375] [Z_STACK/LSTN] INFO : Done with 6 [07:50:43.658,404] [Z_STACK/LSTN] CONNECT: Disconnected from client GATEWAY (connection # 6) [07:50:43.658,888] [Z_STACK/LSTN] MISC1 : Removing connection #6 [07:50:43.658,978] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:4, cmdID:4, len::1 [07:50:43.659,018] [Z_STACK/LSTN] INFO : preparing to send 1 bytes, subSys 0x04, cmdId 0x04, pData: [07:50:43.659,051] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:43.659,104] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:64:04:01 [07:50:43.672,206] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x04 [07:50:43.672,329] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:43.672,364] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:43.671,823] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:04:01 [07:50:43.676,421] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:43.676,573] [Z_STACK/LSTN] DEBUG : --> status failed: cmdID:4, status: 1 [07:50:43.676,625] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:4, cmdID:4, len::1 [07:50:43.676,661] [Z_STACK/LSTN] INFO : preparing to send 1 bytes, subSys 0x04, cmdId 0x04, pData: [07:50:43.676,693] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:43.676,749] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:64:04:01 [07:50:43.689,338] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:04:01 [07:50:43.693,022] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:43.694,443] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x04 [07:50:43.694,570] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:43.694,608] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:43.697,700] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:43.697,833] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:43.697,872] [Z_STACK/LSTN] DEBUG : --> status failed: cmdID:4, status: 1 [07:50:47.029,580] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:64:04:01 [07:50:47.036,225] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:04:01 [07:50:47.036,981] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x04 [07:50:47.037,107] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:47.037,142] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:47.039,460] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [07:50:47.039,552] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:47.039,653] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:47.039,685] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:47.039,714] [Z_STACK/LSTN] DEBUG : --> status failed: cmdID:4, status: 1 [07:50:50.119,729] [NPISRVR/MAIN] UNMSKBL: [07:50:50.120,209] [NPISRVR/MAIN] UNMSKBL: ************************************************ [07:50:50.120,251] [NPISRVR/MAIN] UNMSKBL: * NPI Server v1.0.2d * [07:50:50.120,280] [NPISRVR/MAIN] UNMSKBL: ************************************************ [07:50:50.120,307] [NPISRVR/MAIN] UNMSKBL: [07:50:50.183,732] [Z_STACK/MAIN] MISC1 : Port: 2533 [07:50:50.187,438] [Z_STACK/MAIN] MISC1 : IP addresses for 127.0.0.1: [07:50:50.190,656] [Z_STACK/MAIN] MISC1 : IPv4: 127.0.0.1 [07:50:50.193,998] [Z_STACK/MAIN] MISC1 : Trying to connect... [07:50:50.198,511] [Z_STACK/MAIN] MISC1 : Connected. [07:50:50.201,632] [Z_STACK/MAIN] UNMSKBL: [07:50:50.204,652] [Z_STACK/MAIN] UNMSKBL: ************************************************ [07:50:50.207,695] [Z_STACK/MAIN] UNMSKBL: * ZStack Server for ZNP v1.0.2d * [07:50:50.208,730] [Z_STACK/MAIN] UNMSKBL: ************************************************ [07:50:50.209,750] [Z_STACK/MAIN] UNMSKBL: [07:50:50.210,844] [Z_STACK/MAIN] INFO : trying to send 8 bytes, subSys 0x1F, cmdId 0x00 [07:50:50.214,094] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:3E:00 [07:50:50.239,957] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:3E:00 [07:50:50.243,526] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x3E [07:50:50.245,098] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.247,774] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:50.248,642] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Mutex [07:50:50.249,481] [Z_STACK/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:50.251,741] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.252,511] [Z_STACK/MAIN] INFO : zstackpb config: defaultChannelList:2000, configPANID:ffff, deviceType:0 [07:50:50.253,338] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:f, cmdID:9, len::1 [07:50:50.253,927] [Z_STACK/MAIN] INFO : preparing to send 1 bytes, subSys 0x0F, cmdId 0x09, pData: [07:50:50.257,026] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.258,134] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:6F:09:00 [07:50:50.268,071] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:09:00 [07:50:50.264,293] [Z_STACK/MAIN] INFO : Waiting for synchronous response... [07:50:50.273,173] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x6F, cmdId 0x09 [07:50:50.273,308] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.273,344] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:50.275,113] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:6F:06:00 [07:50:50.279,983] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:06:00 [07:50:50.274,369] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Mutex [07:50:50.274,488] [Z_STACK/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:50.274,523] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.274,558] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:f, cmdID:6, len::1 [07:50:50.274,595] [Z_STACK/MAIN] INFO : preparing to send 1 bytes, subSys 0x0F, cmdId 0x06, pData: [07:50:50.274,625] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.274,679] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:6F:02:00 [07:50:50.307,936] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:02:00 [07:50:50.308,432] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x6F, cmdId 0x02 [07:50:50.308,563] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.308,599] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:50.312,663] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.316,512] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:6F:08:00 [07:50:50.322,922] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:08:00 [07:50:50.315,536] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:f, cmdID:8, len::5 [07:50:50.315,655] [Z_STACK/MAIN] INFO : preparing to send 5 bytes, subSys 0x0F, cmdId 0x08, pData: [07:50:50.315,695] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.316,019] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:6F:08:00 [07:50:50.341,321] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:08:00 [07:50:50.333,505] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.333,613] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:f, cmdID:8, len::5 [07:50:50.333,658] [Z_STACK/MAIN] INFO : preparing to send 5 bytes, subSys 0x0F, cmdId 0x08, pData: [07:50:50.333,691] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.333,757] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:61:09:00 [07:50:50.370,813] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:09:00 [07:50:50.371,245] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x09 [07:50:50.371,368] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.371,403] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:50.373,844] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.375,550] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:61:09:00 [07:50:50.388,150] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:09:00 [07:50:50.388,579] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x09 [07:50:50.388,694] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.388,729] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:50.393,969] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 03:60:00:02:21:15 [07:50:50.400,601] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:15:FF [07:50:50.393,163] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.393,327] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:15, len::7 [07:50:50.393,376] [Z_STACK/MAIN] INFO : preparing to send 7 bytes, subSys 0x01, cmdId 0x15, pData: [07:50:50.393,409] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.393,477] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR< status failed: cmdID:15, status: 255 [07:50:50.409,342] [Z_STACK/MAIN] INFO : Port: 2536 [07:50:50.410,571] [Z_STACK/MAIN] INFO : Following IP addresses are available: [07:50:50.411,193] [Z_STACK/MAIN] INFO : IPv4: interface: lo IP Address 127.0.0.1 [07:50:50.411,259] [Z_STACK/MAIN] INFO : IPv4: interface: eth0 IP Address 10.27.1.22 [07:50:50.411,291] [Z_STACK/MAIN] INFO : IPv4: interface: usb0 IP Address 192.168.7.2 [07:50:50.411,322] [Z_STACK/MAIN] INFO : IPv4: interface: usb1 IP Address 192.168.6.2 [07:50:50.411,353] [Z_STACK/MAIN] INFO : IPv6: interface: lo IP Address ::1 [07:50:50.411,385] [Z_STACK/MAIN] INFO : IPv6: interface: eth0 IP Address fe80::9259:afff:fe54:d14d [07:50:50.411,426] [Z_STACK/MAIN] INFO : The socket will listen on the following IP addresses: [07:50:50.411,458] [Z_STACK/MAIN] INFO : IPv4: 0.0.0.0 [07:50:50.411,486] [Z_STACK/MAIN] INFO : IPv6: :: [07:50:50.411,512] [Z_STACK/MAIN] INFO : 0.0.0.0 means it will listen to all available IP address [07:50:50.411,643] [Z_STACK/MAIN] MISC1 : waiting for first connection on #4... [07:50:50.440,783] [Z_STACK/MAIN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:f, cmdID:5, len::1 [07:50:50.442,094] [Z_STACK/MAIN] INFO : preparing to send 1 bytes, subSys 0x0F, cmdId 0x05, pData: [07:50:50.445,297] [Z_STACK/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.446,429] [Z_STACK/MAIN] PKT_HEX: [ NPISRVR<>NWK_MGR ] [ucst] 04:00:71:FA:08:14:10:00 [07:50:50.672,379] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [07:50:50.672,481] [Z_STACK/LSTN] PKTBODY: cmdID = DEV_ZDO_CBS_REQ [07:50:50.672,522] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:50.672,684] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:50.672,744] [Z_STACK/LSTN] INFO : !Done [07:50:50.671,488] [NWK_MGR/MAIN] PKTBODY: mgmtPermitJoinRsp = 1 [07:50:50.671,591] [NWK_MGR/MAIN] PKTBODY: mgmtNwkUpdateNotify = 1 [07:50:50.671,634] [NWK_MGR/MAIN] PKTBODY: deviceAnnounce = 1 [07:50:50.671,670] [NWK_MGR/MAIN] PKTBODY: devStateChange = 1 [07:50:50.671,707] [NWK_MGR/MAIN] PKTBODY: tcDeviceInd = 1 [07:50:50.678,795] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [07:50:50.678,954] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:50.678,992] [NWK_MGR/READ] INFO : Client Read: (len 8): [07:50:50.681,158] [NWK_MGR/MAIN] INFO : Waiting for synchronous response... [07:50:50.684,740] [Z_STACK/LSTN] INFO : Receive message... [07:50:50.685,035] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [07:50:50.685,089] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:50:50.685,124] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.683,938] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Mutex [07:50:50.684,050] [NWK_MGR/MAIN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:50.684,085] [NWK_MGR/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:50.684,134] [NWK_MGR/MAIN] MISC1 : ZDO Callback Register Response Successful [07:50:50.684,167] [NWK_MGR/MAIN] MISC1 : NwkMgr sendSysNwkInfoReadReqAndSetTxPower: [07:50:50.684,208] [NWK_MGR/MAIN] INFO : preparing to send 2 bytes, subSys 0x11, cmdId 0x08, pData: [07:50:50.684,238] [NWK_MGR/MAIN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:50.684,299] [NWK_MGR/MAIN] PKT_HEX: [ Z_STACK<>NPISRVR ] [SRSP] 01:6F:05:00 [07:50:51.677,995] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x6F, cmdId 0x05 [07:50:51.678,147] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.678,185] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:51.676,652] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:6F:05:00 [07:50:51.679,108] [Z_STACK/MAIN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:51.679,382] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:51.685,264] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 03:4F:80:01:02:04 [07:50:51.685,387] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:51.685,701] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 03:4F:80:01:02:04 [07:50:51.688,455] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:51.688,596] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:51.688,633] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 1 messages)... [07:50:51.688,664] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:51.688,778] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:51.688,962] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:51.688,998] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:51.689,026] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:51.689,057] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:51.689,084] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 1 messages)... [07:50:51.689,114] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:51.692,561] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [SRSP] 18:65:50:00:00:08:F8:CA:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:51.695,704] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:08:F8:CA:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:51.729,508] [Z_STACK/READ] INFO : Received 3 bytes, subSys 0x4F, cmdId 0x80 [07:50:51.729,666] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x80 [07:50:51.729,703] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 2 messages)... [07:50:51.729,734] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:51.729,812] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:51.729,852] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.729,879] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:51.729,964] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:51.730,010] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:51.730,039] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:51.730,067] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x0000000F, cmdId:0x00000080, len:0x00000003, pData:0xB6B00498 [07:50:51.730,182] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:51.730,219] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 2 messages)... [07:50:51.730,250] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:51.730,374] [Z_STACK/MAIN] INFO : zstackpb: AppCnfCommissioningNotification: state:1 [07:50:51.730,420] [Z_STACK/MAIN] INFO : zstackpb: AppCnfCommissioningNotification: commMode:2 [07:50:51.730,451] [Z_STACK/MAIN] INFO : zstackpb: AppCnfCommissioningNotification: remainingCommMode:4 [07:50:51.730,540] [Z_STACK/MAIN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 09:00:51:E3:08:E3:01:10:04:18:00:20:00 [07:50:51.730,600] [Z_STACK/MAIN] PKTTYPE: [ Z_STACK>>NWK_MGR ] AppCnfCommissioningNotification [07:50:51.730,652] [Z_STACK/MAIN] PKTBODY: cmdID = [07:50:51.730,691] [Z_STACK/MAIN] PKTBODY: status = TL_NOT_AA_CAPABLE [07:50:51.730,728] [Z_STACK/MAIN] PKTBODY: commMode = 0x00000000 (0) [07:50:51.730,763] [Z_STACK/MAIN] PKTBODY: remainingCommMode = 0x00000000 (0) [07:50:51.730,997] [Z_STACK/MAIN] INFO : ...sent 13 bytes to Client [07:50:51.731,776] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:51.731,838] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:51.731,870] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:1, len:8 [07:50:51.731,900] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:51.731,929] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:51.731,983] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 0A:61:08:00:08:34:57:49:01:00:4B:12:00 [07:50:51.751,059] [Z_STACK/READ] INFO : Received 10 bytes, subSys 0x61, cmdId 0x08 [07:50:51.751,250] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.751,288] [Z_STACK/READ] INFO : Client Read: (len 13): [07:50:51.753,064] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:51.753,361] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:08:28:F8:95:03: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:00 [07:50:51.753,427] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:51.753,487] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:51.753,530] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:51.753,570] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:51.753,605] [Z_STACK/LSTN] PKTBODY: devState = COORD_STARTING [07:50:51.753,640] [Z_STACK/LSTN] PKTBODY: panId = 0x0000CAF8 (51960) [07:50:51.753,676] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:51.753,710] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:51.753,746] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:51.753,779] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:51.753,810] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:51.753,845] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:51.753,878] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:51.753,914] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x00000000 (0) [07:50:51.754,010] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:51.754,053] [Z_STACK/LSTN] INFO : !Done [07:50:51.748,385] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 0A:61:08:00:08:34:57:49:01:00:4B:12:00 [07:50:51.773,564] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:51.773,728] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.773,767] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:51.775,664] [Z_STACK/LSTN] INFO : Receive message... [07:50:51.775,846] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [07:50:51.775,895] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:50:51.775,929] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:51.775,981] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:08:F8:CA:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:51.793,862] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:08:F8:CA:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:51.794,343] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:51.794,461] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.794,497] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:51.798,689] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:51.798,848] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:51.799,050] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:08:28:F8:95:03: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:00 [07:50:51.799,106] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:51.799,169] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:51.799,211] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:51.799,250] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:51.799,284] [Z_STACK/LSTN] PKTBODY: devState = COORD_STARTING [07:50:51.799,317] [Z_STACK/LSTN] PKTBODY: panId = 0x0000CAF8 (51960) [07:50:51.799,354] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:51.799,387] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:51.799,424] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:51.799,457] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:51.799,488] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:51.799,523] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:51.799,556] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:51.799,591] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x00000000 (0) [07:50:51.799,915] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:51.800,039] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:51.800,074] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:51.800,175] [NWK_MGR/HNDL] INFO : [MUTEX] Unlock SRSP Mutex [07:50:51.800,253] [NWK_MGR/HNDL] MISC1 : NwkInfoReadRsp: [07:50:51.800,288] [NWK_MGR/HNDL] MISC1 : - nwkaddr:0000 [07:50:51.800,316] [NWK_MGR/HNDL] MISC1 : - ieeeaddr:00124B0001495734 [07:50:51.800,346] [NWK_MGR/HNDL] MISC1 : - devstate:8 [07:50:51.800,373] [NWK_MGR/HNDL] MISC1 : - panid:CAF8 [07:50:51.800,399] [NWK_MGR/HNDL] MISC1 : - extendedpanid:00124B0001495734 [07:50:51.800,425] [NWK_MGR/HNDL] MISC1 : - coordaddr:0000 [07:50:51.800,452] [NWK_MGR/HNDL] MISC1 : - coordextaddr:0000000000000000 [07:50:51.800,478] [NWK_MGR/HNDL] MISC1 : - devtypes: Coordinator: 1 [07:50:51.800,505] [NWK_MGR/HNDL] MISC1 : - devtypes: Router: 0 [07:50:51.800,530] [NWK_MGR/HNDL] MISC1 : - logicalchannel:0 [07:50:51.800,569] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6A004A0)... [07:50:51.800,598] [NWK_MGR/HNDL] INFO : [DBG] Clearing @ 0xB6A004A0 (processed 1 messages)... [07:50:51.800,627] [NWK_MGR/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:51.807,361] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:51.808,475] [Z_STACK/LSTN] INFO : !Done [07:50:51.812,545] [NWK_MGR/MAIN] MISC1 : Parsing gateway_config.tlg [07:50:51.816,983] [NWK_MGR/MAIN] DEBUG : parsing defines [07:50:51.819,902] [NWK_MGR/MAIN] DEBUG : parsing attributes [07:50:51.823,485] [NWK_MGR/MAIN] DEBUG : parsing attrlists [07:50:51.825,676] [NWK_MGR/MAIN] DEBUG : parsing clusterlists [07:50:51.829,671] [NWK_MGR/MAIN] DEBUG : parsing endpointdefs [07:50:51.832,655] [NWK_MGR/MAIN] DEBUG : parsing endpoints [07:50:51.836,150] [NWK_MGR/MAIN] MISC1 : 2 app endpoints parsed in gateway_config.tlg [07:50:51.839,471] [NWK_MGR/MAIN] DEBUG : Endpoint 4 [07:50:51.840,693] [NWK_MGR/MAIN] DEBUG : profileid 0104 [07:50:51.843,663] [NWK_MGR/MAIN] DEBUG : deviceid 0007 [07:50:51.845,010] [NWK_MGR/MAIN] DEBUG : devicever 0 [07:50:51.847,886] [NWK_MGR/MAIN] DEBUG : inclusters 3: [07:50:51.849,049] [NWK_MGR/MAIN] DEBUG : 0000 [07:50:51.849,936] [NWK_MGR/MAIN] DEBUG : 0003 [07:50:51.852,382] [NWK_MGR/MAIN] DEBUG : 0501 [07:50:51.853,536] [NWK_MGR/MAIN] DEBUG : [07:50:51.854,406] [NWK_MGR/MAIN] DEBUG : outclusters 7: [07:50:51.856,987] [NWK_MGR/MAIN] DEBUG : 0006 [07:50:51.858,026] [NWK_MGR/MAIN] DEBUG : 0004 [07:50:51.858,878] [NWK_MGR/MAIN] DEBUG : 0005 [07:50:51.861,294] [NWK_MGR/MAIN] DEBUG : 0500 [07:50:51.862,082] [NWK_MGR/MAIN] DEBUG : 0009 [07:50:51.862,655] [NWK_MGR/MAIN] DEBUG : 0101 [07:50:51.863,147] [NWK_MGR/MAIN] DEBUG : 0020 [07:50:51.865,542] [NWK_MGR/MAIN] DEBUG : [07:50:51.866,329] [NWK_MGR/MAIN] DEBUG : 5 Attributes: [07:50:51.866,927] [NWK_MGR/MAIN] DEBUG : { Cluster, AttrId, type, accesscontrol, value } [07:50:51.867,460] [NWK_MGR/MAIN] DEBUG : { 0003, 0000, 21, 03, 0000} [07:50:51.869,172] [NWK_MGR/MAIN] DEBUG : { 0003, FFFD, 20, 01, 00} [07:50:51.869,951] [NWK_MGR/MAIN] DEBUG : { 0000, 0000, 20, 01, 00} [07:50:51.870,522] [NWK_MGR/MAIN] DEBUG : { 0000, 0007, 30, 01, 00} [07:50:51.871,839] [NWK_MGR/MAIN] DEBUG : { 0000, FFFD, 20, 01, 00} [07:50:51.876,114] [NWK_MGR/MAIN] DEBUG : Endpoint 242 [07:50:51.877,335] [NWK_MGR/MAIN] DEBUG : profileid A1E0 [07:50:51.878,234] [NWK_MGR/MAIN] DEBUG : deviceid 0061 [07:50:51.878,988] [NWK_MGR/MAIN] DEBUG : devicever 0 [07:50:51.879,687] [NWK_MGR/MAIN] DEBUG : inclusters 0: [07:50:51.881,062] [NWK_MGR/MAIN] DEBUG : [07:50:51.883,984] [NWK_MGR/MAIN] DEBUG : outclusters 1: [07:50:51.884,111] [NWK_MGR/MAIN] DEBUG : 0021 [07:50:51.884,149] [NWK_MGR/MAIN] DEBUG : [07:50:51.884,178] [NWK_MGR/MAIN] DEBUG : 10 Attributes: [07:50:51.884,205] [NWK_MGR/MAIN] DEBUG : { Cluster, AttrId, type, accesscontrol, value } [07:50:51.884,237] [NWK_MGR/MAIN] DEBUG : { 0021, 0010, 20, 01, 00} [07:50:51.884,269] [NWK_MGR/MAIN] DEBUG : { 0021, 0011, 43, 01, uknown size for type=43} [07:50:51.884,300] [NWK_MGR/MAIN] DEBUG : { 0021, 0012, 20, 03, 00} [07:50:51.884,331] [NWK_MGR/MAIN] DEBUG : { 0021, 0013, 20, 03, 00} [07:50:51.884,361] [NWK_MGR/MAIN] DEBUG : { 0021, 0016, 1A, 01, uknown size for type=1A} [07:50:51.884,391] [NWK_MGR/MAIN] DEBUG : { 0021, 0017, 1A, 03, uknown size for type=1A} [07:50:51.884,422] [NWK_MGR/MAIN] DEBUG : { 0021, 0020, 18, 03, 00} [07:50:51.884,452] [NWK_MGR/MAIN] DEBUG : { 0021, 0021, F1, 03, uknown size for type=F1} [07:50:51.884,482] [NWK_MGR/MAIN] DEBUG : { 0021, 0022, F1, 03, uknown size for type=F1} [07:50:51.884,512] [NWK_MGR/MAIN] DEBUG : { 0021, FFFD, 20, 01, 00} [07:50:51.884,594] [NWK_MGR/MAIN] INFO : Port: 2540 [07:50:51.888,630] [NWK_MGR/MAIN] INFO : Following IP addresses are available: [07:50:51.892,532] [NWK_MGR/MAIN] INFO : IPv4: interface: lo IP Address 127.0.0.1 [07:50:51.895,539] [NWK_MGR/MAIN] INFO : IPv4: interface: eth0 IP Address 10.27.1.22 [07:50:51.898,111] [NWK_MGR/MAIN] INFO : IPv4: interface: usb0 IP Address 192.168.7.2 [07:50:51.902,014] [NWK_MGR/MAIN] INFO : IPv4: interface: usb1 IP Address 192.168.6.2 [07:50:51.903,040] [NWK_MGR/MAIN] INFO : IPv6: interface: lo IP Address ::1 [07:50:51.903,862] [NWK_MGR/MAIN] INFO : IPv6: interface: eth0 IP Address fe80::9259:afff:fe54:d14d [07:50:51.908,172] [NWK_MGR/MAIN] INFO : The socket will listen on the following IP addresses: [07:50:51.909,435] [NWK_MGR/MAIN] INFO : IPv4: 0.0.0.0 [07:50:51.910,335] [NWK_MGR/MAIN] INFO : IPv6: :: [07:50:51.913,194] [NWK_MGR/MAIN] INFO : 0.0.0.0 means it will listen to all available IP address [07:50:51.914,412] [NWK_MGR/MAIN] MISC1 : waiting for first connection on #6... [07:50:51.945,802] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:51.948,688] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:51.950,539] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:51.950,706] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:51.950,745] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 3 messages)... [07:50:51.950,775] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:51.953,138] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:51.953,267] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:51.953,299] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:51.953,328] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:51.953,360] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:51.953,386] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 3 messages)... [07:50:51.953,416] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:52.047,041] [Z_STACK/LSTN] MISC1 : Connected to #6.(127.0.0.1 / ::) [07:50:52.047,195] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb - connected: 6 [07:50:52.047,238] [Z_STACK/LSTN] CONNECT: Connected to new client, connection # 6 [07:50:52.054,371] [NWK_MGR/LSTN] MISC1 : Connected to #7.(127.0.0.1 / ::) [07:50:52.054,518] [NWK_MGR/LSTN] CONNECT: Connected to new client, connection # 7 [07:50:52.059,852] [GATEWAY/MAIN] UNMSKBL: [07:50:52.063,865] [GATEWAY/MAIN] UNMSKBL: ************************************************ [07:50:52.065,346] [GATEWAY/MAIN] UNMSKBL: * Gateway Server v1.0.2d * [07:50:52.067,944] [GATEWAY/MAIN] UNMSKBL: ************************************************ [07:50:52.069,397] [GATEWAY/MAIN] UNMSKBL: [07:50:52.072,354] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<<>NPISRVR ] [SRSP] 01:64:00:00 [07:50:52.139,114] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x00 [07:50:52.139,247] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.139,282] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.138,604] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:00:00 [07:50:52.145,509] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.146,800] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 04:00:71:FA:08:20:10:00 [07:50:52.146,944] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] zstackDefaultRsp [07:50:52.147,005] [Z_STACK/LSTN] PKTBODY: cmdID = AF_REGISTER_REQ [07:50:52.147,049] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:52.150,831] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:52.150,980] [Z_STACK/LSTN] INFO : !Done [07:50:52.152,140] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<<>NPISRVR ] [SRSP] 01:64:00:00 [07:50:52.181,548] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x00 [07:50:52.181,671] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.181,708] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.181,105] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:00:00 [07:50:52.185,489] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.185,706] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 04:00:71:FA:08:20:10:00 [07:50:52.185,779] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] zstackDefaultRsp [07:50:52.185,835] [Z_STACK/LSTN] PKTBODY: cmdID = AF_REGISTER_REQ [07:50:52.185,875] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:52.186,088] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:52.186,151] [Z_STACK/LSTN] INFO : !Done [07:50:52.189,761] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:08:02:F7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:52.210,841] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:52.211,026] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:52.214,292] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:08:02:F7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:00 [07:50:52.217,494] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:52.217,636] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:52.217,676] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 4 messages)... [07:50:52.217,706] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:52.218,675] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:52.218,747] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:52.218,776] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:52.218,805] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:52.218,837] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:52.218,863] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 4 messages)... [07:50:52.218,892] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:52.221,999] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.222,120] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.254,121] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:52.254,282] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.254,317] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:52.254,615] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.254,700] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:52.254,896] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:08:28:82:EE:03: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:00 [07:50:52.254,952] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] sysNwkInfoReadRsp [07:50:52.255,010] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:52.255,050] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:52.255,089] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:52.255,124] [Z_STACK/LSTN] PKTBODY: devState = COORD_STARTING [07:50:52.255,158] [Z_STACK/LSTN] PKTBODY: panId = 0x0000F702 (63234) [07:50:52.255,195] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:52.255,228] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:52.255,266] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:52.255,299] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:52.255,330] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:52.255,365] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:52.255,398] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:52.255,434] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x00000000 (0) [07:50:52.258,279] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.260,105] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:52.263,187] [GATEWAY/MAIN] PKTBODY: action = 0x00000000 (0) [07:50:52.264,265] [Z_STACK/LSTN] INFO : !Done [07:50:52.265,550] [GATEWAY/MAIN] PKTBODY: id = 0x00000310 (784) [07:50:52.268,418] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.270,015] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.273,271] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.276,314] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.277,534] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:7, len::65 [07:50:52.280,622] [Z_STACK/LSTN] INFO : preparing to send 65 bytes, subSys 0x01, cmdId 0x07, pData: [07:50:52.282,853] [NPISRVR/MAIN] PKT_HEX: [SOCZIGB<>NPISRVR ] [SRSP] 01:61:07:00 [07:50:52.289,112] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:07:00 [07:50:52.292,336] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x07 [07:50:52.292,479] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.292,513] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.295,561] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [07:50:52.297,188] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.299,665] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.299,775] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.300,304] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:00:18:90:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [07:50:52.300,389] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.300,447] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.300,489] [Z_STACK/LSTN] PKTBODY: action = 0x00000000 (0) [07:50:52.300,523] [Z_STACK/LSTN] PKTBODY: id = 0x00000310 (784) [07:50:52.300,558] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.300,730] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.304,458] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.309,090] [GATEWAY/MAIN] PKTBODY: action = 0x00000001 (1) [07:50:52.309,209] [GATEWAY/MAIN] PKTBODY: id = 0x00000310 (784) [07:50:52.309,251] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.309,427] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.312,356] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.315,963] [Z_STACK/LSTN] INFO : !Done [07:50:52.318,910] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.320,194] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.321,234] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:310, len:65 [07:50:52.324,126] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:52.325,326] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.326,396] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.339,575] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.343,286] [Z_STACK/READ] INFO : Received 67 bytes, subSys 0x61, cmdId 0x08 [07:50:52.343,422] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.343,457] [Z_STACK/READ] INFO : Client Read: (len 70): [07:50:52.345,083] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.346,079] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.349,059] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.350,247] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:01:18:90:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.353,498] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.354,595] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.355,456] [Z_STACK/LSTN] PKTBODY: action = 0x00000001 (1) [07:50:52.356,197] [Z_STACK/LSTN] PKTBODY: id = 0x00000310 (784) [07:50:52.359,004] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.360,370] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.363,922] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.364,111] [GATEWAY/MAIN] PKTBODY: action = 0x00000000 (0) [07:50:52.364,148] [GATEWAY/MAIN] PKTBODY: id = 0x00000311 (785) [07:50:52.364,182] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.364,355] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.367,967] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.369,293] [Z_STACK/LSTN] INFO : !Done [07:50:52.372,357] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.373,615] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.374,679] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:7, len::65 [07:50:52.375,492] [Z_STACK/LSTN] INFO : preparing to send 65 bytes, subSys 0x01, cmdId 0x07, pData: [07:50:52.377,255] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.379,987] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:61:07:00 [07:50:52.391,104] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:07:00 [07:50:52.395,674] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x07 [07:50:52.395,805] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.395,840] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.397,224] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.399,864] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.401,564] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.402,750] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:00:18:91:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [07:50:52.405,224] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.406,323] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.407,347] [Z_STACK/LSTN] PKTBODY: action = 0x00000000 (0) [07:50:52.408,759] [Z_STACK/LSTN] PKTBODY: id = 0x00000311 (785) [07:50:52.411,969] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.413,353] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.414,792] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.414,980] [GATEWAY/MAIN] PKTBODY: action = 0x00000001 (1) [07:50:52.415,017] [GATEWAY/MAIN] PKTBODY: id = 0x00000311 (785) [07:50:52.415,052] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.415,226] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.418,960] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.421,206] [Z_STACK/LSTN] INFO : !Done [07:50:52.422,294] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.424,213] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.427,447] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:311, len:65 [07:50:52.428,514] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:52.429,567] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.430,413] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.441,907] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.447,560] [Z_STACK/READ] INFO : Received 67 bytes, subSys 0x61, cmdId 0x08 [07:50:52.447,710] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.447,745] [Z_STACK/READ] INFO : Client Read: (len 70): [07:50:52.452,021] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.452,198] [GATEWAY/MAIN] PKTBODY: action = 0x00000000 (0) [07:50:52.452,235] [GATEWAY/MAIN] PKTBODY: id = 0x00000312 (786) [07:50:52.452,270] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.452,443] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.450,630] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.450,755] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.451,037] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:01:18:91:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.451,103] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.451,157] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.451,199] [Z_STACK/LSTN] PKTBODY: action = 0x00000001 (1) [07:50:52.451,234] [Z_STACK/LSTN] PKTBODY: id = 0x00000311 (785) [07:50:52.451,269] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.451,440] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.458,332] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.461,567] [Z_STACK/LSTN] INFO : !Done [07:50:52.462,660] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.463,532] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.465,191] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:7, len::65 [07:50:52.466,410] [Z_STACK/LSTN] INFO : preparing to send 65 bytes, subSys 0x01, cmdId 0x07, pData: [07:50:52.468,525] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.469,938] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:52.479,070] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:52.484,295] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [SRSP] 01:61:07:00 [07:50:52.485,568] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:07:00 [07:50:52.489,162] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:52.489,325] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:52.489,364] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 5 messages)... [07:50:52.489,393] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:52.489,463] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x07 [07:50:52.489,505] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.489,531] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.489,606] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:52.489,652] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:52.489,680] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:52.489,707] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:52.489,738] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:52.489,764] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 5 messages)... [07:50:52.489,793] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:52.492,724] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [07:50:52.494,031] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.496,573] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.497,754] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.498,872] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:00:18:92:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [07:50:52.501,863] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.502,952] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.505,202] [Z_STACK/LSTN] PKTBODY: action = 0x00000000 (0) [07:50:52.506,251] [Z_STACK/LSTN] PKTBODY: id = 0x00000312 (786) [07:50:52.507,081] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.507,971] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.511,687] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.511,883] [GATEWAY/MAIN] PKTBODY: action = 0x00000001 (1) [07:50:52.511,921] [GATEWAY/MAIN] PKTBODY: id = 0x00000312 (786) [07:50:52.511,956] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.512,129] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.515,508] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.517,108] [Z_STACK/LSTN] INFO : !Done [07:50:52.519,641] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.521,072] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.524,323] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:312, len:65 [07:50:52.525,731] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:52.526,979] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.530,013] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.541,549] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.544,763] [Z_STACK/READ] INFO : Received 67 bytes, subSys 0x61, cmdId 0x08 [07:50:52.545,024] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.545,063] [Z_STACK/READ] INFO : Client Read: (len 70): [07:50:52.546,125] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.549,112] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.551,747] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.553,348] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:01:18:92:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.556,478] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.557,706] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.560,647] [Z_STACK/LSTN] PKTBODY: action = 0x00000001 (1) [07:50:52.561,819] [Z_STACK/LSTN] PKTBODY: id = 0x00000312 (786) [07:50:52.562,756] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.565,719] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.567,275] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.567,469] [GATEWAY/MAIN] PKTBODY: action = 0x00000000 (0) [07:50:52.567,506] [GATEWAY/MAIN] PKTBODY: id = 0x00000313 (787) [07:50:52.567,542] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.567,716] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.571,239] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.573,215] [Z_STACK/LSTN] INFO : !Done [07:50:52.574,274] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.576,786] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.579,013] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:7, len::65 [07:50:52.580,030] [Z_STACK/LSTN] INFO : preparing to send 65 bytes, subSys 0x01, cmdId 0x07, pData: [07:50:52.582,469] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.583,678] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:61:07:00 [07:50:52.596,456] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:07:00 [07:50:52.600,062] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x07 [07:50:52.600,198] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.600,231] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.601,596] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.604,707] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.605,936] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.607,019] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:00:18:93:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [07:50:52.609,199] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.610,279] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.612,382] [Z_STACK/LSTN] PKTBODY: action = 0x00000000 (0) [07:50:52.613,731] [Z_STACK/LSTN] PKTBODY: id = 0x00000313 (787) [07:50:52.616,479] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.617,789] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.619,393] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.619,595] [GATEWAY/MAIN] PKTBODY: action = 0x00000001 (1) [07:50:52.619,633] [GATEWAY/MAIN] PKTBODY: id = 0x00000313 (787) [07:50:52.619,669] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.619,843] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.624,793] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.626,242] [Z_STACK/LSTN] INFO : !Done [07:50:52.629,032] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.630,885] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.633,118] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:313, len:65 [07:50:52.634,153] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:52.634,963] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.636,989] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.651,191] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.655,112] [Z_STACK/READ] INFO : Received 67 bytes, subSys 0x61, cmdId 0x08 [07:50:52.655,251] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.655,286] [Z_STACK/READ] INFO : Client Read: (len 70): [07:50:52.656,354] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.659,347] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.660,639] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:01:18:93:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.661,860] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.664,753] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.665,961] [Z_STACK/LSTN] PKTBODY: action = 0x00000001 (1) [07:50:52.666,801] [Z_STACK/LSTN] PKTBODY: id = 0x00000313 (787) [07:50:52.669,715] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.670,911] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.672,223] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.672,411] [GATEWAY/MAIN] PKTBODY: action = 0x00000000 (0) [07:50:52.672,448] [GATEWAY/MAIN] PKTBODY: id = 0x00000314 (788) [07:50:52.672,483] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.672,657] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.676,325] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.679,312] [Z_STACK/LSTN] INFO : !Done [07:50:52.680,637] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.684,074] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.685,337] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:7, len::65 [07:50:52.688,066] [Z_STACK/LSTN] INFO : preparing to send 65 bytes, subSys 0x01, cmdId 0x07, pData: [07:50:52.689,254] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.690,536] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:61:07:00 [07:50:52.703,637] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:07:00 [07:50:52.707,413] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x61, cmdId 0x07 [07:50:52.707,556] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.707,591] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.709,154] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.711,926] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.713,060] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.714,208] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:00:18:94:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [07:50:52.717,276] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.718,351] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.719,440] [Z_STACK/LSTN] PKTBODY: action = 0x00000000 (0) [07:50:52.721,147] [Z_STACK/LSTN] PKTBODY: id = 0x00000314 (788) [07:50:52.722,431] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.725,054] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.729,447] [GATEWAY/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<< [07:50:52.729,644] [GATEWAY/MAIN] PKTBODY: action = 0x00000001 (1) [07:50:52.729,683] [GATEWAY/MAIN] PKTBODY: id = 0x00000314 (788) [07:50:52.729,718] [GATEWAY/MAIN] PKTBODY: index = 0x00000000 (0) [07:50:52.729,892] [GATEWAY/MAIN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.733,371] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.734,575] [Z_STACK/LSTN] INFO : !Done [07:50:52.737,403] [Z_STACK/LSTN] INFO : Receive message... [07:50:52.738,866] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:f3 [07:50:52.741,737] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:52.742,895] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:52.747,080] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:52.747,363] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:52.747,417] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 6 messages)... [07:50:52.747,448] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:52.747,641] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:52.747,698] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:52.747,728] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:52.747,755] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:52.747,785] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:52.747,812] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 6 messages)... [07:50:52.747,840] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:52.750,843] [Z_STACK/LSTN] INFO : znp_misc osal_nv_read: id:314, len:65 [07:50:52.751,913] [Z_STACK/LSTN] INFO : preparing to send 3 bytes, subSys 0x01, cmdId 0x08, pData: [07:50:52.753,129] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:52.755,764] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.766,899] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 43:61:08:00:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.769,229] [Z_STACK/READ] INFO : Received 67 bytes, subSys 0x61, cmdId 0x08 [07:50:52.769,371] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.769,407] [Z_STACK/READ] INFO : Client Read: (len 70): [07:50:52.771,925] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:52.775,032] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:52.776,068] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.777,282] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>GATEWAY ] [ucst] 4D:00:71:F8:08:F8:01:10:01:18:94:06:20:00:2A:41:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:67:07:00 [07:50:52.780,434] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>GATEWAY ] ZclActionRsp [07:50:52.781,673] [Z_STACK/LSTN] PKTBODY: cmdID = [07:50:52.785,128] [Z_STACK/LSTN] PKTBODY: action = 0x00000001 (1) [07:50:52.786,220] [Z_STACK/LSTN] PKTBODY: id = 0x00000314 (788) [07:50:52.787,057] [Z_STACK/LSTN] PKTBODY: index = 0x00000000 (0) [07:50:52.788,115] [Z_STACK/LSTN] PKTBODY: payload (hex string) = FF:FF:FF:FF:FF:FF:FF:FF:FF:00:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:FF:F [07:50:52.803,778] [Z_STACK/LSTN] INFO : ...sent 81 bytes to Client [07:50:52.805,124] [Z_STACK/LSTN] INFO : !Done [07:50:52.921,728] [Z_STACK/LSTN] MISC1 : Connected to #7.(127.0.0.1 / ::) [07:50:52.921,867] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb - connected: 7 [07:50:52.921,909] [Z_STACK/LSTN] CONNECT: Connected to new client, connection # 7 [07:50:52.929,637] [NWK_MGR/LSTN] MISC1 : Connected to #8.(127.0.0.1 / ::) [07:50:52.929,764] [NWK_MGR/LSTN] CONNECT: Connected to new client, connection # 8 [07:50:52.931,419] [OTASRVR/MAIN] UNMSKBL: [07:50:52.935,790] [OTASRVR/MAIN] UNMSKBL: ************************************************ [07:50:52.938,993] [OTASRVR/MAIN] UNMSKBL: * OTA Upgrade Server v1.0.2d * [07:50:52.940,461] [OTASRVR/MAIN] UNMSKBL: ************************************************ [07:50:52.943,270] [OTASRVR/MAIN] UNMSKBL: [07:50:52.944,407] [OTASRVR/MAIN] PKTTYPE: [ Z_STACK<<<<<<<<<<>NPISRVR ] [SRSP] 01:64:00:00 [07:50:52.991,549] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x64, cmdId 0x00 [07:50:52.991,710] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:52.991,748] [Z_STACK/READ] INFO : Client Read: (len 4): [07:50:52.988,953] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:64:00:00 [07:50:52.995,808] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:52.996,025] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>>>>>>>>>>OTASRVR ] [ucst] 04:00:71:FA:08:20:10:00 [07:50:52.996,100] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>>>>>>>>>>OTASRVR ] zstackDefaultRsp [07:50:52.996,156] [Z_STACK/LSTN] PKTBODY: cmdID = AF_REGISTER_REQ [07:50:52.996,196] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:50:52.999,667] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:53.000,743] [Z_STACK/LSTN] INFO : !Done [07:50:53.008,534] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:53.013,852] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:53.013,989] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:53.014,029] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 7 messages)... [07:50:53.014,058] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:53.015,163] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:53.015,265] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:53.015,298] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:53.015,326] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:53.015,359] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:53.015,385] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 7 messages)... [07:50:53.015,414] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:53.013,250] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:53.035,181] [OTASRVR/LSTN] CONNECT: Connected to new client, connection # 7 [07:50:53.036,386] [OTASRVR/LSTN] CONNECT: Client at connection # 7 identified itself as SMPLAPP [07:50:53.039,527] [OTASRVR/LSTN] PKTTYPE: [ OTASRVR<>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:53.273,384] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:53.273,540] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:53.273,579] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 8 messages)... [07:50:53.273,610] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:53.272,363] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:53.275,110] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:53.275,214] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:53.275,246] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:53.275,273] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:53.275,305] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:53.275,332] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 8 messages)... [07:50:53.275,361] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:53.537,588] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 01:45:C0:08 [07:50:53.537,811] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:08 [07:50:53.539,353] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:53.539,520] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:53.539,558] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 9 messages)... [07:50:53.539,588] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:53.539,679] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:53.539,727] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:53.539,757] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:53.539,785] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:53.539,817] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:53.539,843] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 9 messages)... [07:50:53.539,872] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:53.818,603] [NWK_MGR/LSTN] MISC1 : Connected to #9.(127.0.0.1 / ::) [07:50:53.818,744] [NWK_MGR/LSTN] CONNECT: Connected to new client, connection # 9 [07:50:53.818,814] [NWK_MGR/LSTN] INFO : Receive message... [07:50:53.818,881] [NWK_MGR/LSTN] CONNECT: Client at connection # 9 identified itself as SMPLAPP [07:50:53.818,954] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>NPISRVR ] [ASNC] 01:45:C0:09 [07:50:54.098,106] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 01:45:C0:09 [07:50:54.099,397] [NPISRVR/U_RX] PKT_HEX: [SOCZIGB>>NPISRVR ] [ASNC] 03:4F:80:00:02:00 [07:50:54.100,492] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x45, cmdId 0xC0 [07:50:54.100,640] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0xC0 [07:50:54.100,678] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 10 messages)... [07:50:54.100,707] [Z_STACK/READ] INFO : Filling new message (@ 0xB6B00490)... [07:50:54.100,796] [Z_STACK/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:54.100,966] [Z_STACK/HNDL] INFO : [DBG] Processing @ 0xB6B00490 [07:50:54.100,996] [Z_STACK/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:54.101,024] [Z_STACK/HNDL] INFO : [DBG] asyncCB: subSys:0x00000005, cmdId:0x000000C0, len:0x00000001, pData:0xB6B00498 [07:50:54.101,057] [Z_STACK/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6B00490)... [07:50:54.101,084] [Z_STACK/HNDL] INFO : [DBG] Clearing @ 0xB6B00490 (processed 10 messages)... [07:50:54.101,114] [Z_STACK/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:54.102,422] [NPISRVR/ACBK] PKT_HEX: [ NPISRVR>>Z_STACK ] [bcst] 03:4F:80:00:02:00 [07:50:54.106,790] [Z_STACK/READ] INFO : Received 3 bytes, subSys 0x4F, cmdId 0x80 [07:50:54.107,932] [Z_STACK/READ] INFO : RPC_CMD_AREQ cmdId: 0x80 [07:50:54.109,152] [Z_STACK/READ] INFO : [DBG] Allocated @ 0xB6B00490 (received 11 messages)... [07:50:54.113,487] [NWK_MGR/READ] INFO : Received 9 bytes, subSys 0x51, cmdId 0xE3 [07:50:54.113,617] [NWK_MGR/READ] INFO : RPC_CMD_AREQ cmdId: 0xE3 [07:50:54.113,653] [NWK_MGR/READ] INFO : [DBG] Allocated @ 0xB6A004A0 (received 2 messages)... [07:50:54.113,682] [NWK_MGR/READ] INFO : Filling new message (@ 0xB6A004A0)... [07:50:54.113,775] [NWK_MGR/HNDL] INFO : [MUTEX] Mutex for AREQ unlocked [07:50:54.113,823] [NWK_MGR/HNDL] INFO : [DBG] Processing @ 0xB6A004A0 [07:50:54.113,851] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ Calling asynchMsgCback (Handle)... [07:50:54.113,878] [NWK_MGR/HNDL] INFO : [DBG] asyncCB: subSys:0x00000011, cmdId:0x000000E3, len:0x00000009, pData:0xB6A004A8 [07:50:54.113,924] [NWK_MGR/HNDL] MISC1 : Received Commissioning Notification [07:50:54.113,954] [NWK_MGR/HNDL] MISC1 : NwkMgr sendSysNwkInfoReadReqAndSetTxPower: [07:50:54.113,991] [NWK_MGR/HNDL] INFO : preparing to send 2 bytes, subSys 0x11, cmdId 0x08, pData: [07:50:54.114,022] [NWK_MGR/HNDL] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:54.114,077] [NWK_MGR/HNDL] PKT_HEX: [ Z_STACK<>NWK_MGR ] [ucst] 09:00:51:E3:08:E3:01:10:00:18:00:20:00 [07:50:54.112,619] [Z_STACK/MAIN] PKTTYPE: [ Z_STACK>>NWK_MGR ] AppCnfCommissioningNotification [07:50:54.112,672] [Z_STACK/MAIN] PKTBODY: cmdID = [07:50:54.112,713] [Z_STACK/MAIN] PKTBODY: status = STATUS_SUCCESS [07:50:54.112,750] [Z_STACK/MAIN] PKTBODY: commMode = 0x00000000 (0) [07:50:54.112,784] [Z_STACK/MAIN] PKTBODY: remainingCommMode = 0x00000000 (0) [07:50:54.113,195] [Z_STACK/MAIN] INFO : ...sent 13 bytes to Client [07:50:54.117,052] [Z_STACK/LSTN] INFO : Receive message... [07:50:54.117,218] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [07:50:54.117,266] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:50:54.117,300] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:54.117,352] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:54.127,658] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:54.132,051] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:54.132,191] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:54.132,227] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:54.136,642] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:54.136,770] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:54.136,933] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:54.139,646] [NWK_MGR/HNDL] INFO : [MUTEX] Unlock SRSP Mutex [07:50:54.139,778] [NWK_MGR/HNDL] MISC1 : NwkInfoReadRsp: [07:50:54.139,815] [NWK_MGR/HNDL] MISC1 : - nwkaddr:0000 [07:50:54.139,844] [NWK_MGR/HNDL] MISC1 : - ieeeaddr:00124B0001495734 [07:50:54.139,875] [NWK_MGR/HNDL] MISC1 : - devstate:9 [07:50:54.139,900] [NWK_MGR/HNDL] MISC1 : - panid:C77A [07:50:54.139,926] [NWK_MGR/HNDL] MISC1 : - extendedpanid:00124B0001495734 [07:50:54.139,953] [NWK_MGR/HNDL] MISC1 : - coordaddr:0000 [07:50:54.139,979] [NWK_MGR/HNDL] MISC1 : - coordextaddr:0000000000000000 [07:50:54.140,006] [NWK_MGR/HNDL] MISC1 : - devtypes: Coordinator: 1 [07:50:54.140,032] [NWK_MGR/HNDL] MISC1 : - devtypes: Router: 0 [07:50:54.140,058] [NWK_MGR/HNDL] MISC1 : - logicalchannel:13 [07:50:54.140,090] [NWK_MGR/HNDL] INFO : Requested TX power for channel 13 is 127 [07:50:54.140,119] [NWK_MGR/HNDL] INFO : Setting Tx Power to 0x7F [07:50:54.140,157] [NWK_MGR/HNDL] INFO : preparing to send 4 bytes, subSys 0x11, cmdId 0x07, pData: [07:50:54.140,188] [NWK_MGR/HNDL] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:54.140,252] [NWK_MGR/HNDL] PKT_HEX: [ Z_STACK<>NPISRVR ] [SRSP] 01:61:14:03 [07:50:54.154,991] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:61:14:03 [07:50:54.135,372] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:54.135,491] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:54.135,692] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:FA:8E:03: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 [07:50:54.135,747] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:54.135,805] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:54.135,847] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:54.135,885] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:54.135,919] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [07:50:54.135,953] [Z_STACK/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.135,989] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:54.136,023] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:54.136,060] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:54.136,093] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:54.136,124] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:54.136,160] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:54.136,193] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:54.136,228] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [07:50:54.145,223] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:54.145,365] [Z_STACK/LSTN] INFO : !Done [07:50:54.145,440] [Z_STACK/LSTN] INFO : Receive message... [07:50:54.145,516] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:7 [07:50:54.145,566] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:1, cmdID:14, len::1 [07:50:54.145,603] [Z_STACK/LSTN] INFO : preparing to send 1 bytes, subSys 0x01, cmdId 0x14, pData: [07:50:54.145,634] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:54.145,687] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>>>>>>>>>>SMPLAPP] [bcst] 11:00:52:09:08:09:10:0D:18:FA:8E:03:21:34:57:49:01:00:4B:12:00 [07:50:54.167,164] [NWK_MGR/HNDL] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkZigbeeNwkReadyInd [07:50:54.167,211] [NWK_MGR/HNDL] PKTBODY: cmdId = NWK_ZIGBEE_NWK_READY_IND [07:50:54.167,250] [NWK_MGR/HNDL] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:54.167,285] [NWK_MGR/HNDL] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.167,322] [NWK_MGR/HNDL] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:54.177,465] [NWK_MGR/HNDL] PKT_HEX: [ NWK_MGR>>OTASRVR ] [bcst] 11:00:52:09:08:09:10:0D:18:FA:8E:03:21:34:57:49:01:00:4B:12:00 [07:50:54.180,448] [NWK_MGR/HNDL] PKTTYPE: [ NWK_MGR>>OTASRVR ] NwkZigbeeNwkReadyInd [07:50:54.162,837] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:54.162,949] [Z_STACK/LSTN] DEBUG : --> status failed: cmdID:14, status: 3 [07:50:54.163,044] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:0D:08:0D:10:03 [07:50:54.163,101] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysSetTxPowerRsp [07:50:54.163,154] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_SET_TX_POWER_RSP [07:50:54.163,196] [Z_STACK/LSTN] PKTBODY: txPower = 0x00000003 (3) [07:50:54.182,424] [NWK_MGR/HNDL] PKTBODY: cmdId = NWK_ZIGBEE_NWK_READY_IND [07:50:54.183,365] [NWK_MGR/HNDL] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:54.185,482] [NWK_MGR/HNDL] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.185,600] [NWK_MGR/HNDL] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:54.189,350] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:50:54.189,500] [Z_STACK/LSTN] INFO : !Done [07:50:54.192,582] [NWK_MGR/HNDL] PKT_HEX: [ NWK_MGR>>GATEWAY ] [bcst] 11:00:52:09:08:09:10:0D:18:FA:8E:03:21:34:57:49:01:00:4B:12:00 [07:50:54.193,876] [NWK_MGR/HNDL] PKTTYPE: [ NWK_MGR>>GATEWAY ] NwkZigbeeNwkReadyInd [07:50:54.196,506] [NWK_MGR/HNDL] PKTBODY: cmdId = NWK_ZIGBEE_NWK_READY_IND [07:50:54.196,616] [NWK_MGR/HNDL] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:54.196,657] [NWK_MGR/HNDL] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.196,695] [NWK_MGR/HNDL] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:54.200,712] [NWK_MGR/HNDL] INFO : [MUTEX] AREQ (Handle) (message @ 0xB6A004A0)... [07:50:54.204,062] [NWK_MGR/HNDL] INFO : [DBG] Clearing @ 0xB6A004A0 (processed 2 messages)... [07:50:54.205,422] [NWK_MGR/HNDL] INFO : [MUTEX] Wait for AREQ semaphore [07:50:54.820,062] [NWK_MGR/LSTN] INFO : Receive message... [07:50:54.820,282] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>NPISRVR ] [SRSP] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:54.945,703] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:54.945,863] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:54.945,901] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:54.944,321] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:54.947,507] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:54.947,637] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:54.947,833] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:FA:8E:03: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 [07:50:54.947,891] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:54.947,954] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:54.947,995] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:54.948,033] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:54.948,067] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [07:50:54.948,101] [Z_STACK/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.948,137] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:54.948,171] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:54.948,207] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:54.948,241] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:54.948,273] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:54.948,307] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:54.948,340] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:54.948,377] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [07:50:54.948,739] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:54.948,982] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:54.949,025] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:54.956,175] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:54.956,360] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [07:50:54.956,402] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [07:50:54.956,433] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [07:50:54.956,465] [NWK_MGR/LSTN] MISC1 : - devstate:9 [07:50:54.956,491] [NWK_MGR/LSTN] MISC1 : - panid:C77A [07:50:54.956,517] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [07:50:54.956,544] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [07:50:54.956,571] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [07:50:54.956,598] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [07:50:54.956,624] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [07:50:54.956,650] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [07:50:54.956,767] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>SMPLAPP] [ucst] 13:00:72:0B:08:0B:10:01:18:0D:20:FA:8E:03:29:34:57:49:01:00:4B:12:00 [07:50:54.956,939] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkZigbeeNwkInfoCnf [07:50:54.957,003] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [07:50:54.957,044] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [07:50:54.957,080] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:54.957,114] [NWK_MGR/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:54.957,151] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:54.964,103] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:54.965,242] [Z_STACK/LSTN] INFO : !Done [07:50:54.968,344] [NWK_MGR/LSTN] INFO : ...sent 23 bytes to Client [07:50:54.969,627] [NWK_MGR/LSTN] INFO : !Done [07:50:54.973,032] [NWK_MGR/LSTN] INFO : Receive message... [07:50:54.974,201] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>>>>>>>>>>SMPLAPP] [ucst] 44:00:72:08:08:08:12:40:08:00:11:34:57:49:01:00:4B:12:00:20:07:2A:20:08:04:10:84:02:18:07:20:00:28:00:28:03:28:81:0A:30:06:30:04:30:05:30:80:0A:30:09:30:81:02:30:20:2A:0D:08:F2:01:10:E0:C3:02:18:61:20:00:30:21:30:01 [07:50:54.981,665] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkGetLocalDeviceInfoCnf [07:50:54.984,559] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_GET_LOCAL_DEVICE_INFO_CNF [07:50:54.985,894] [NWK_MGR/LSTN] PKTBODY: deviceInfoList : [07:50:54.988,951] [NWK_MGR/LSTN] PKTBODY: networkAddress = 0x00000000 (0) [07:50:54.990,053] [NWK_MGR/LSTN] PKTBODY: ieeeAddress = 00:12:4B:00:01:49:57:34 [07:50:54.990,910] [NWK_MGR/LSTN] PKTBODY: manufacturerId = 0x00000007 (7) [07:50:54.991,673] [NWK_MGR/LSTN] PKTBODY: simpleDescList : [07:50:54.994,095] [NWK_MGR/LSTN] PKTBODY: [000] : [07:50:54.996,614] [NWK_MGR/LSTN] PKTBODY: endpointId = 0x00000004 (4) [07:50:54.998,019] [NWK_MGR/LSTN] PKTBODY: profileId = 0x00000104 (260) [07:50:55.000,750] [NWK_MGR/LSTN] PKTBODY: deviceId = 0x00000007 (7) [07:50:55.001,965] [NWK_MGR/LSTN] PKTBODY: deviceVer = 0x00000000 (0) [07:50:55.003,220] [NWK_MGR/LSTN] PKTBODY: inputClusters : [07:50:55.005,118] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000000 (0) [07:50:55.005,445] [NWK_MGR/LSTN] PKTBODY: [001] = 0x00000003 (3) [07:50:55.005,502] [NWK_MGR/LSTN] PKTBODY: [002] = 0x00000501 (1281) [07:50:55.005,539] [NWK_MGR/LSTN] PKTBODY: outputClusters : [07:50:55.005,573] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000006 (6) [07:50:55.005,605] [NWK_MGR/LSTN] PKTBODY: [001] = 0x00000004 (4) [07:50:55.005,636] [NWK_MGR/LSTN] PKTBODY: [002] = 0x00000005 (5) [07:50:55.005,668] [NWK_MGR/LSTN] PKTBODY: [003] = 0x00000500 (1280) [07:50:55.005,699] [NWK_MGR/LSTN] PKTBODY: [004] = 0x00000009 (9) [07:50:55.005,730] [NWK_MGR/LSTN] PKTBODY: [005] = 0x00000101 (257) [07:50:55.005,762] [NWK_MGR/LSTN] PKTBODY: [006] = 0x00000020 (32) [07:50:55.005,797] [NWK_MGR/LSTN] PKTBODY: [001] : [07:50:55.005,830] [NWK_MGR/LSTN] PKTBODY: endpointId = 0x000000F2 (242) [07:50:55.005,865] [NWK_MGR/LSTN] PKTBODY: profileId = 0x0000A1E0 (41440) [07:50:55.005,898] [NWK_MGR/LSTN] PKTBODY: deviceId = 0x00000061 (97) [07:50:55.005,932] [NWK_MGR/LSTN] PKTBODY: deviceVer = 0x00000000 (0) [07:50:55.005,965] [NWK_MGR/LSTN] PKTBODY: outputClusters : [07:50:55.005,997] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000021 (33) [07:50:55.006,034] [NWK_MGR/LSTN] PKTBODY: deviceStatus = DEVICE_ON_LINE [07:50:55.014,359] [NWK_MGR/LSTN] INFO : ...sent 72 bytes to Client [07:50:55.016,156] [NWK_MGR/LSTN] INFO : !Done [07:50:55.019,247] [NWK_MGR/LSTN] INFO : Receive message... [07:50:55.020,368] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>>>>>>>>>>SMPLAPP] [ucst] 04:00:72:17:08:17:10:00 [07:50:55.031,521] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkGetDeviceListCnf [07:50:55.033,208] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_GET_DEVICE_LIST_CNF [07:50:55.034,413] [NWK_MGR/LSTN] PKTBODY: status = STATUS_SUCCESS [07:50:55.042,060] [NWK_MGR/LSTN] INFO : ...sent 8 bytes to Client [07:50:55.045,030] [NWK_MGR/LSTN] INFO : !Done [07:50:55.046,303] [NWK_MGR/LSTN] INFO : Receive message... [07:50:55.049,470] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR<<<<<<<<<<>>>>>>>>>>SMPLAPP] [ucst] 44:00:72:22:08:22:12:40:08:00:11:34:57:49:01:00:4B:12:00:20:07:2A:20:08:04:10:84:02:18:07:20:00:28:00:28:03:28:81:0A:30:06:30:04:30:05:30:80:0A:30:09:30:81:02:30:20:2A:0D:08:F2:01:10:E0:C3:02:18:61:20:00:30:21:30:01 [07:50:55.056,793] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>SMPLAPP] NwkGetGwEndpointInfoCnf [07:50:55.059,738] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_GET_GW_ENDPOINT_INFO_CNF [07:50:55.060,784] [NWK_MGR/LSTN] PKTBODY: deviceInfoList : [07:50:55.061,824] [NWK_MGR/LSTN] PKTBODY: networkAddress = 0x00000000 (0) [07:50:55.064,179] [NWK_MGR/LSTN] PKTBODY: ieeeAddress = 00:12:4B:00:01:49:57:34 [07:50:55.065,230] [NWK_MGR/LSTN] PKTBODY: manufacturerId = 0x00000007 (7) [07:50:55.065,896] [NWK_MGR/LSTN] PKTBODY: simpleDescList : [07:50:55.068,119] [NWK_MGR/LSTN] PKTBODY: [000] : [07:50:55.069,082] [NWK_MGR/LSTN] PKTBODY: endpointId = 0x00000004 (4) [07:50:55.069,758] [NWK_MGR/LSTN] PKTBODY: profileId = 0x00000104 (260) [07:50:55.072,265] [NWK_MGR/LSTN] PKTBODY: deviceId = 0x00000007 (7) [07:50:55.073,241] [NWK_MGR/LSTN] PKTBODY: deviceVer = 0x00000000 (0) [07:50:55.073,908] [NWK_MGR/LSTN] PKTBODY: inputClusters : [07:50:55.073,994] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000000 (0) [07:50:55.074,033] [NWK_MGR/LSTN] PKTBODY: [001] = 0x00000003 (3) [07:50:55.074,067] [NWK_MGR/LSTN] PKTBODY: [002] = 0x00000501 (1281) [07:50:55.074,100] [NWK_MGR/LSTN] PKTBODY: outputClusters : [07:50:55.074,133] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000006 (6) [07:50:55.074,165] [NWK_MGR/LSTN] PKTBODY: [001] = 0x00000004 (4) [07:50:55.074,197] [NWK_MGR/LSTN] PKTBODY: [002] = 0x00000005 (5) [07:50:55.074,228] [NWK_MGR/LSTN] PKTBODY: [003] = 0x00000500 (1280) [07:50:55.074,260] [NWK_MGR/LSTN] PKTBODY: [004] = 0x00000009 (9) [07:50:55.074,291] [NWK_MGR/LSTN] PKTBODY: [005] = 0x00000101 (257) [07:50:55.074,323] [NWK_MGR/LSTN] PKTBODY: [006] = 0x00000020 (32) [07:50:55.074,358] [NWK_MGR/LSTN] PKTBODY: [001] : [07:50:55.074,391] [NWK_MGR/LSTN] PKTBODY: endpointId = 0x000000F2 (242) [07:50:55.074,425] [NWK_MGR/LSTN] PKTBODY: profileId = 0x0000A1E0 (41440) [07:50:55.074,458] [NWK_MGR/LSTN] PKTBODY: deviceId = 0x00000061 (97) [07:50:55.074,492] [NWK_MGR/LSTN] PKTBODY: deviceVer = 0x00000000 (0) [07:50:55.074,526] [NWK_MGR/LSTN] PKTBODY: outputClusters : [07:50:55.074,558] [NWK_MGR/LSTN] PKTBODY: [000] = 0x00000021 (33) [07:50:55.074,595] [NWK_MGR/LSTN] PKTBODY: deviceStatus = DEVICE_ON_LINE [07:50:55.081,089] [NWK_MGR/LSTN] INFO : ...sent 72 bytes to Client [07:50:55.082,306] [NWK_MGR/LSTN] INFO : !Done [07:50:56.762,829] [NWK_MGR/LSTN] MISC1 : Connected to #10.(127.0.0.1 / ::) [07:50:56.762,980] [NWK_MGR/LSTN] CONNECT: Connected to new client, connection # 10 [07:50:56.768,053] [GATEWAY/LSTN] CONNECT: Connected to new client, connection # 7 [07:50:56.860,119] [Z_STACK/LSTN] INFO : Receive message... [07:50:56.860,293] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [07:50:56.860,346] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:50:56.860,381] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:56.860,435] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:56.869,184] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:56.873,614] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:56.873,764] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:56.873,799] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:56.878,361] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:56.878,534] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:56.878,573] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:56.874,856] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [07:50:56.874,979] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:56.875,011] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:56.875,040] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:56.875,072] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:56.875,261] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:FA:8E:03: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 [07:50:56.875,314] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:56.875,374] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:56.875,416] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:56.875,455] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:56.875,490] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [07:50:56.875,524] [Z_STACK/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:56.875,561] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:56.875,594] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:56.875,630] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:56.875,664] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:56.875,695] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:56.875,730] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:56.875,762] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:56.875,798] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [07:50:56.882,970] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:56.883,153] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [07:50:56.883,196] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [07:50:56.883,226] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [07:50:56.883,258] [NWK_MGR/LSTN] MISC1 : - devstate:9 [07:50:56.883,284] [NWK_MGR/LSTN] MISC1 : - panid:C77A [07:50:56.883,310] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [07:50:56.883,337] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [07:50:56.883,363] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [07:50:56.883,390] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [07:50:56.883,416] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [07:50:56.883,442] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [07:50:56.883,560] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] [ucst] 13:00:72:0B:08:0B:10:01:18:0D:20:FA:8E:03:29:34:57:49:01:00:4B:12:00 [07:50:56.883,613] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] NwkZigbeeNwkInfoCnf [07:50:56.883,658] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [07:50:56.883,697] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [07:50:56.883,733] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:56.883,767] [NWK_MGR/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:56.883,804] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:56.884,007] [NWK_MGR/LSTN] INFO : ...sent 23 bytes to Client [07:50:56.884,067] [NWK_MGR/LSTN] INFO : !Done [07:50:56.889,418] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:56.890,725] [Z_STACK/LSTN] INFO : !Done [07:50:57.164,258] [Z_STACK/LSTN] INFO : Receive message... [07:50:57.164,434] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:8 [07:50:57.164,487] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:50:57.164,522] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:50:57.164,574] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:57.175,830] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:50:57.177,459] [Z_STACK/LSTN] INFO : Waiting for synchronous response... [07:50:57.177,599] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Mutex [07:50:57.177,633] [Z_STACK/LSTN] INFO : [MUTEX] Wait for SRSP Cond signal... [07:50:57.177,758] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:50:57.177,820] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:57.177,849] [Z_STACK/READ] INFO : Client Read: (len 27): [07:50:57.180,598] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:57.180,729] [Z_STACK/LSTN] INFO : zstackpb Sending Network Info Response [07:50:57.183,930] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 31:00:71:0E:08:0E:10:00:19:34:57:49:01:00:4B:12:00:20:09:28:FA:8E:03: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 [07:50:57.185,028] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] sysNwkInfoReadRsp [07:50:57.187,679] [Z_STACK/LSTN] PKTBODY: cmdID = SYS_NWK_INFO_READ_RSP [07:50:57.188,720] [Z_STACK/LSTN] PKTBODY: nwkAddr = 0x00000000 (0) [07:50:57.191,648] [Z_STACK/LSTN] PKTBODY: ieeeAddr = 00:12:4B:00:01:49:57:34 [07:50:57.192,698] [Z_STACK/LSTN] PKTBODY: devState = DEV_ZB_COORD [07:50:57.194,695] [NWK_MGR/READ] INFO : Received 49 bytes, subSys 0x71, cmdId 0x0E [07:50:57.194,829] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:50:57.194,864] [NWK_MGR/READ] INFO : Client Read: (len 53): [07:50:57.199,591] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:50:57.199,756] [NWK_MGR/LSTN] MISC1 : NwkInfoReadRsp: [07:50:57.199,797] [NWK_MGR/LSTN] MISC1 : - nwkaddr:0000 [07:50:57.199,827] [NWK_MGR/LSTN] MISC1 : - ieeeaddr:00124B0001495734 [07:50:57.199,857] [NWK_MGR/LSTN] MISC1 : - devstate:9 [07:50:57.199,882] [NWK_MGR/LSTN] MISC1 : - panid:C77A [07:50:57.199,908] [NWK_MGR/LSTN] MISC1 : - extendedpanid:00124B0001495734 [07:50:57.199,935] [NWK_MGR/LSTN] MISC1 : - coordaddr:0000 [07:50:57.199,961] [NWK_MGR/LSTN] MISC1 : - coordextaddr:0000000000000000 [07:50:57.199,987] [NWK_MGR/LSTN] MISC1 : - devtypes: Coordinator: 1 [07:50:57.200,013] [NWK_MGR/LSTN] MISC1 : - devtypes: Router: 0 [07:50:57.200,039] [NWK_MGR/LSTN] MISC1 : - logicalchannel:13 [07:50:57.200,158] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] [ucst] 13:00:72:0B:08:0B:10:01:18:0D:20:FA:8E:03:29:34:57:49:01:00:4B:12:00 [07:50:57.200,208] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] NwkZigbeeNwkInfoCnf [07:50:57.200,254] [NWK_MGR/LSTN] PKTBODY: cmdId = NWK_ZIGBEE_NWK_INFO_CNF [07:50:57.200,293] [NWK_MGR/LSTN] PKTBODY: status = NWK_UP [07:50:57.200,329] [NWK_MGR/LSTN] PKTBODY: nwkChannel = 0x0000000D (13) [07:50:57.200,363] [NWK_MGR/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:57.200,400] [NWK_MGR/LSTN] PKTBODY: extPanId = 00:12:4B:00:01:49:57:34 [07:50:57.200,657] [NWK_MGR/LSTN] INFO : ...sent 23 bytes to Client [07:50:57.200,720] [NWK_MGR/LSTN] INFO : !Done [07:50:57.193,941] [Z_STACK/LSTN] PKTBODY: panId = 0x0000C77A (51066) [07:50:57.194,050] [Z_STACK/LSTN] PKTBODY: extendedPanId = 00:12:4B:00:01:49:57:34 [07:50:57.194,094] [Z_STACK/LSTN] PKTBODY: coordAddr = 0x00000000 (0) [07:50:57.194,133] [Z_STACK/LSTN] PKTBODY: coordExtAddr = 00:00:00:00:00:00:00:00 [07:50:57.194,169] [Z_STACK/LSTN] PKTBODY: devTypes : [07:50:57.194,202] [Z_STACK/LSTN] PKTBODY: coodinator = 1 [07:50:57.194,238] [Z_STACK/LSTN] PKTBODY: router = 0 [07:50:57.194,271] [Z_STACK/LSTN] PKTBODY: enddevice = 0 [07:50:57.194,307] [Z_STACK/LSTN] PKTBODY: logicalChannel = 0x0000000D (13) [07:50:57.211,124] [Z_STACK/LSTN] INFO : ...sent 53 bytes to Client [07:50:57.212,295] [Z_STACK/LSTN] INFO : !Done [07:51:44.569,292] [Z_STACK/LSTN] INFO : Receive message... [07:51:44.569,525] [Z_STACK/LSTN] INFO : zstackpb zspbHandlePbCb: subsystemID:31, cmdId:43 [07:51:44.569,588] [Z_STACK/LSTN] INFO : preparing to send 0 bytes, subSys 0x05, cmdId 0x50, pData: [07:51:44.569,624] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:51:44.569,676] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:51:44.585,038] [Z_STACK/READ] INFO : Received 24 bytes, subSys 0x65, cmdId 0x50 [07:51:44.585,176] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:51:44.585,210] [Z_STACK/READ] INFO : Client Read: (len 27): [07:51:44.584,344] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 18:65:50:00:00:09:7A:C7:00:00:34:57:49:01:00:4B:12:00:00:00:00:00:00:00:00:00:0D [07:51:44.589,246] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:51:44.589,412] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [07:51:44.589,460] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [07:51:44.589,493] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:51:44.589,559] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:36:00 [07:51:44.607,056] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [07:51:44.608,482] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [07:51:44.608,643] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:51:44.608,680] [Z_STACK/READ] INFO : Client Read: (len 4): [07:51:44.610,000] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:51:44.610,139] [Z_STACK/LSTN] INFO : znp_misc sendNPIExpectDefaultStatusZNP: subSys:5, cmdID:36, len::5 [07:51:44.610,182] [Z_STACK/LSTN] INFO : preparing to send 5 bytes, subSys 0x05, cmdId 0x36, pData: [07:51:44.610,216] [Z_STACK/LSTN] INFO : [MUTEX] Lock SRSP Transaction Mutex [07:51:44.610,280] [Z_STACK/LSTN] PKT_HEX: [ NPISRVR<>NPISRVR ] [SRSP] 01:65:36:00 [07:51:44.626,825] [NPISRVR/MAIN] PKT_HEX: [ NPISRVR>>Z_STACK ] [ucst] 01:65:36:00 [07:51:44.627,559] [Z_STACK/READ] INFO : Received 1 bytes, subSys 0x65, cmdId 0x36 [07:51:44.627,690] [Z_STACK/READ] INFO : [MUTEX] SRSP Cond signal set [07:51:44.627,724] [Z_STACK/READ] INFO : Client Read: (len 4): [07:51:44.630,170] [NWK_MGR/READ] INFO : Received 4 bytes, subSys 0x71, cmdId 0xFA [07:51:44.630,294] [NWK_MGR/READ] INFO : [MUTEX] SRSP Cond signal set [07:51:44.630,328] [NWK_MGR/READ] INFO : Client Read: (len 8): [07:51:44.629,439] [Z_STACK/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:51:44.629,638] [Z_STACK/LSTN] PKT_HEX: [ Z_STACK>>NWK_MGR ] [ucst] 04:00:71:FA:08:43:10:00 [07:51:44.629,705] [Z_STACK/LSTN] PKTTYPE: [ Z_STACK>>NWK_MGR ] zstackDefaultRsp [07:51:44.629,760] [Z_STACK/LSTN] PKTBODY: cmdID = ZDO_MGMT_PERMIT_JOIN_REQ [07:51:44.629,802] [Z_STACK/LSTN] PKTBODY: status = ZSuccess [07:51:44.633,014] [NWK_MGR/LSTN] INFO : [MUTEX] Unlock SRSP Mutex [07:51:44.633,199] [NWK_MGR/LSTN] MISC1 : Sending ZigBee Generic Confirmation [07:51:44.633,291] [NWK_MGR/LSTN] PKT_HEX: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] [ucst] 04:00:72:00:08:00:10:00 [07:51:44.633,343] [NWK_MGR/LSTN] PKTTYPE: [ NWK_MGR>>>>>>>>>>>>>>>>>>>>CON010] NwkZigbeeGenericCnf [07:51:44.633,389] [NWK_MGR/LSTN] PKTBODY: cmdId = ZIGBEE_GENERIC_CNF [07:51:44.633,427] [NWK_MGR/LSTN] PKTBODY: status = STATUS_SUCCESS [07:51:44.633,762] [NWK_MGR/LSTN] INFO : ...sent 8 bytes to Client [07:51:44.633,833] [NWK_MGR/LSTN] INFO : !Done [07:51:44.637,517] [Z_STACK/LSTN] INFO : ...sent 8 bytes to Client [07:51:44.638,796] [Z_STACK/LSTN] INFO : !Done