Skip to content

Instantly share code, notes, and snippets.

@borpin

borpin/zigbee2mqttdebug.log Secret

Created October 31, 2018 18:47
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save borpin/cebcfe3bfea3c8dd8987f8dc226fc086 to your computer and use it in GitHub Desktop.
Save borpin/cebcfe3bfea3c8dd8987f8dc226fc086 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
zigbee2mqttdebug.log
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +24ms
serialport:main _read reading +50ms
serialport:bindings read +73ms
serialport:unixRead Starting read +56ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 63 bytes +16ms
serialport:main binding.read finished +23ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 22 },
cc-znp fcs: 208,
cc-znp csum: 208 } +16ms
cc-znp { sof: 254,
cc-znp len: 50,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 344365,
cc-znp transseqnumber: 0,
cc-znp len: 30,
cc-znp data:
cc-znp <Buffer 18 16 01 00 00 00 10 00 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> },
cc-znp fcs: 184,
cc-znp csum: 184 } +38ms
serialport:main _read reading +62ms
serialport:bindings read +85ms
serialport:unixRead Starting read +69ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 22 } +21ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 22 +2ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 344365, transseqnumber: 0, len: 30, data: <Buffer 18 16 01 00 00 00 10 00 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +5ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +164ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 23 +29ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 23, options: 48, radius: 30, len: 5, data: <Buffer 00 17 00 00 00> } +11ms
serialport:main _write 20 bytes of data +236ms
serialport:bindings write 20 bytes +242ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +478ms
serialport:unixRead waiting for readable because of code: EAGAIN +256ms
serialport:poller Polling for "readable" +477ms
serialport:unixWrite write returned null 20 +8ms
serialport:unixWrite wrote 20 bytes +3ms
serialport:unixWrite Finished writing 20 bytes +7ms
serialport:main binding.write write finished +41ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +54ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 24 +19ms
serialport:poller received "readable" +42ms
serialport:bindings read +70ms
serialport:unixRead Starting read +57ms
serialport:unixRead Finished read 22 bytes +3ms
serialport:main binding.read finished +45ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +34ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +42ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +33ms
serialport:main _read reading +111ms
serialport:bindings read +121ms
serialport:unixRead Starting read +118ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +33ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +6ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 41 bytes +24ms
serialport:main binding.read finished +32ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 23 },
cc-znp fcs: 209,
cc-znp csum: 209 } +17ms
cc-znp { sof: 254,
cc-znp len: 28,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 345738,
cc-znp transseqnumber: 0,
cc-znp len: 8,
cc-znp data: <Buffer 18 17 01 00 00 00 10 00> },
cc-znp fcs: 7,
cc-znp csum: 7 } +23ms
serialport:main _read reading +47ms
serialport:bindings read +79ms
serialport:unixRead Starting read +55ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 24, options: 48, radius: 30, len: 5, data: <Buffer 00 18 00 fd ff> } +23ms
serialport:main _write 20 bytes of data +20ms
serialport:bindings write 20 bytes +20ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +261ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 23 } +18ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 23 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 345738, transseqnumber: 0, len: 8, data: <Buffer 18 17 01 00 00 00 10 00> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
serialport:unixRead waiting for readable because of code: EAGAIN +56ms
serialport:poller Polling for "readable" +271ms
serialport:unixWrite write returned null 20 +42ms
serialport:unixWrite wrote 20 bytes +2ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +53ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +19ms
zigbee2mqtt:debug 10/31/2018, 6:43:02 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
serialport:poller received "readable" +40ms
serialport:bindings read +83ms
serialport:unixRead Starting read +47ms
serialport:unixRead Finished read 22 bytes +3ms
serialport:main binding.read finished +41ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +49ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +34ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
serialport:main _read reading +80ms
serialport:bindings read +91ms
serialport:unixRead Starting read +88ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +21ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 42 bytes +19ms
serialport:main binding.read finished +26ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 24 },
cc-znp fcs: 222,
cc-znp csum: 222 } +14ms
cc-znp { sof: 254,
cc-znp len: 29,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 346606,
cc-znp transseqnumber: 0,
cc-znp len: 9,
cc-znp data: <Buffer 18 18 01 fd ff 00 21 01 00> },
cc-znp fcs: 81,
cc-znp csum: 81 } +23ms
serialport:main _read reading +48ms
serialport:bindings read +74ms
serialport:unixRead Starting read +54ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 24 } +23ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 24 +2ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 346606, transseqnumber: 0, len: 9, data: <Buffer 18 18 01 fd ff 00 21 01 00> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
serialport:unixRead waiting for readable because of code: EAGAIN +35ms
serialport:poller Polling for "readable" +206ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +7ms
zigbee2mqtt:debug 10/31/2018, 6:43:02 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
zigbee2mqtt:debug 10/31/2018, 6:43:15 PM Received mqtt message on topic 'zigbee2mqtt/Standing Lamp/set' with data '{"state":"on"}'
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 25 +12s
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 25, options: 48, radius: 30, len: 3, data: <Buffer 01 19 01> } +4ms
serialport:main _write 18 bytes of data +12s
serialport:bindings write 18 bytes +12s
serialport:unixWrite Starting write 18 bytes offset 0 bytesToWrite 18 +12s
serialport:unixWrite write returned null 18 +4ms
serialport:unixWrite wrote 18 bytes +2ms
serialport:unixWrite Finished writing 18 bytes +2ms
serialport:main binding.write write finished +19ms
serialport:poller received "readable" +12s
serialport:bindings read +22ms
serialport:unixRead Starting read +12s
serialport:unixRead Finished read 6 bytes +4ms
serialport:main binding.read finished +14ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +54ms
serialport:main _read reading +22ms
serialport:bindings read +33ms
serialport:unixRead Starting read +29ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 16 bytes +9ms
serialport:main binding.read finished +17ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +19ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
serialport:main _read reading +47ms
serialport:bindings read +63ms
serialport:unixRead Starting read +52ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +16ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 38 bytes +14ms
serialport:main binding.read finished +19ms
cc-znp { sof: 254,
cc-znp len: 25,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 385812,
cc-znp transseqnumber: 0,
cc-znp len: 5,
cc-znp data: <Buffer 08 19 0b 01 00> },
cc-znp fcs: 49,
cc-znp csum: 49 } +21ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 25 },
cc-znp fcs: 223,
cc-znp csum: 223 } +21ms
serialport:main _read reading +51ms
serialport:bindings read +71ms
serialport:unixRead Starting read +58ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 385812, transseqnumber: 0, len: 5, data: <Buffer 08 19 0b 01 00> } +21ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +5ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 25 } +3ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 25 +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +36ms
serialport:poller Polling for "readable" +209ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +9ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 26 +15ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 26, options: 48, radius: 30, len: 6, data: <Buffer 00 1a 0c 00 00 f0> } +5ms
serialport:main _write 21 bytes of data +74ms
serialport:bindings write 21 bytes +74ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +263ms
serialport:unixWrite write returned null 21 +4ms
serialport:unixWrite wrote 21 bytes +2ms
serialport:unixWrite Finished writing 21 bytes +2ms
serialport:main binding.write write finished +26ms
serialport:poller received "readable" +62ms
serialport:bindings read +30ms
serialport:unixRead Starting read +69ms
serialport:unixRead Finished read 6 bytes +2ms
serialport:main binding.read finished +14ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +73ms
serialport:main _read reading +34ms
serialport:bindings read +42ms
serialport:unixRead Starting read +38ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +14ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 16 bytes +8ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +19ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
serialport:main _read reading +48ms
serialport:bindings read +62ms
serialport:unixRead Starting read +55ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +2ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +4ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 52 bytes +15ms
serialport:main binding.read finished +22ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 26 },
cc-znp fcs: 220,
cc-znp csum: 220 } +16ms
cc-znp { sof: 254,
cc-znp len: 39,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 386667,
cc-znp transseqnumber: 0,
cc-znp len: 19,
cc-znp data:
cc-znp <Buffer 18 1a 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> },
cc-znp fcs: 71,
cc-znp csum: 71 } +24ms
serialport:main _read reading +48ms
serialport:bindings read +70ms
serialport:unixRead Starting read +57ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 26 } +22ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +2ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 26 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 386667, transseqnumber: 0, len: 19, data: <Buffer 18 1a 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> } +43ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +66ms
serialport:unixRead waiting for readable because of code: EAGAIN +124ms
serialport:poller Polling for "readable" +308ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +11ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 27 +9ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 27, options: 48, radius: 30, len: 6, data: <Buffer 00 1b 0c 04 40 f0> } +4ms
serialport:main _write 21 bytes of data +161ms
serialport:bindings write 21 bytes +161ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +348ms
serialport:unixWrite write returned null 21 +3ms
serialport:unixWrite wrote 21 bytes +2ms
serialport:unixWrite Finished writing 21 bytes +3ms
serialport:main binding.write write finished +17ms
serialport:poller received "readable" +49ms
serialport:bindings read +22ms
serialport:unixRead Starting read +57ms
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +14ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +53ms
serialport:main _read reading +24ms
serialport:bindings read +33ms
serialport:unixRead Starting read +30ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 8 bytes +9ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +19ms
serialport:main _read reading +27ms
serialport:bindings read +43ms
serialport:unixRead Starting read +34ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 40 bytes +9ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 27 },
cc-znp fcs: 221,
cc-znp csum: 221 } +16ms
cc-znp { sof: 254,
cc-znp len: 27,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 387768,
cc-znp transseqnumber: 0,
cc-znp len: 7,
cc-znp data: <Buffer 18 1b 0d 01 fd ff 21> },
cc-znp fcs: 163,
cc-znp csum: 163 } +24ms
serialport:main _read reading +50ms
serialport:bindings read +66ms
serialport:unixRead Starting read +58ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 27 } +22ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 27 +2ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 387768, transseqnumber: 0, len: 7, data: <Buffer 18 1b 0d 01 fd ff 21> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +12ms
serialport:unixRead Finished read 8 bytes +32ms
serialport:main binding.read finished +39ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +24ms
serialport:main _read reading +33ms
serialport:bindings read +72ms
serialport:unixRead Starting read +39ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +22ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 28 +11ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 28, options: 48, radius: 30, len: 13, data: <Buffer 00 1c 00 00 00 00 40 01 40 02 40 03 40> } +5ms
serialport:main _write 28 bytes of data +34ms
serialport:bindings write 28 bytes +34ms
serialport:unixWrite Starting write 28 bytes offset 0 bytesToWrite 28 +262ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +46ms
serialport:poller Polling for "readable" +267ms
serialport:unixWrite write returned null 28 +14ms
serialport:unixWrite wrote 28 bytes +3ms
serialport:unixWrite Finished writing 28 bytes +2ms
serialport:main binding.write write finished +28ms
serialport:poller received "readable" +12ms
serialport:bindings read +32ms
serialport:unixRead Starting read +20ms
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +13ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +39ms
serialport:main _read reading +23ms
serialport:bindings read +33ms
serialport:unixRead Starting read +30ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +18ms
serialport:unixRead Finished read 16 bytes +25ms
serialport:main binding.read finished +32ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +20ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +46ms
serialport:main _read reading +72ms
serialport:bindings read +103ms
serialport:unixRead Starting read +78ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +19ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 29 +16ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 29, options: 48, radius: 30, len: 5, data: <Buffer 00 1d 00 00 00> } +5ms
serialport:main _write 20 bytes of data +48ms
serialport:bindings write 20 bytes +47ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +197ms
serialport:unixRead Finished read 63 bytes +54ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 28 },
cc-znp fcs: 218,
cc-znp csum: 218 } +33ms
cc-znp { sof: 254,
cc-znp len: 50,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 388609,
cc-znp transseqnumber: 0,
cc-znp len: 30,
cc-znp data:
cc-znp <Buffer 18 1c 01 00 00 00 10 01 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> },
cc-znp fcs: 48,
cc-znp csum: 48 } +24ms
serialport:main _read reading +48ms
serialport:bindings read +60ms
serialport:unixRead Starting read +51ms
serialport:unixWrite write returned null 20 +56ms
serialport:unixWrite wrote 20 bytes +3ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +11ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 28 } +26ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 28 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 388609, transseqnumber: 0, len: 30, data: <Buffer 18 1c 01 00 00 00 10 01 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +65ms
serialport:unixRead Finished read 22 bytes +94ms
serialport:main binding.read finished +85ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +15ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
serialport:main _read reading +68ms
serialport:bindings read +165ms
serialport:unixRead Starting read +73ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +21ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 30 +10ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +5ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +2ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +4ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 41 bytes +36ms
serialport:main binding.read finished +42ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 29 },
cc-znp fcs: 219,
cc-znp csum: 219 } +16ms
cc-znp { sof: 254,
cc-znp len: 28,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 389303,
cc-znp transseqnumber: 0,
cc-znp len: 8,
cc-znp data: <Buffer 18 1d 01 00 00 00 10 01> },
cc-znp fcs: 135,
cc-znp csum: 135 } +26ms
serialport:main _read reading +53ms
serialport:bindings read +95ms
serialport:unixRead Starting read +59ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 30, options: 48, radius: 30, len: 5, data: <Buffer 00 1e 00 fd ff> } +26ms
serialport:main _write 20 bytes of data +32ms
serialport:bindings write 20 bytes +32ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +287ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 29 } +29ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 29 +2ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 389303, transseqnumber: 0, len: 8, data: <Buffer 18 1d 01 00 00 00 10 01> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
serialport:unixRead waiting for readable because of code: EAGAIN +67ms
serialport:poller Polling for "readable" +577ms
serialport:unixWrite write returned null 20 +40ms
serialport:unixWrite wrote 20 bytes +3ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +51ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +18ms
zigbee2mqtt:debug 10/31/2018, 6:43:16 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
serialport:poller received "readable" +40ms
serialport:bindings read +82ms
serialport:unixRead Starting read +47ms
serialport:unixRead Finished read 22 bytes +3ms
serialport:main binding.read finished +41ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +49ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
serialport:main _read reading +67ms
serialport:bindings read +77ms
serialport:unixRead Starting read +74ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 42 bytes +22ms
serialport:main binding.read finished +29ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 30 },
cc-znp fcs: 216,
cc-znp csum: 216 } +17ms
cc-znp { sof: 254,
cc-znp len: 29,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 390363,
cc-znp transseqnumber: 0,
cc-znp len: 9,
cc-znp data: <Buffer 18 1e 01 fd ff 00 21 01 00> },
cc-znp fcs: 223,
cc-znp csum: 223 } +23ms
serialport:main _read reading +49ms
serialport:bindings read +78ms
serialport:unixRead Starting read +56ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 30 } +22ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 30 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 390363, transseqnumber: 0, len: 9, data: <Buffer 18 1e 01 fd ff 00 21 01 00> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +16ms
serialport:unixRead waiting for readable because of code: EAGAIN +37ms
serialport:poller Polling for "readable" +199ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +8ms
zigbee2mqtt:debug 10/31/2018, 6:43:16 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
zigbee2mqtt:debug 10/31/2018, 6:43:26 PM Received mqtt message on topic 'zigbee2mqtt/Standing Lamp/set' with data '{"state":"off"}'
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 31 +9s
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 31, options: 48, radius: 30, len: 3, data: <Buffer 01 1f 00> } +4ms
serialport:main _write 18 bytes of data +9s
serialport:bindings write 18 bytes +9s
serialport:unixWrite Starting write 18 bytes offset 0 bytesToWrite 18 +10s
serialport:unixWrite write returned null 18 +3ms
serialport:unixWrite wrote 18 bytes +2ms
serialport:unixWrite Finished writing 18 bytes +1ms
serialport:main binding.write write finished +14ms
serialport:poller received "readable" +9s
serialport:bindings read +17ms
serialport:unixRead Starting read +9s
serialport:unixRead Finished read 6 bytes +2ms
serialport:main binding.read finished +10ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +45ms
serialport:main _read reading +44ms
serialport:bindings read +51ms
serialport:unixRead Starting read +49ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +45ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +13ms
serialport:poller Polling for "readable" +76ms
serialport:poller received "readable" +22ms
serialport:bindings read +47ms
serialport:unixRead Starting read +34ms
serialport:unixRead Finished read 16 bytes +2ms
serialport:main binding.read finished +60ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 31 },
cc-znp fcs: 217,
cc-znp csum: 217 } +65ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +29ms
serialport:main _read reading +62ms
serialport:bindings read +74ms
serialport:unixRead Starting read +72ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 31 } +18ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 31 +1ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +2ms
serialport:unixRead Finished read 38 bytes +12ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +15ms
cc-znp { sof: 254,
cc-znp len: 25,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 420518,
cc-znp transseqnumber: 0,
cc-znp len: 5,
cc-znp data: <Buffer 08 1f 0b 00 00> },
cc-znp fcs: 8,
cc-znp csum: 8 } +31ms
serialport:main _read reading +54ms
serialport:bindings read +70ms
serialport:unixRead Starting read +58ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +15ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 420518, transseqnumber: 0, len: 5, data: <Buffer 08 1f 0b 00 00> } +1ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +10ms
serialport:unixRead waiting for readable because of code: EAGAIN +64ms
serialport:poller Polling for "readable" +212ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +48ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 32 +24ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 32, options: 48, radius: 30, len: 6, data: <Buffer 00 20 0c 00 00 f0> } +15ms
serialport:main _write 21 bytes of data +119ms
serialport:bindings write 21 bytes +127ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +393ms
serialport:unixWrite write returned null 21 +14ms
serialport:unixWrite wrote 21 bytes +1ms
serialport:unixWrite Finished writing 21 bytes +1ms
serialport:main binding.write write finished +43ms
serialport:poller received "readable" +96ms
serialport:bindings read +37ms
serialport:unixRead Starting read +100ms
serialport:unixRead Finished read 6 bytes +2ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +77ms
serialport:main _read reading +25ms
serialport:bindings read +39ms
serialport:unixRead Starting read +37ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +21ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +2ms
serialport:unixRead waiting for readable because of code: EAGAIN +13ms
serialport:poller Polling for "readable" +56ms
serialport:poller received "readable" +118ms
serialport:bindings read +135ms
serialport:unixRead Starting read +121ms
serialport:unixRead Finished read 8 bytes +2ms
serialport:main binding.read finished +140ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +145ms
serialport:main _read reading +18ms
serialport:bindings read +23ms
serialport:unixRead Starting read +22ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +11ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +2ms
serialport:unixRead waiting for readable because of code: EAGAIN +5ms
serialport:poller Polling for "readable" +32ms
serialport:poller received "readable" +67ms
serialport:bindings read +77ms
serialport:unixRead Starting read +71ms
serialport:unixRead Finished read 8 bytes +1ms
serialport:main binding.read finished +82ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 32 },
cc-znp fcs: 230,
cc-znp csum: 230 } +84ms
serialport:main _read reading +16ms
serialport:bindings read +21ms
serialport:unixRead Starting read +20ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 32 } +11ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +2ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 32 +2ms
serialport:unixRead waiting for readable because of code: EAGAIN +8ms
serialport:poller Polling for "readable" +33ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 33 +292ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 33, options: 48, radius: 30, len: 5, data: <Buffer 00 21 00 00 00> } +3ms
serialport:main _write 20 bytes of data +311ms
serialport:bindings write 20 bytes +311ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +614ms
serialport:unixWrite write returned null 20 +1ms
serialport:unixWrite wrote 20 bytes +2ms
serialport:unixWrite Finished writing 20 bytes +1ms
serialport:main binding.write write finished +10ms
serialport:poller received "readable" +315ms
serialport:bindings read +16ms
serialport:unixRead Starting read +319ms
serialport:unixRead Finished read 6 bytes +1ms
serialport:main binding.read finished +10ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +37ms
serialport:main _read reading +14ms
serialport:bindings read +18ms
serialport:unixRead Starting read +16ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +9ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +2ms
serialport:unixRead waiting for readable because of code: EAGAIN +6ms
serialport:poller Polling for "readable" +27ms
serialport:poller received "readable" +2s
serialport:bindings read +2s
serialport:unixRead Starting read +2s
serialport:unixRead Finished read 8 bytes +2ms
serialport:main binding.read finished +2s
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +2s
serialport:main _read reading +25ms
serialport:bindings read +31ms
serialport:unixRead Starting read +29ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +13ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +5ms
serialport:poller Polling for "readable" +39ms
serialport:poller received "readable" +21ms
serialport:bindings read +29ms
serialport:unixRead Starting read +24ms
serialport:unixRead Finished read 33 bytes +1ms
serialport:main binding.read finished +34ms
cc-znp { sof: 254,
cc-znp len: 28,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 428574,
cc-znp transseqnumber: 0,
cc-znp len: 8,
cc-znp data: <Buffer 18 21 01 00 00 00 10 00> },
cc-znp fcs: 106,
cc-znp csum: 106 } +40ms
serialport:main _read reading +20ms
serialport:bindings read +24ms
serialport:unixRead Starting read +23ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 428574, transseqnumber: 0, len: 8, data: <Buffer 18 21 01 00 00 00 10 00> } +10ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +2ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +31ms
serialport:unixRead waiting for readable because of code: EAGAIN +46ms
serialport:poller Polling for "readable" +73ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +14ms
zigbee2mqtt:debug 10/31/2018, 6:43:28 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
serialport:poller received "readable" +858ms
serialport:bindings read +908ms
serialport:unixRead Starting read +862ms
serialport:unixRead Finished read 8 bytes +2ms
serialport:main binding.read finished +913ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +894ms
serialport:main _read reading +52ms
serialport:bindings read +59ms
serialport:unixRead Starting read +58ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +29ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 44 bytes +12ms
serialport:main binding.read finished +19ms
cc-znp { sof: 254,
cc-znp len: 39,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 431634,
cc-znp transseqnumber: 0,
cc-znp len: 19,
cc-znp data:
cc-znp <Buffer 18 20 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> },
cc-znp fcs: 119,
cc-znp csum: 119 } +40ms
serialport:main _read reading +59ms
serialport:bindings read +77ms
serialport:unixRead Starting read +65ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 431634, transseqnumber: 0, len: 19, data: <Buffer 18 20 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> } +34ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +5ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +41ms
serialport:unixRead waiting for readable because of code: EAGAIN +52ms
serialport:poller Polling for "readable" +194ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +8ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 34 +10ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 34, options: 48, radius: 30, len: 6, data: <Buffer 00 22 0c 04 40 f0> } +4ms
serialport:main _write 21 bytes of data +86ms
serialport:bindings write 21 bytes +85ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +3s
serialport:unixWrite write returned null 21 +2ms
serialport:unixWrite wrote 21 bytes +3ms
serialport:unixWrite Finished writing 21 bytes +2ms
serialport:main binding.write write finished +16ms
serialport:poller received "readable" +47ms
serialport:bindings read +20ms
serialport:unixRead Starting read +54ms
serialport:unixRead Finished read 6 bytes +2ms
serialport:main binding.read finished +13ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +50ms
serialport:main _read reading +23ms
serialport:bindings read +33ms
serialport:unixRead Starting read +30ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +19ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 8 bytes +7ms
serialport:main binding.read finished +13ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +17ms
serialport:main _read reading +26ms
serialport:bindings read +39ms
serialport:unixRead Starting read +33ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 32 bytes +9ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 27,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 432468,
cc-znp transseqnumber: 0,
cc-znp len: 7,
cc-znp data: <Buffer 18 22 0d 01 fd ff 21> },
cc-znp fcs: 6,
cc-znp csum: 6 } +20ms
serialport:main _read reading +27ms
serialport:bindings read +43ms
serialport:unixRead Starting read +34ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 432468, transseqnumber: 0, len: 7, data: <Buffer 18 22 0d 01 fd ff 21> } +20ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +13ms
serialport:unixRead Finished read 8 bytes +22ms
serialport:main binding.read finished +30ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +17ms
serialport:main _read reading +25ms
serialport:bindings read +55ms
serialport:unixRead Starting read +33ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +20ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 35 +11ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 35, options: 48, radius: 30, len: 13, data: <Buffer 00 23 00 00 00 00 40 01 40 02 40 03 40> } +5ms
serialport:main _write 28 bytes of data +33ms
serialport:bindings write 28 bytes +33ms
serialport:unixWrite Starting write 28 bytes offset 0 bytesToWrite 28 +216ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +45ms
serialport:poller Polling for "readable" +222ms
serialport:unixWrite write returned null 28 +14ms
serialport:unixWrite wrote 28 bytes +2ms
serialport:unixWrite Finished writing 28 bytes +2ms
serialport:main binding.write write finished +28ms
serialport:poller received "readable" +12ms
serialport:bindings read +31ms
serialport:unixRead Starting read +19ms
serialport:unixRead Finished read 14 bytes +2ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 34 },
cc-znp fcs: 228,
cc-znp csum: 228 } +36ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +19ms
serialport:main _read reading +41ms
serialport:bindings read +50ms
serialport:unixRead Starting read +47ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 34 } +20ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +2ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 34 +3ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +4ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +4ms
serialport:unixRead Finished read 16 bytes +21ms
serialport:main binding.read finished +27ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +23ms
serialport:main _read reading +47ms
serialport:bindings read +74ms
serialport:unixRead Starting read +54ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 63 bytes +15ms
serialport:main binding.read finished +22ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 35 },
cc-znp fcs: 229,
cc-znp csum: 229 } +15ms
cc-znp { sof: 254,
cc-znp len: 50,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 433344,
cc-znp transseqnumber: 0,
cc-znp len: 30,
cc-znp data:
cc-znp <Buffer 18 23 01 00 00 00 10 00 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> },
cc-znp fcs: 190,
cc-znp csum: 190 } +24ms
serialport:main _read reading +49ms
serialport:bindings read +71ms
serialport:unixRead Starting read +55ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 35 } +22ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 35 +2ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 433344, transseqnumber: 0, len: 30, data: <Buffer 18 23 01 00 00 00 10 00 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +73ms
serialport:unixRead waiting for readable because of code: EAGAIN +93ms
serialport:poller Polling for "readable" +293ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +8ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 36 +9ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 36, options: 48, radius: 30, len: 5, data: <Buffer 00 24 00 fd ff> } +5ms
serialport:main _write 20 bytes of data +126ms
serialport:bindings write 20 bytes +126ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +333ms
serialport:unixWrite write returned null 20 +3ms
serialport:unixWrite wrote 20 bytes +2ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +16ms
serialport:poller received "readable" +47ms
serialport:bindings read +19ms
serialport:unixRead Starting read +54ms
serialport:unixRead Finished read 6 bytes +2ms
serialport:main binding.read finished +13ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +49ms
serialport:main _read reading +22ms
serialport:bindings read +32ms
serialport:unixRead Starting read +29ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 16 bytes +10ms
serialport:main binding.read finished +17ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +23ms
serialport:main _read reading +47ms
serialport:bindings read +65ms
serialport:unixRead Starting read +54ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 42 bytes +15ms
serialport:main binding.read finished +23ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 36 },
cc-znp fcs: 226,
cc-znp csum: 226 } +16ms
cc-znp { sof: 254,
cc-znp len: 29,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 434264,
cc-znp transseqnumber: 0,
cc-znp len: 9,
cc-znp data: <Buffer 18 24 01 fd ff 00 21 01 00> },
cc-znp fcs: 49,
cc-znp csum: 49 } +23ms
serialport:main _read reading +45ms
serialport:bindings read +65ms
serialport:unixRead Starting read +49ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 36 } +17ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 36 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 434264, transseqnumber: 0, len: 9, data: <Buffer 18 24 01 fd ff 00 21 01 00> } +5ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
serialport:unixRead waiting for readable because of code: EAGAIN +36ms
serialport:poller Polling for "readable" +203ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +8ms
zigbee2mqtt:debug 10/31/2018, 6:43:30 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
serialport:poller received "readable" +2s
serialport:bindings read +2s
serialport:unixRead Starting read +2s
serialport:unixRead Finished read 16 bytes +5ms
serialport:main binding.read finished +2s
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +2s
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
serialport:main _read reading +51ms
serialport:bindings read +63ms
serialport:unixRead Starting read +58ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 41 bytes +15ms
serialport:main binding.read finished +22ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 33 },
cc-znp fcs: 231,
cc-znp csum: 231 } +16ms
cc-znp { sof: 254,
cc-znp len: 28,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 442409,
cc-znp transseqnumber: 0,
cc-znp len: 8,
cc-znp data: <Buffer 18 21 01 00 00 00 10 00> },
cc-znp fcs: 23,
cc-znp csum: 23 } +22ms
serialport:main _read reading +45ms
serialport:bindings read +65ms
serialport:unixRead Starting read +48ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 33 } +15ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 33 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 442409, transseqnumber: 0, len: 8, data: <Buffer 18 21 01 00 00 00 10 00> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +27ms
serialport:unixRead waiting for readable because of code: EAGAIN +46ms
serialport:poller Polling for "readable" +179ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +7ms
zigbee2mqtt:debug 10/31/2018, 6:43:40 PM Received mqtt message on topic 'zigbee2mqtt/Standing Lamp/set' with data '{"state":"on"}'
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 37 +7s
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 37, options: 48, radius: 30, len: 3, data: <Buffer 01 25 01> } +5ms
serialport:main _write 18 bytes of data +7s
serialport:bindings write 18 bytes +7s
serialport:unixWrite Starting write 18 bytes offset 0 bytesToWrite 18 +10s
serialport:unixWrite write returned null 18 +4ms
serialport:unixWrite wrote 18 bytes +2ms
serialport:unixWrite Finished writing 18 bytes +2ms
serialport:main binding.write write finished +17ms
serialport:poller received "readable" +7s
serialport:bindings read +20ms
serialport:unixRead Starting read +7s
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +13ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +51ms
serialport:main _read reading +21ms
serialport:bindings read +30ms
serialport:unixRead Starting read +24ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +14ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 16 bytes +9ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +23ms
serialport:main _read reading +47ms
serialport:bindings read +61ms
serialport:unixRead Starting read +54ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 38 bytes +16ms
serialport:main binding.read finished +23ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 37 },
cc-znp fcs: 227,
cc-znp csum: 227 } +15ms
cc-znp { sof: 254,
cc-znp len: 25,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 464099,
cc-znp transseqnumber: 0,
cc-znp len: 5,
cc-znp data: <Buffer 08 25 0b 01 00> },
cc-znp fcs: 9,
cc-znp csum: 9 } +24ms
serialport:main _read reading +48ms
serialport:bindings read +69ms
serialport:unixRead Starting read +54ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 37 } +21ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 37 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 464099, transseqnumber: 0, len: 5, data: <Buffer 08 25 0b 01 00> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +12ms
serialport:unixRead waiting for readable because of code: EAGAIN +32ms
serialport:poller Polling for "readable" +199ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +8ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 38 +15ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 38, options: 48, radius: 30, len: 6, data: <Buffer 00 26 0c 00 00 f0> } +4ms
serialport:main _write 21 bytes of data +71ms
serialport:bindings write 21 bytes +72ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +252ms
serialport:unixWrite write returned null 21 +3ms
serialport:unixWrite wrote 21 bytes +2ms
serialport:unixWrite Finished writing 21 bytes +3ms
serialport:main binding.write write finished +26ms
serialport:poller received "readable" +62ms
serialport:bindings read +29ms
serialport:unixRead Starting read +67ms
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +59ms
serialport:main _read reading +23ms
serialport:bindings read +32ms
serialport:unixRead Starting read +31ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +20ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 16 bytes +10ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +19ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +22ms
serialport:main _read reading +48ms
serialport:bindings read +64ms
serialport:unixRead Starting read +53ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +20ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +10ms
serialport:unixRead Finished read 52 bytes +22ms
serialport:main binding.read finished +33ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 38 },
cc-znp fcs: 224,
cc-znp csum: 224 } +21ms
cc-znp { sof: 254,
cc-znp len: 39,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 464893,
cc-znp transseqnumber: 0,
cc-znp len: 19,
cc-znp data:
cc-znp <Buffer 18 26 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> },
cc-znp fcs: 24,
cc-znp csum: 24 } +24ms
serialport:main _read reading +48ms
serialport:bindings read +81ms
serialport:unixRead Starting read +59ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 38 } +21ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 38 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 464893, transseqnumber: 0, len: 19, data: <Buffer 18 26 0d 00 00 00 10 00 40 10 01 40 21 02 40 21 03 40 30> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +48ms
serialport:unixRead waiting for readable because of code: EAGAIN +68ms
serialport:poller Polling for "readable" +251ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +7ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 39 +11ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 39, options: 48, radius: 30, len: 6, data: <Buffer 00 27 0c 04 40 f0> } +4ms
serialport:main _write 21 bytes of data +101ms
serialport:bindings write 21 bytes +101ms
serialport:unixWrite Starting write 21 bytes offset 0 bytesToWrite 21 +292ms
serialport:unixWrite write returned null 21 +2ms
serialport:unixWrite wrote 21 bytes +2ms
serialport:unixWrite Finished writing 21 bytes +3ms
serialport:main binding.write write finished +17ms
serialport:poller received "readable" +47ms
serialport:bindings read +19ms
serialport:unixRead Starting read +53ms
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +49ms
serialport:main _read reading +22ms
serialport:bindings read +32ms
serialport:unixRead Starting read +29ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +19ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +4ms
serialport:unixRead Finished read 8 bytes +9ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
serialport:main _read reading +25ms
serialport:bindings read +42ms
serialport:unixRead Starting read +32ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +21ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +2ms
serialport:unixRead Finished read 32 bytes +9ms
serialport:main binding.read finished +17ms
cc-znp { sof: 254,
cc-znp len: 27,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 465828,
cc-znp transseqnumber: 0,
cc-znp len: 7,
cc-znp data: <Buffer 18 27 0d 01 fd ff 21> },
cc-znp fcs: 112,
cc-znp csum: 112 } +20ms
serialport:main _read reading +25ms
serialport:bindings read +40ms
serialport:unixRead Starting read +30ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 465828, transseqnumber: 0, len: 7, data: <Buffer 18 27 0d 01 fd ff 21> } +16ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +5ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +12ms
serialport:unixRead Finished read 16 bytes +23ms
serialport:main binding.read finished +27ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 39 },
cc-znp fcs: 225,
cc-znp csum: 225 } +15ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
serialport:main _read reading +44ms
serialport:bindings read +72ms
serialport:unixRead Starting read +51ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +20ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 40 +12ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 40, options: 48, radius: 30, len: 13, data: <Buffer 00 28 00 00 00 00 40 01 40 02 40 03 40> } +4ms
serialport:main _write 28 bytes of data +34ms
serialport:bindings write 28 bytes +33ms
serialport:unixWrite Starting write 28 bytes offset 0 bytesToWrite 28 +230ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 39 } +20ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 39 +3ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +4ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +54ms
serialport:poller Polling for "readable" +246ms
serialport:unixWrite write returned null 28 +24ms
serialport:unixWrite wrote 28 bytes +2ms
serialport:unixWrite Finished writing 28 bytes +3ms
serialport:main binding.write write finished +37ms
serialport:poller received "readable" +12ms
serialport:bindings read +41ms
serialport:unixRead Starting read +19ms
serialport:unixRead Finished read 6 bytes +3ms
serialport:main binding.read finished +12ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +37ms
serialport:main _read reading +22ms
serialport:bindings read +31ms
serialport:unixRead Starting read +28ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +19ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +4ms
serialport:unixRead Finished read 16 bytes +10ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +23ms
serialport:main _read reading +48ms
serialport:bindings read +64ms
serialport:unixRead Starting read +55ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +19ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +3ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
serialport:unixRead Finished read 63 bytes +15ms
serialport:main binding.read finished +22ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 40 },
cc-znp fcs: 238,
cc-znp csum: 238 } +15ms
cc-znp { sof: 254,
cc-znp len: 50,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 466606,
cc-znp transseqnumber: 0,
cc-znp len: 30,
cc-znp data:
cc-znp <Buffer 18 28 01 00 00 00 10 01 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> },
cc-znp fcs: 95,
cc-znp csum: 95 } +23ms
serialport:main _read reading +45ms
serialport:bindings read +66ms
serialport:unixRead Starting read +48ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 40 } +15ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 40 +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 466606, transseqnumber: 0, len: 30, data: <Buffer 18 28 01 00 00 00 10 01 00 40 00 10 01 01 40 00 21 00 00 02 40 00 21 00 00 03 40 00 30 01> } +4ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +73ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 41 +17ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 41, options: 48, radius: 30, len: 5, data: <Buffer 00 29 00 00 00> } +5ms
serialport:main _write 20 bytes of data +121ms
serialport:bindings write 20 bytes +122ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +296ms
serialport:unixRead waiting for readable because of code: EAGAIN +130ms
serialport:poller Polling for "readable" +297ms
serialport:unixWrite write returned null 20 +7ms
serialport:unixWrite wrote 20 bytes +3ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +22ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +34ms
zigbee-shepherd:request REQ --> AF:dataRequest, transId: 42 +10ms
serialport:poller received "readable" +26ms
serialport:bindings read +39ms
serialport:unixRead Starting read +33ms
serialport:unixRead Finished read 6 bytes +4ms
serialport:main binding.read finished +27ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +24ms
serialport:main _read reading +22ms
serialport:bindings read +31ms
serialport:unixRead Starting read +28ms
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +19ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
serialport:unixRead Finished read 8 bytes +9ms
serialport:main binding.read finished +15ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
serialport:main _read reading +25ms
serialport:bindings read +41ms
serialport:unixRead Starting read +31ms
cc-znp:SREQ --> AF:dataRequest, { dstaddr: 37345, destendpoint: 1, srcendpoint: 1, clusterid: 6, transid: 42, options: 48, radius: 30, len: 5, data: <Buffer 00 2a 00 fd ff> } +22ms
serialport:main _write 20 bytes of data +19ms
serialport:bindings write 20 bytes +19ms
serialport:unixWrite Starting write 20 bytes offset 0 bytesToWrite 20 +115ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +16ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 41 bytes +29ms
serialport:main binding.read finished +16ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +18ms
cc-znp { sof: 254,
cc-znp len: 28,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 26,
cc-znp securityuse: 0,
cc-znp timestamp: 467634,
cc-znp transseqnumber: 0,
cc-znp len: 8,
cc-znp data: <Buffer 18 29 01 00 00 00 10 01> },
cc-znp fcs: 102,
cc-znp csum: 102 } +33ms
serialport:main _read reading +67ms
serialport:bindings read +83ms
serialport:unixRead Starting read +74ms
serialport:unixWrite write returned null 20 +85ms
serialport:unixWrite wrote 20 bytes +2ms
serialport:unixWrite Finished writing 20 bytes +2ms
serialport:main binding.write write finished +14ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +38ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +3ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 26, securityuse: 0, timestamp: 467634, transseqnumber: 0, len: 8, data: <Buffer 18 29 01 00 00 00 10 01> } +2ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +5ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +13ms
serialport:unixRead Finished read 30 bytes +38ms
serialport:main binding.read finished +31ms
cc-znp { sof: 254,
cc-znp len: 1,
cc-znp type: 'SRSP',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataRequest',
cc-znp payload: { status: 0 },
cc-znp fcs: 100,
cc-znp csum: 100 } +15ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 41 },
cc-znp fcs: 239,
cc-znp csum: 239 } +18ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'ZDO',
cc-znp cmd: 'srcRtgInd',
cc-znp payload: { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > },
cc-znp fcs: 242,
cc-znp csum: 242 } +21ms
serialport:main _read reading +82ms
serialport:bindings read +127ms
serialport:unixRead Starting read +89ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +20ms
zigbee2mqtt:debug 10/31/2018, 6:43:41 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
cc-znp:SRSP <-- AF:dataRequest, { status: 0 } +28ms
zigbee-shepherd:request RSP <-- AF:dataRequest, status: 0 +3ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 41 } +3ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 41 +2ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +4ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +5ms
cc-znp:AREQ <-- ZDO:srcRtgInd, { dstaddr: 37345, relaycount: 0, relaylist: <Buffer > } +2ms
zigbee-shepherd:msgHdlr IND <-- ZDO:srcRtgInd +4ms
serialport:unixRead Finished read 42 bytes +61ms
serialport:main binding.read finished +68ms
cc-znp { sof: 254,
cc-znp len: 29,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'incomingMsg',
cc-znp payload:
cc-znp { groupid: 0,
cc-znp clusterid: 6,
cc-znp srcaddr: 37345,
cc-znp srcendpoint: 1,
cc-znp dstendpoint: 1,
cc-znp wasbroadcast: 0,
cc-znp linkquality: 28,
cc-znp securityuse: 0,
cc-znp timestamp: 467994,
cc-znp transseqnumber: 0,
cc-znp len: 9,
cc-znp data: <Buffer 18 2a 01 fd ff 00 21 01 00> },
cc-znp fcs: 254,
cc-znp csum: 254 } +19ms
cc-znp { sof: 254,
cc-znp len: 3,
cc-znp type: 'AREQ',
cc-znp subsys: 'AF',
cc-znp cmd: 'dataConfirm',
cc-znp payload: { status: 0, endpoint: 1, transid: 42 },
cc-znp fcs: 236,
cc-znp csum: 236 } +35ms
serialport:main _read reading +62ms
serialport:bindings read +130ms
serialport:unixRead Starting read +68ms
cc-znp:AREQ <-- AF:incomingMsg, { groupid: 0, clusterid: 6, srcaddr: 37345, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 28, securityuse: 0, timestamp: 467994, transseqnumber: 0, len: 9, data: <Buffer 18 2a 01 fd ff 00 21 01 00> } +21ms
zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object] +4ms
zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0 +14ms
cc-znp:AREQ <-- AF:dataConfirm, { status: 0, endpoint: 1, transid: 42 } +2ms
zigbee-shepherd:af dispatchIncomingMsg(): type: dataConfirm, msg: [object Object] +3ms
zigbee-shepherd:msgHdlr IND <-- AF:dataConfirm, transId: 42 +3ms
serialport:unixRead waiting for readable because of code: EAGAIN +34ms
serialport:poller Polling for "readable" +471ms
zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object] +9ms
zigbee2mqtt:debug 10/31/2018, 6:43:41 PM Received zigbee message of type 'devChange' with data '{"cid":"genOnOff","data":{"onOff":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffe6d0dcf)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment