> zigbee2mqtt@1.1.1 start /opt/zigbee2mqtt3
> node index.js

  zigbee2mqtt:info 2019-2-16 13:06:17 Logging to directory: '/opt/zigbee2mqtt3/data/log/2019-02-16.13-06-17'
  zigbee2mqtt:debug 2019-2-16 13:06:17 Removing old log directory '/opt/zigbee2mqtt3/data/log/2019-02-16.12-50-53'
2019-02-16T12:06:18.306Z serialport:binding:auto-detect loading LinuxBinding
  zigbee2mqtt:debug 2019-2-16 13:06:18 Using zigbee-shepherd with settings: '{"net":{"panId":6754,"channelList":[11],"precfgkey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]},"dbPath":"/opt/zigbee2mqtt3/data/database.db","sp":{"baudRate":115200,"rtscts":true}}'
  zigbee2mqtt:debug 2019-2-16 13:06:18 Loaded state from file /opt/zigbee2mqtt3/data/state.json
  zigbee2mqtt:info 2019-2-16 13:06:18 Starting zigbee2mqtt version 1.1.1 (commit #92d88b6)
  zigbee2mqtt:info 2019-2-16 13:06:18 Starting zigbee-shepherd
2019-02-16T12:06:18.789Z zigbee-shepherd:init zigbee-shepherd booting...
2019-02-16T12:06:18.804Z serialport:main opening path: /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0018E1A241-if00
2019-02-16T12:06:18.805Z serialport:bindings open
2019-02-16T12:06:18.809Z serialport:main _read queueing _read for after open
2019-02-16T12:06:18.816Z serialport:poller Creating poller
2019-02-16T12:06:18.817Z serialport:main opened path: /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0018E1A241-if00
Sat, 16 Feb 2019 12:06:18 GMT cc-znp The serialport /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0018E1A241-if00 is opened.
2019-02-16T12:06:18.822Z serialport:main _read reading
2019-02-16T12:06:18.823Z serialport:bindings read
2019-02-16T12:06:18.824Z serialport:unixRead Starting read
2019-02-16T12:06:18.827Z zigbee-shepherd:request REQ --> SYS:osalNvRead
Sat, 16 Feb 2019 12:06:18 GMT cc-znp:SREQ --> SYS:osalNvRead, { id: 3840, offset: 0, len: 1, value: [ 85 ] }
2019-02-16T12:06:18.848Z serialport:main _write 8 bytes of data
2019-02-16T12:06:18.849Z serialport:bindings write 8 bytes
2019-02-16T12:06:18.852Z serialport:unixWrite Starting write 8 bytes offset 0 bytesToWrite 8
2019-02-16T12:06:18.856Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:18.857Z serialport:poller Polling for "readable"
2019-02-16T12:06:18.858Z serialport:unixWrite write returned null 8
2019-02-16T12:06:18.858Z serialport:unixWrite wrote 8 bytes
2019-02-16T12:06:18.858Z serialport:unixWrite Finished writing 8 bytes
2019-02-16T12:06:18.859Z serialport:main binding.write write finished
2019-02-16T12:06:18.897Z serialport:poller received "readable"
2019-02-16T12:06:18.898Z serialport:bindings read
2019-02-16T12:06:18.899Z serialport:unixRead Starting read
2019-02-16T12:06:18.900Z serialport:unixRead Finished read 8 bytes
2019-02-16T12:06:18.907Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:18 GMT cc-znp { sof: 254,
  len: 3,
  type: 'SRSP',
  subsys: 'SYS',
  cmd: 'osalNvRead',
  payload: { status: 0, len: 1, value: <Buffer 55> },
  fcs: 62,
  csum: 62 }
2019-02-16T12:06:18.937Z serialport:main _read reading
2019-02-16T12:06:18.937Z serialport:bindings read
2019-02-16T12:06:18.938Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:18 GMT cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 1, value: <Buffer 55> }
2019-02-16T12:06:18.942Z zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0
2019-02-16T12:06:18.944Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:18.945Z serialport:poller Polling for "readable"
2019-02-16T12:06:18.957Z zigbee-shepherd:request REQ --> SAPI:readConfiguration
Sat, 16 Feb 2019 12:06:18 GMT cc-znp:SREQ --> SAPI:readConfiguration, { configid: 131, len: 2, value: [ 98, 26 ] }
2019-02-16T12:06:18.961Z serialport:main _write 6 bytes of data
2019-02-16T12:06:18.961Z serialport:bindings write 6 bytes
2019-02-16T12:06:18.962Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:18.963Z serialport:unixWrite write returned null 6
2019-02-16T12:06:18.964Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:18.964Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:18.964Z serialport:main binding.write write finished
2019-02-16T12:06:19.003Z serialport:poller received "readable"
2019-02-16T12:06:19.004Z serialport:bindings read
2019-02-16T12:06:19.005Z serialport:unixRead Starting read
2019-02-16T12:06:19.005Z serialport:unixRead Finished read 10 bytes
2019-02-16T12:06:19.006Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 5,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'readConfiguration',
  payload: { status: 0, configid: 131, len: 2, value: <Buffer 62 1a> },
  fcs: 158,
  csum: 158 }
2019-02-16T12:06:19.012Z serialport:main _read reading
2019-02-16T12:06:19.013Z serialport:bindings read
2019-02-16T12:06:19.013Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:readConfiguration, { status: 0, configid: 131, len: 2, value: <Buffer 62 1a> }
2019-02-16T12:06:19.015Z zigbee-shepherd:request RSP <-- SAPI:readConfiguration, status: 0
2019-02-16T12:06:19.016Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.017Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.028Z zigbee-shepherd:request REQ --> SAPI:readConfiguration
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:readConfiguration, { configid: 45, len: 8, value: [ 221, 221, 221, 221, 221, 221, 221, 221 ] }
2019-02-16T12:06:19.030Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.031Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.032Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.032Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.032Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.033Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.033Z serialport:main binding.write write finished
2019-02-16T12:06:19.053Z serialport:poller received "readable"
2019-02-16T12:06:19.053Z serialport:bindings read
2019-02-16T12:06:19.053Z serialport:unixRead Starting read
2019-02-16T12:06:19.054Z serialport:unixRead Finished read 16 bytes
2019-02-16T12:06:19.054Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 11,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'readConfiguration',
  payload:
   { status: 0,
     configid: 45,
     len: 8,
     value: <Buffer dd dd dd dd dd dd dd dd> },
  fcs: 76,
  csum: 76 }
2019-02-16T12:06:19.059Z serialport:main _read reading
2019-02-16T12:06:19.059Z serialport:bindings read
2019-02-16T12:06:19.059Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:readConfiguration, { status: 0, configid: 45, len: 8, value: <Buffer dd dd dd dd dd dd dd dd> }
2019-02-16T12:06:19.060Z zigbee-shepherd:request RSP <-- SAPI:readConfiguration, status: 0
2019-02-16T12:06:19.061Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.062Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.072Z zigbee-shepherd:request REQ --> SAPI:readConfiguration
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:readConfiguration, { configid: 132, len: 4, value: [ 0, 8, 0, 0 ] }
2019-02-16T12:06:19.075Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.075Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.076Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.076Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.077Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.077Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.077Z serialport:main binding.write write finished
2019-02-16T12:06:19.087Z serialport:poller received "readable"
2019-02-16T12:06:19.087Z serialport:bindings read
2019-02-16T12:06:19.087Z serialport:unixRead Starting read
2019-02-16T12:06:19.088Z serialport:unixRead Finished read 12 bytes
2019-02-16T12:06:19.088Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 7,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'readConfiguration',
  payload:
   { status: 0, configid: 132, len: 4, value: <Buffer 00 08 00 00> },
  fcs: 237,
  csum: 237 }
2019-02-16T12:06:19.093Z serialport:main _read reading
2019-02-16T12:06:19.093Z serialport:bindings read
2019-02-16T12:06:19.093Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:readConfiguration, { status: 0, configid: 132, len: 4, value: <Buffer 00 08 00 00> }
2019-02-16T12:06:19.095Z zigbee-shepherd:request RSP <-- SAPI:readConfiguration, status: 0
2019-02-16T12:06:19.095Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.096Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.106Z zigbee-shepherd:request REQ --> SAPI:readConfiguration
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:readConfiguration, { configid: 98, len: 16, value: [ 1, 3, 5, 7, 9, 11, 13, 15, 0, 2, 4, 6, 8, 10, 12, 13 ] }
2019-02-16T12:06:19.109Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.109Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.110Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.110Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.110Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.111Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.114Z serialport:main binding.write write finished
2019-02-16T12:06:19.129Z serialport:poller received "readable"
2019-02-16T12:06:19.130Z serialport:bindings read
2019-02-16T12:06:19.130Z serialport:unixRead Starting read
2019-02-16T12:06:19.130Z serialport:unixRead Finished read 24 bytes
2019-02-16T12:06:19.131Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 19,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'readConfiguration',
  payload:
   { status: 0,
     configid: 98,
     len: 16,
     value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  fcs: 0,
  csum: 0 }
2019-02-16T12:06:19.136Z serialport:main _read reading
2019-02-16T12:06:19.136Z serialport:bindings read
2019-02-16T12:06:19.136Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:readConfiguration, { status: 0, configid: 98, len: 16, value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> }
2019-02-16T12:06:19.137Z zigbee-shepherd:request RSP <-- SAPI:readConfiguration, status: 0
2019-02-16T12:06:19.138Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.139Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.149Z zigbee-shepherd:request REQ --> SAPI:readConfiguration
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:readConfiguration, { configid: 99, len: 1, value: [ 0 ] }
2019-02-16T12:06:19.152Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.152Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.153Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.153Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.154Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.154Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.154Z serialport:main binding.write write finished
2019-02-16T12:06:19.166Z serialport:poller received "readable"
2019-02-16T12:06:19.166Z serialport:bindings read
2019-02-16T12:06:19.166Z serialport:unixRead Starting read
2019-02-16T12:06:19.167Z serialport:unixRead Finished read 9 bytes
2019-02-16T12:06:19.167Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 4,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'readConfiguration',
  payload: { status: 0, configid: 99, len: 1, value: <Buffer 00> },
  fcs: 4,
  csum: 4 }
2019-02-16T12:06:19.172Z serialport:main _read reading
2019-02-16T12:06:19.172Z serialport:bindings read
2019-02-16T12:06:19.173Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:readConfiguration, { status: 0, configid: 99, len: 1, value: <Buffer 00> }
2019-02-16T12:06:19.174Z zigbee-shepherd:request RSP <-- SAPI:readConfiguration, status: 0
2019-02-16T12:06:19.175Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.175Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.187Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 0 }
2019-02-16T12:06:19.190Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.190Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.191Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.192Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.192Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.192Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.193Z serialport:main binding.write write finished
2019-02-16T12:06:19.196Z serialport:poller received "readable"
2019-02-16T12:06:19.196Z serialport:bindings read
2019-02-16T12:06:19.196Z serialport:unixRead Starting read
2019-02-16T12:06:19.197Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.197Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 0, value: <Buffer 09 09 00 ff fe 04 66 04> },
  fcs: 14,
  csum: 14 }
2019-02-16T12:06:19.202Z serialport:main _read reading
2019-02-16T12:06:19.202Z serialport:bindings read
2019-02-16T12:06:19.203Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 0, value: <Buffer 09 09 00 ff fe 04 66 04> }
2019-02-16T12:06:19.204Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.205Z zigbee-shepherd:init Now the ZNP is a coordinator.
2019-02-16T12:06:19.206Z zigbee-shepherd:request REQ --> SYS:version
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SYS:version, {}
2019-02-16T12:06:19.208Z serialport:main _write 5 bytes of data
2019-02-16T12:06:19.209Z serialport:bindings write 5 bytes
2019-02-16T12:06:19.210Z serialport:unixWrite Starting write 5 bytes offset 0 bytesToWrite 5
2019-02-16T12:06:19.210Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.210Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.211Z serialport:unixWrite write returned null 5
2019-02-16T12:06:19.211Z serialport:unixWrite wrote 5 bytes
2019-02-16T12:06:19.211Z serialport:unixWrite Finished writing 5 bytes
2019-02-16T12:06:19.212Z serialport:main binding.write write finished
2019-02-16T12:06:19.213Z serialport:poller received "readable"
2019-02-16T12:06:19.214Z serialport:bindings read
2019-02-16T12:06:19.214Z serialport:unixRead Starting read
2019-02-16T12:06:19.214Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.214Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'SRSP',
  subsys: 'SYS',
  cmd: 'version',
  payload:
   { transportrev: 2,
     product: 0,
     majorrel: 2,
     minorrel: 6,
     maintrel: 3,
     revision: 20181024 },
  fcs: 136,
  csum: 136 }
2019-02-16T12:06:19.220Z serialport:main _read reading
2019-02-16T12:06:19.220Z serialport:bindings read
2019-02-16T12:06:19.220Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SYS:version, { transportrev: 2, product: 0, majorrel: 2, minorrel: 6, maintrel: 3, revision: 20181024 }
2019-02-16T12:06:19.222Z zigbee-shepherd:request RSP <-- SYS:version
2019-02-16T12:06:19.225Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 0 }
2019-02-16T12:06:19.227Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.228Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.228Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.229Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.229Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.230Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.230Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.230Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.230Z serialport:main binding.write write finished
2019-02-16T12:06:19.232Z serialport:poller received "readable"
2019-02-16T12:06:19.233Z serialport:bindings read
2019-02-16T12:06:19.233Z serialport:unixRead Starting read
2019-02-16T12:06:19.234Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.234Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 0, value: <Buffer 09 09 00 ff fe 04 66 04> },
  fcs: 14,
  csum: 14 }
2019-02-16T12:06:19.238Z serialport:main _read reading
2019-02-16T12:06:19.239Z serialport:bindings read
2019-02-16T12:06:19.239Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 0, value: <Buffer 09 09 00 ff fe 04 66 04> }
2019-02-16T12:06:19.240Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.241Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 1 }
2019-02-16T12:06:19.244Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.245Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.245Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.246Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.246Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.246Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.246Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.247Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.247Z serialport:main binding.write write finished
2019-02-16T12:06:19.252Z serialport:poller received "readable"
2019-02-16T12:06:19.253Z serialport:bindings read
2019-02-16T12:06:19.253Z serialport:unixRead Starting read
2019-02-16T12:06:19.253Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.254Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 1, value: <Buffer 41 a2 e1 18 00 4b 12 00> },
  fcs: 43,
  csum: 43 }
2019-02-16T12:06:19.258Z serialport:main _read reading
2019-02-16T12:06:19.259Z serialport:bindings read
2019-02-16T12:06:19.259Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 1, value: <Buffer 41 a2 e1 18 00 4b 12 00> }
2019-02-16T12:06:19.260Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.262Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 2 }
2019-02-16T12:06:19.265Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.265Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.266Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.267Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.267Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.267Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.268Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.268Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.268Z serialport:main binding.write write finished
2019-02-16T12:06:19.271Z serialport:poller received "readable"
2019-02-16T12:06:19.272Z serialport:bindings read
2019-02-16T12:06:19.272Z serialport:unixRead Starting read
2019-02-16T12:06:19.272Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.273Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 2, value: <Buffer 00 00 e1 18 00 4b 12 00> },
  fcs: 203,
  csum: 203 }
2019-02-16T12:06:19.277Z serialport:main _read reading
2019-02-16T12:06:19.277Z serialport:bindings read
2019-02-16T12:06:19.278Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 2, value: <Buffer 00 00 e1 18 00 4b 12 00> }
2019-02-16T12:06:19.279Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.280Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 5 }
2019-02-16T12:06:19.283Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.284Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.284Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.285Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.285Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.285Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.285Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.285Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.286Z serialport:main binding.write write finished
2019-02-16T12:06:19.289Z serialport:poller received "readable"
2019-02-16T12:06:19.289Z serialport:bindings read
2019-02-16T12:06:19.289Z serialport:unixRead Starting read
2019-02-16T12:06:19.290Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.290Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 5, value: <Buffer 0b 00 e1 18 00 4b 12 00> },
  fcs: 199,
  csum: 199 }
2019-02-16T12:06:19.295Z serialport:main _read reading
2019-02-16T12:06:19.295Z serialport:bindings read
2019-02-16T12:06:19.295Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 5, value: <Buffer 0b 00 e1 18 00 4b 12 00> }
2019-02-16T12:06:19.296Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.297Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 6 }
2019-02-16T12:06:19.299Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.299Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.300Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.300Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.301Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.301Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.301Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.301Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.302Z serialport:main binding.write write finished
2019-02-16T12:06:19.304Z serialport:poller received "readable"
2019-02-16T12:06:19.304Z serialport:bindings read
2019-02-16T12:06:19.304Z serialport:unixRead Starting read
2019-02-16T12:06:19.305Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.305Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 6, value: <Buffer 63 1a e1 18 00 4b 12 00> },
  fcs: 182,
  csum: 182 }
2019-02-16T12:06:19.310Z serialport:main _read reading
2019-02-16T12:06:19.310Z serialport:bindings read
2019-02-16T12:06:19.311Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 6, value: <Buffer 63 1a e1 18 00 4b 12 00> }
2019-02-16T12:06:19.312Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.314Z zigbee-shepherd:request REQ --> SAPI:getDeviceInfo
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> SAPI:getDeviceInfo, { param: 7 }
2019-02-16T12:06:19.316Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.316Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.317Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.317Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.318Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.318Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.318Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.318Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.318Z serialport:main binding.write write finished
2019-02-16T12:06:19.322Z serialport:poller received "readable"
2019-02-16T12:06:19.322Z serialport:bindings read
2019-02-16T12:06:19.322Z serialport:unixRead Starting read
2019-02-16T12:06:19.323Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:19.323Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 9,
  type: 'SRSP',
  subsys: 'SAPI',
  cmd: 'getDeviceInfo',
  payload: { param: 7, value: <Buffer dd dd dd dd dd dd dd dd> },
  fcs: 110,
  csum: 110 }
2019-02-16T12:06:19.329Z serialport:main _read reading
2019-02-16T12:06:19.329Z serialport:bindings read
2019-02-16T12:06:19.329Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- SAPI:getDeviceInfo, { param: 7, value: <Buffer dd dd dd dd dd dd dd dd> }
2019-02-16T12:06:19.330Z zigbee-shepherd:request RSP <-- SAPI:getDeviceInfo
2019-02-16T12:06:19.334Z zigbee-shepherd:request REQ --> ZDO:nodeDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:19.340Z serialport:main _write 9 bytes of data
2019-02-16T12:06:19.340Z serialport:bindings write 9 bytes
2019-02-16T12:06:19.341Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:19.342Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.342Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.342Z serialport:unixWrite write returned null 9
2019-02-16T12:06:19.343Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:19.343Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:19.343Z serialport:main binding.write write finished
2019-02-16T12:06:19.349Z serialport:poller received "readable"
2019-02-16T12:06:19.349Z serialport:bindings read
2019-02-16T12:06:19.350Z serialport:unixRead Starting read
2019-02-16T12:06:19.350Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.350Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'nodeDescReq',
  payload: { status: 0 },
  fcs: 102,
  csum: 102 }
2019-02-16T12:06:19.355Z serialport:main _read reading
2019-02-16T12:06:19.356Z serialport:bindings read
2019-02-16T12:06:19.356Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 }
2019-02-16T12:06:19.358Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.358Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.358Z serialport:poller received "readable"
2019-02-16T12:06:19.359Z serialport:bindings read
2019-02-16T12:06:19.359Z serialport:unixRead Starting read
2019-02-16T12:06:19.359Z serialport:unixRead Finished read 23 bytes
2019-02-16T12:06:19.359Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 18,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'nodeDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     logicaltype_cmplxdescavai_userdescavai: 0,
     apsflags_freqband: 64,
     maccapflags: 143,
     manufacturercode: 0,
     maxbuffersize: 80,
     maxintransfersize: 160,
     servermask: 1,
     maxouttransfersize: 160,
     descriptorcap: 0 },
  fcs: 75,
  csum: 75 }
2019-02-16T12:06:19.366Z serialport:main _read reading
2019-02-16T12:06:19.366Z serialport:bindings read
2019-02-16T12:06:19.366Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:nodeDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, logicaltype_cmplxdescavai_userdescavai: 0, apsflags_freqband: 64, maccapflags: 143, manufacturercode: 0, maxbuffersize: 80, maxintransfersize: 160, servermask: 1, maxouttransfersize: 160, descriptorcap: 0 }
2019-02-16T12:06:19.368Z zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp
2019-02-16T12:06:19.372Z zigbee-shepherd:request REQ --> ZDO:activeEpReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:19.375Z serialport:main _write 9 bytes of data
2019-02-16T12:06:19.376Z serialport:bindings write 9 bytes
2019-02-16T12:06:19.376Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:19.377Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.377Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.378Z serialport:unixWrite write returned null 9
2019-02-16T12:06:19.378Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:19.378Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:19.378Z serialport:main binding.write write finished
2019-02-16T12:06:19.384Z serialport:poller received "readable"
2019-02-16T12:06:19.385Z serialport:bindings read
2019-02-16T12:06:19.385Z serialport:unixRead Starting read
2019-02-16T12:06:19.386Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.386Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'activeEpReq',
  payload: { status: 0 },
  fcs: 97,
  csum: 97 }
2019-02-16T12:06:19.391Z serialport:main _read reading
2019-02-16T12:06:19.391Z serialport:bindings read
2019-02-16T12:06:19.391Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 }
2019-02-16T12:06:19.393Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.393Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.393Z serialport:poller received "readable"
2019-02-16T12:06:19.394Z serialport:bindings read
2019-02-16T12:06:19.394Z serialport:unixRead Starting read
2019-02-16T12:06:19.394Z serialport:unixRead Finished read 18 bytes
2019-02-16T12:06:19.395Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 13,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'activeEpRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     activeepcount: 7,
     activeeplist: <Buffer 0b 06 05 04 03 02 01> },
  fcs: 198,
  csum: 198 }
2019-02-16T12:06:19.400Z serialport:main _read reading
2019-02-16T12:06:19.401Z serialport:bindings read
2019-02-16T12:06:19.401Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 7, activeeplist: <Buffer 0b 06 05 04 03 02 01> }
2019-02-16T12:06:19.402Z zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp
2019-02-16T12:06:19.407Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 1 }
2019-02-16T12:06:19.410Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.410Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.411Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.412Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.412Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.412Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.413Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.413Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.413Z serialport:main binding.write write finished
2019-02-16T12:06:19.420Z serialport:poller received "readable"
2019-02-16T12:06:19.420Z serialport:bindings read
2019-02-16T12:06:19.420Z serialport:unixRead Starting read
2019-02-16T12:06:19.420Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.421Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.425Z serialport:main _read reading
2019-02-16T12:06:19.425Z serialport:bindings read
2019-02-16T12:06:19.425Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.427Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.427Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.427Z serialport:poller received "readable"
2019-02-16T12:06:19.428Z serialport:bindings read
2019-02-16T12:06:19.428Z serialport:unixRead Starting read
2019-02-16T12:06:19.428Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.428Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 1,
     profileid: 260,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 198,
  csum: 198 }
2019-02-16T12:06:19.436Z serialport:main _read reading
2019-02-16T12:06:19.436Z serialport:bindings read
2019-02-16T12:06:19.437Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 1, profileid: 260, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.438Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.440Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 2 }
2019-02-16T12:06:19.443Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.443Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.444Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.444Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.445Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.445Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.445Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.445Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.445Z serialport:main binding.write write finished
2019-02-16T12:06:19.452Z serialport:poller received "readable"
2019-02-16T12:06:19.453Z serialport:bindings read
2019-02-16T12:06:19.453Z serialport:unixRead Starting read
2019-02-16T12:06:19.453Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.454Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.458Z serialport:main _read reading
2019-02-16T12:06:19.458Z serialport:bindings read
2019-02-16T12:06:19.458Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.459Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.460Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.460Z serialport:poller received "readable"
2019-02-16T12:06:19.460Z serialport:bindings read
2019-02-16T12:06:19.461Z serialport:unixRead Starting read
2019-02-16T12:06:19.461Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.461Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 2,
     profileid: 257,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 192,
  csum: 192 }
2019-02-16T12:06:19.467Z serialport:main _read reading
2019-02-16T12:06:19.468Z serialport:bindings read
2019-02-16T12:06:19.468Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 2, profileid: 257, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.469Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.470Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 3 }
2019-02-16T12:06:19.473Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.473Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.474Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.474Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.475Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.475Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.475Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.475Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.476Z serialport:main binding.write write finished
2019-02-16T12:06:19.483Z serialport:poller received "readable"
2019-02-16T12:06:19.483Z serialport:bindings read
2019-02-16T12:06:19.483Z serialport:unixRead Starting read
2019-02-16T12:06:19.484Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.484Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.488Z serialport:main _read reading
2019-02-16T12:06:19.488Z serialport:bindings read
2019-02-16T12:06:19.489Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.490Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.490Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.490Z serialport:poller received "readable"
2019-02-16T12:06:19.491Z serialport:bindings read
2019-02-16T12:06:19.491Z serialport:unixRead Starting read
2019-02-16T12:06:19.491Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.492Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 3,
     profileid: 261,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 197,
  csum: 197 }
2019-02-16T12:06:19.498Z serialport:main _read reading
2019-02-16T12:06:19.499Z serialport:bindings read
2019-02-16T12:06:19.499Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 3, profileid: 261, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.500Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.501Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 4 }
2019-02-16T12:06:19.504Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.505Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.505Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.506Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.506Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.507Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.507Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.507Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.507Z serialport:main binding.write write finished
2019-02-16T12:06:19.514Z serialport:poller received "readable"
2019-02-16T12:06:19.515Z serialport:bindings read
2019-02-16T12:06:19.515Z serialport:unixRead Starting read
2019-02-16T12:06:19.515Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.516Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.520Z serialport:main _read reading
2019-02-16T12:06:19.520Z serialport:bindings read
2019-02-16T12:06:19.521Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.522Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.522Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 4,
     profileid: 263,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 192,
  csum: 192 }
2019-02-16T12:06:19.529Z serialport:main _read reading
2019-02-16T12:06:19.529Z serialport:bindings read
2019-02-16T12:06:19.529Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 4, profileid: 263, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.530Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.531Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 5 }
2019-02-16T12:06:19.534Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.534Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.535Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.536Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.536Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.536Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.536Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.536Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.537Z serialport:main binding.write write finished
2019-02-16T12:06:19.543Z serialport:poller received "readable"
2019-02-16T12:06:19.543Z serialport:bindings read
2019-02-16T12:06:19.544Z serialport:unixRead Starting read
2019-02-16T12:06:19.544Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.544Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.548Z serialport:main _read reading
2019-02-16T12:06:19.549Z serialport:bindings read
2019-02-16T12:06:19.549Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.550Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.550Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.551Z serialport:poller received "readable"
2019-02-16T12:06:19.551Z serialport:bindings read
2019-02-16T12:06:19.552Z serialport:unixRead Starting read
2019-02-16T12:06:19.552Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.552Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 5,
     profileid: 264,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 206,
  csum: 206 }
2019-02-16T12:06:19.559Z serialport:main _read reading
2019-02-16T12:06:19.559Z serialport:bindings read
2019-02-16T12:06:19.559Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 5, profileid: 264, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.561Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.562Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 6 }
2019-02-16T12:06:19.565Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.566Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.567Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.567Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.568Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.568Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.568Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.568Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.568Z serialport:main binding.write write finished
2019-02-16T12:06:19.575Z serialport:poller received "readable"
2019-02-16T12:06:19.575Z serialport:bindings read
2019-02-16T12:06:19.575Z serialport:unixRead Starting read
2019-02-16T12:06:19.576Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.576Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.580Z serialport:main _read reading
2019-02-16T12:06:19.580Z serialport:bindings read
2019-02-16T12:06:19.581Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.582Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.582Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.583Z serialport:poller received "readable"
2019-02-16T12:06:19.583Z serialport:bindings read
2019-02-16T12:06:19.583Z serialport:unixRead Starting read
2019-02-16T12:06:19.583Z serialport:unixRead Finished read 19 bytes
2019-02-16T12:06:19.584Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 14,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 8,
     endpoint: 6,
     profileid: 265,
     deviceid: 5,
     deviceversion: 0,
     numinclusters: 0,
     inclusterlist: <Buffer >,
     numoutclusters: 0,
     outclusterlist: <Buffer > },
  fcs: 204,
  csum: 204 }
2019-02-16T12:06:19.590Z serialport:main _read reading
2019-02-16T12:06:19.590Z serialport:bindings read
2019-02-16T12:06:19.590Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 8, endpoint: 6, profileid: 265, deviceid: 5, deviceversion: 0, numinclusters: 0, inclusterlist: <Buffer >, numoutclusters: 0, outclusterlist: <Buffer > }
2019-02-16T12:06:19.591Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.592Z zigbee-shepherd:request REQ --> ZDO:simpleDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:simpleDescReq, { dstaddr: 0, nwkaddrofinterest: 0, endpoint: 11 }
2019-02-16T12:06:19.595Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.595Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.596Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.596Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.597Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.597Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.597Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.597Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.598Z serialport:main binding.write write finished
2019-02-16T12:06:19.604Z serialport:poller received "readable"
2019-02-16T12:06:19.604Z serialport:bindings read
2019-02-16T12:06:19.604Z serialport:unixRead Starting read
2019-02-16T12:06:19.605Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.605Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'simpleDescReq',
  payload: { status: 0 },
  fcs: 96,
  csum: 96 }
2019-02-16T12:06:19.609Z serialport:main _read reading
2019-02-16T12:06:19.610Z serialport:bindings read
2019-02-16T12:06:19.610Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:simpleDescReq, { status: 0 }
2019-02-16T12:06:19.612Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.612Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.613Z serialport:poller received "readable"
2019-02-16T12:06:19.613Z serialport:bindings read
2019-02-16T12:06:19.613Z serialport:unixRead Starting read
2019-02-16T12:06:19.614Z serialport:unixRead Finished read 31 bytes
2019-02-16T12:06:19.614Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 26,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'simpleDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     len: 20,
     endpoint: 11,
     profileid: 260,
     deviceid: 1024,
     deviceversion: 0,
     numinclusters: 3,
     inclusterlist: <Buffer 00 00 01 05 03 00>,
     numoutclusters: 3,
     outclusterlist: <Buffer 00 05 02 05 03 00> },
  fcs: 195,
  csum: 195 }
2019-02-16T12:06:19.623Z serialport:main _read reading
2019-02-16T12:06:19.624Z serialport:bindings read
2019-02-16T12:06:19.625Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:simpleDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, len: 20, endpoint: 11, profileid: 260, deviceid: 1024, deviceversion: 0, numinclusters: 3, inclusterlist: <Buffer 00 00 01 05 03 00>, numoutclusters: 3, outclusterlist: <Buffer 00 05 02 05 03 00> }
2019-02-16T12:06:19.628Z zigbee-shepherd:msgHdlr IND <-- ZDO:simpleDescRsp
2019-02-16T12:06:19.632Z zigbee-shepherd:request REQ --> AF:delete
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> AF:delete, { endpoint: 11 }
2019-02-16T12:06:19.635Z serialport:main _write 6 bytes of data
2019-02-16T12:06:19.636Z serialport:bindings write 6 bytes
2019-02-16T12:06:19.637Z serialport:unixWrite Starting write 6 bytes offset 0 bytesToWrite 6
2019-02-16T12:06:19.637Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.638Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.638Z serialport:unixWrite write returned null 6
2019-02-16T12:06:19.639Z serialport:unixWrite wrote 6 bytes
2019-02-16T12:06:19.639Z serialport:unixWrite Finished writing 6 bytes
2019-02-16T12:06:19.639Z serialport:main binding.write write finished
2019-02-16T12:06:19.642Z serialport:poller received "readable"
2019-02-16T12:06:19.642Z serialport:bindings read
2019-02-16T12:06:19.643Z serialport:unixRead Starting read
2019-02-16T12:06:19.643Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.644Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'AF',
  cmd: 'delete',
  payload: { status: 0 },
  fcs: 97,
  csum: 97 }
2019-02-16T12:06:19.650Z serialport:main _read reading
2019-02-16T12:06:19.650Z serialport:bindings read
2019-02-16T12:06:19.651Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- AF:delete, { status: 0 }
2019-02-16T12:06:19.652Z zigbee-shepherd:request RSP <-- AF:delete, status: 0
2019-02-16T12:06:19.653Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.654Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.664Z zigbee-shepherd:init Deregister endpoint, epId: 11
2019-02-16T12:06:19.670Z zigbee-shepherd:request REQ --> ZDO:nodeDescReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:19.674Z serialport:main _write 9 bytes of data
2019-02-16T12:06:19.674Z serialport:bindings write 9 bytes
2019-02-16T12:06:19.675Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:19.676Z serialport:unixWrite write returned null 9
2019-02-16T12:06:19.677Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:19.678Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:19.679Z serialport:main binding.write write finished
2019-02-16T12:06:19.684Z serialport:poller received "readable"
2019-02-16T12:06:19.685Z serialport:bindings read
2019-02-16T12:06:19.686Z serialport:unixRead Starting read
2019-02-16T12:06:19.687Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.687Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'nodeDescReq',
  payload: { status: 0 },
  fcs: 102,
  csum: 102 }
2019-02-16T12:06:19.693Z serialport:main _read reading
2019-02-16T12:06:19.693Z serialport:bindings read
2019-02-16T12:06:19.694Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 }
2019-02-16T12:06:19.696Z serialport:unixRead Finished read 23 bytes
2019-02-16T12:06:19.696Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 18,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'nodeDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     logicaltype_cmplxdescavai_userdescavai: 0,
     apsflags_freqband: 64,
     maccapflags: 143,
     manufacturercode: 0,
     maxbuffersize: 80,
     maxintransfersize: 160,
     servermask: 1,
     maxouttransfersize: 160,
     descriptorcap: 0 },
  fcs: 75,
  csum: 75 }
2019-02-16T12:06:19.704Z serialport:main _read reading
2019-02-16T12:06:19.704Z serialport:bindings read
2019-02-16T12:06:19.704Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:nodeDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, logicaltype_cmplxdescavai_userdescavai: 0, apsflags_freqband: 64, maccapflags: 143, manufacturercode: 0, maxbuffersize: 80, maxintransfersize: 160, servermask: 1, maxouttransfersize: 160, descriptorcap: 0 }
2019-02-16T12:06:19.706Z zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp
2019-02-16T12:06:19.708Z zigbee-shepherd:request REQ --> ZDO:activeEpReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:19.712Z serialport:main _write 9 bytes of data
2019-02-16T12:06:19.712Z serialport:bindings write 9 bytes
2019-02-16T12:06:19.713Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:19.714Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.715Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.715Z serialport:unixWrite write returned null 9
2019-02-16T12:06:19.716Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:19.716Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:19.716Z serialport:main binding.write write finished
2019-02-16T12:06:19.722Z serialport:poller received "readable"
2019-02-16T12:06:19.723Z serialport:bindings read
2019-02-16T12:06:19.723Z serialport:unixRead Starting read
2019-02-16T12:06:19.724Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.724Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'activeEpReq',
  payload: { status: 0 },
  fcs: 97,
  csum: 97 }
2019-02-16T12:06:19.730Z serialport:main _read reading
2019-02-16T12:06:19.730Z serialport:bindings read
2019-02-16T12:06:19.731Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 }
2019-02-16T12:06:19.733Z serialport:unixRead Finished read 17 bytes
2019-02-16T12:06:19.734Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 12,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'activeEpRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     activeepcount: 6,
     activeeplist: <Buffer 06 05 04 03 02 01> },
  fcs: 205,
  csum: 205 }
2019-02-16T12:06:19.743Z serialport:main _read reading
2019-02-16T12:06:19.743Z serialport:bindings read
2019-02-16T12:06:19.743Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 6, activeeplist: <Buffer 06 05 04 03 02 01> }
2019-02-16T12:06:19.745Z zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp
2019-02-16T12:06:19.749Z zigbee-shepherd:request REQ --> ZDO:mgmtPermitJoinReq
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SREQ --> ZDO:mgmtPermitJoinReq, { addrmode: 2, dstaddr: 0, duration: 0, tcsignificance: 0 }
2019-02-16T12:06:19.753Z serialport:main _write 10 bytes of data
2019-02-16T12:06:19.754Z serialport:bindings write 10 bytes
2019-02-16T12:06:19.754Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:19.755Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.755Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.756Z serialport:unixWrite write returned null 10
2019-02-16T12:06:19.756Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:19.756Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:19.756Z serialport:main binding.write write finished
2019-02-16T12:06:19.764Z serialport:poller received "readable"
2019-02-16T12:06:19.764Z serialport:bindings read
2019-02-16T12:06:19.765Z serialport:unixRead Starting read
2019-02-16T12:06:19.765Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:19.765Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'mgmtPermitJoinReq',
  payload: { status: 0 },
  fcs: 82,
  csum: 82 }
2019-02-16T12:06:19.783Z serialport:main _read reading
2019-02-16T12:06:19.783Z serialport:bindings read
2019-02-16T12:06:19.784Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:SRSP <-- ZDO:mgmtPermitJoinReq, { status: 0 }
2019-02-16T12:06:19.785Z serialport:unixRead Finished read 8 bytes
2019-02-16T12:06:19.785Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:19 GMT cc-znp { sof: 254,
  len: 3,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'mgmtPermitJoinRsp',
  payload: { srcaddr: 0, status: 0 },
  fcs: 240,
  csum: 240 }
2019-02-16T12:06:19.790Z serialport:main _read reading
2019-02-16T12:06:19.790Z serialport:bindings read
2019-02-16T12:06:19.791Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:19 GMT cc-znp:AREQ <-- ZDO:mgmtPermitJoinRsp, { srcaddr: 0, status: 0 }
2019-02-16T12:06:19.792Z zigbee-shepherd:msgHdlr IND <-- ZDO:mgmtPermitJoinRsp
2019-02-16T12:06:19.799Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:19.799Z serialport:poller Polling for "readable"
2019-02-16T12:06:19.888Z zigbee-shepherd:init Loading devices from database done.
2019-02-16T12:06:19.889Z zigbee-shepherd zigbee-shepherd is _ready and _enabled
  zigbee2mqtt:info 2019-2-16 13:06:19 zigbee-shepherd started
  zigbee2mqtt:info 2019-2-16 13:06:19 Coordinator firmware version: '20181024'
  zigbee2mqtt:debug 2019-2-16 13:06:19 zigbee-shepherd info: {"enabled":true,"net":{"state":"Coordinator","channel":11,"panId":"0x1a63","extPanId":"0xdddddddddddddddd","ieeeAddr":"0x00124b0018e1a241","nwkAddr":0},"firmware":{"transportrev":2,"product":0,"version":"2.6.3","revision":20181024},"startTime":1550318779,"joinTimeLeft":0}
  zigbee2mqtt:info 2019-2-16 13:06:19 Currently 4 devices are joined:
  zigbee2mqtt:info 2019-2-16 13:06:19 0x00158d0003278d3a (0x00158d0003278d3a): 404006/404008/404004 - Müller Licht Tint LED bulb GU10/E14/E27 350/470/806 lumen, dimmable, opal white (Router)
  zigbee2mqtt:info 2019-2-16 13:06:19 0x00158d000204a625 (0x00158d000204a625): WXKG11LM - Xiaomi Aqara wireless switch (EndDevice)
  zigbee2mqtt:info 2019-2-16 13:06:19 0x00158d0002ffc91d (0x00158d0002ffc91d): MLI-404011 - Müller Licht Tint remote control (EndDevice)
  zigbee2mqtt:info 2019-2-16 13:06:19 0x00158d00034218a5 (0x00158d00034218a5): 404000/404005/404012 - Müller Licht Tint LED bulb GU10/E14/E27 350/470/806 lumen, dimmable, color, opal white (Router)
  zigbee2mqtt:warn 2019-2-16 13:06:19 `permit_join` set to  `true` in configuration.yaml.
  zigbee2mqtt:warn 2019-2-16 13:06:19 Allowing new devices to join.
  zigbee2mqtt:warn 2019-2-16 13:06:19 Set `permit_join` to `false` once you joined all devices.
  zigbee2mqtt:info 2019-2-16 13:06:19 Zigbee: allowing new devices to join.
  zigbee2mqtt:info 2019-2-16 13:06:19 Connecting to MQTT server at mqtt://localhost
  zigbee2mqtt:debug 2019-2-16 13:06:19 Using MQTT client ID: 'zigbee_pi'
2019-02-16T12:06:20.123Z zigbee-shepherd:request REQ --> ZDO:mgmtPermitJoinReq
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SREQ --> ZDO:mgmtPermitJoinReq, { addrmode: 15, dstaddr: 65532, duration: 255, tcsignificance: 0 }
2019-02-16T12:06:20.127Z serialport:main _write 10 bytes of data
2019-02-16T12:06:20.127Z serialport:bindings write 10 bytes
2019-02-16T12:06:20.130Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
  zigbee2mqtt:info 2019-2-16 13:06:20 zigbee-shepherd ready
2019-02-16T12:06:20.146Z zigbee-shepherd:request REQ --> AF:register
2019-02-16T12:06:20.147Z serialport:unixWrite write returned null 10
2019-02-16T12:06:20.147Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:20.147Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:20.148Z serialport:main binding.write write finished
2019-02-16T12:06:20.152Z serialport:poller received "readable"
2019-02-16T12:06:20.152Z serialport:bindings read
2019-02-16T12:06:20.153Z serialport:unixRead Starting read
  zigbee2mqtt:info 2019-2-16 13:06:20 Connected to MQTT server
  zigbee2mqtt:info 2019-2-16 13:06:20 MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'online'
  zigbee2mqtt:info 2019-2-16 13:06:20 MQTT publish: topic 'zigbee2mqtt/0x00158d0003278d3a', payload '{"state":"OFF","brightness":225,"color_temp":250,"color":{"x":0.458,"y":0.41}}'
  zigbee2mqtt:info 2019-2-16 13:06:20 MQTT publish: topic 'zigbee2mqtt/0x00158d000204a625', payload '{"battery":100,"voltage":3035,"linkquality":70}'
  zigbee2mqtt:info 2019-2-16 13:06:20 MQTT publish: topic 'zigbee2mqtt/0x00158d00034218a5', payload '{"state":"OFF","brightness":75,"color_temp":153,"color":{"x":0.309,"y":0.354,"saturation":23.6,"hue":190.3},"color_mode":1}'
2019-02-16T12:06:20.213Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:20.214Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'mgmtPermitJoinReq',
  payload: { status: 0 },
  fcs: 82,
  csum: 82 }
2019-02-16T12:06:20.219Z serialport:main _read reading
2019-02-16T12:06:20.219Z serialport:bindings read
2019-02-16T12:06:20.220Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SRSP <-- ZDO:mgmtPermitJoinReq, { status: 0 }
2019-02-16T12:06:20.223Z serialport:unixRead Finished read 14 bytes
2019-02-16T12:06:20.224Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 1,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'permitJoinInd',
  payload: { duration: 255 },
  fcs: 112,
  csum: 112 }
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 3,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'mgmtPermitJoinRsp',
  payload: { srcaddr: 0, status: 0 },
  fcs: 240,
  csum: 240 }
2019-02-16T12:06:20.235Z serialport:main _read reading
2019-02-16T12:06:20.235Z serialport:bindings read
2019-02-16T12:06:20.236Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SREQ --> AF:register, { endpoint: 11, appprofid: 260, appdeviceid: 1024, appdevver: 0, latencyreq: 0, appnuminclusters: 3, appinclusterlist: [ 0, 1281, 3 ], appnumoutclusters: 3, appoutclusterlist: [ 1280, 1282, 3 ] }
2019-02-16T12:06:20.242Z serialport:main _write 26 bytes of data
2019-02-16T12:06:20.242Z serialport:bindings write 26 bytes
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:AREQ <-- ZDO:permitJoinInd, { duration: 255 }
2019-02-16T12:06:20.244Z zigbee-shepherd:msgHdlr IND <-- ZDO:permitJoinInd
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:AREQ <-- ZDO:mgmtPermitJoinRsp, { srcaddr: 0, status: 0 }
2019-02-16T12:06:20.245Z zigbee-shepherd:msgHdlr IND <-- ZDO:mgmtPermitJoinRsp
2019-02-16T12:06:20.245Z serialport:unixWrite Starting write 26 bytes offset 0 bytesToWrite 26
2019-02-16T12:06:20.246Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:20.247Z serialport:poller Polling for "readable"
2019-02-16T12:06:20.247Z serialport:unixWrite write returned null 26
2019-02-16T12:06:20.248Z serialport:unixWrite wrote 26 bytes
2019-02-16T12:06:20.248Z serialport:unixWrite Finished writing 26 bytes
2019-02-16T12:06:20.248Z serialport:main binding.write write finished
2019-02-16T12:06:20.253Z serialport:poller received "readable"
2019-02-16T12:06:20.254Z serialport:bindings read
2019-02-16T12:06:20.254Z serialport:unixRead Starting read
2019-02-16T12:06:20.255Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:20.255Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'AF',
  cmd: 'register',
  payload: { status: 0 },
  fcs: 101,
  csum: 101 }
2019-02-16T12:06:20.260Z serialport:main _read reading
2019-02-16T12:06:20.261Z serialport:bindings read
2019-02-16T12:06:20.261Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SRSP <-- AF:register, { status: 0 }
2019-02-16T12:06:20.263Z zigbee-shepherd:request RSP <-- AF:register, status: 0
2019-02-16T12:06:20.264Z zigbee-shepherd Register zApp, epId: 11, profId: 260 
2019-02-16T12:06:20.265Z zigbee-shepherd:request REQ --> ZDO:nodeDescReq
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:20.269Z serialport:main _write 9 bytes of data
2019-02-16T12:06:20.269Z serialport:bindings write 9 bytes
2019-02-16T12:06:20.270Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:20.271Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:20.271Z serialport:poller Polling for "readable"
2019-02-16T12:06:20.271Z serialport:unixWrite write returned null 9
2019-02-16T12:06:20.271Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:20.271Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:20.272Z serialport:main binding.write write finished
2019-02-16T12:06:20.282Z serialport:poller received "readable"
2019-02-16T12:06:20.283Z serialport:bindings read
2019-02-16T12:06:20.283Z serialport:unixRead Starting read
2019-02-16T12:06:20.283Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:20.284Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'nodeDescReq',
  payload: { status: 0 },
  fcs: 102,
  csum: 102 }
2019-02-16T12:06:20.288Z serialport:main _read reading
2019-02-16T12:06:20.288Z serialport:bindings read
2019-02-16T12:06:20.289Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 }
2019-02-16T12:06:20.290Z serialport:unixRead Finished read 23 bytes
2019-02-16T12:06:20.290Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 18,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'nodeDescRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     logicaltype_cmplxdescavai_userdescavai: 0,
     apsflags_freqband: 64,
     maccapflags: 143,
     manufacturercode: 0,
     maxbuffersize: 80,
     maxintransfersize: 160,
     servermask: 1,
     maxouttransfersize: 160,
     descriptorcap: 0 },
  fcs: 75,
  csum: 75 }
2019-02-16T12:06:20.295Z serialport:main _read reading
2019-02-16T12:06:20.295Z serialport:bindings read
2019-02-16T12:06:20.296Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:AREQ <-- ZDO:nodeDescRsp, { srcaddr: 0, status: 0, nwkaddr: 0, logicaltype_cmplxdescavai_userdescavai: 0, apsflags_freqband: 64, maccapflags: 143, manufacturercode: 0, maxbuffersize: 80, maxintransfersize: 160, servermask: 1, maxouttransfersize: 160, descriptorcap: 0 }
2019-02-16T12:06:20.297Z zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp
2019-02-16T12:06:20.298Z zigbee-shepherd:request REQ --> ZDO:activeEpReq
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 }
2019-02-16T12:06:20.300Z serialport:main _write 9 bytes of data
2019-02-16T12:06:20.300Z serialport:bindings write 9 bytes
2019-02-16T12:06:20.301Z serialport:unixWrite Starting write 9 bytes offset 0 bytesToWrite 9
2019-02-16T12:06:20.302Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:20.302Z serialport:poller Polling for "readable"
2019-02-16T12:06:20.302Z serialport:unixWrite write returned null 9
2019-02-16T12:06:20.302Z serialport:unixWrite wrote 9 bytes
2019-02-16T12:06:20.302Z serialport:unixWrite Finished writing 9 bytes
2019-02-16T12:06:20.303Z serialport:main binding.write write finished
2019-02-16T12:06:20.310Z serialport:poller received "readable"
2019-02-16T12:06:20.310Z serialport:bindings read
2019-02-16T12:06:20.310Z serialport:unixRead Starting read
2019-02-16T12:06:20.311Z serialport:unixRead Finished read 6 bytes
2019-02-16T12:06:20.311Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 1,
  type: 'SRSP',
  subsys: 'ZDO',
  cmd: 'activeEpReq',
  payload: { status: 0 },
  fcs: 97,
  csum: 97 }
2019-02-16T12:06:20.315Z serialport:main _read reading
2019-02-16T12:06:20.315Z serialport:bindings read
2019-02-16T12:06:20.316Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 }
2019-02-16T12:06:20.317Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:20.317Z serialport:poller Polling for "readable"
2019-02-16T12:06:20.317Z serialport:poller received "readable"
2019-02-16T12:06:20.318Z serialport:bindings read
2019-02-16T12:06:20.318Z serialport:unixRead Starting read
2019-02-16T12:06:20.318Z serialport:unixRead Finished read 18 bytes
2019-02-16T12:06:20.318Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:20 GMT cc-znp { sof: 254,
  len: 13,
  type: 'AREQ',
  subsys: 'ZDO',
  cmd: 'activeEpRsp',
  payload:
   { srcaddr: 0,
     status: 0,
     nwkaddr: 0,
     activeepcount: 7,
     activeeplist: <Buffer 0b 06 05 04 03 02 01> },
  fcs: 198,
  csum: 198 }
2019-02-16T12:06:20.324Z serialport:main _read reading
2019-02-16T12:06:20.324Z serialport:bindings read
2019-02-16T12:06:20.324Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:20 GMT cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 7, activeeplist: <Buffer 0b 06 05 04 03 02 01> }
2019-02-16T12:06:20.325Z zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp
2019-02-16T12:06:20.329Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:20.329Z serialport:poller Polling for "readable"
  zigbee2mqtt:debug 2019-2-16 13:06:20 Mounted the cieApp (epId 11)
2019-02-16T12:06:22.233Z serialport:poller received "readable"
2019-02-16T12:06:22.233Z serialport:bindings read
2019-02-16T12:06:22.234Z serialport:unixRead Starting read
2019-02-16T12:06:22.234Z serialport:unixRead Finished read 28 bytes
2019-02-16T12:06:22.235Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:22 GMT cc-znp { sof: 254,
  len: 23,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 6,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 15,
     securityuse: 0,
     timestamp: 6317811,
     transseqnumber: 0,
     len: 3,
     data: <Buffer 11 86 00> },
  fcs: 80,
  csum: 80 }
2019-02-16T12:06:22.245Z serialport:main _read reading
2019-02-16T12:06:22.246Z serialport:bindings read
2019-02-16T12:06:22.246Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:22 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 6, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 15, securityuse: 0, timestamp: 6317811, transseqnumber: 0, len: 3, data: <Buffer 11 86 00> }
2019-02-16T12:06:22.252Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:22.269Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:22.270Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:22.271Z serialport:poller Polling for "readable"
2019-02-16T12:06:22.272Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
  zigbee2mqtt:debug 2019-2-16 13:06:22 Received zigbee message of type 'cmdOff' with data '{"cid":"genOnOff","data":{}}' of device 'ZBT-Remote-ALL-RGBW' (0x00158d0002ffc91d)
  zigbee2mqtt:info 2019-2-16 13:06:22 MQTT publish: topic 'zigbee2mqtt/0x00158d0002ffc91d', payload '{"action":"toggle","linkquality":15}'
2019-02-16T12:06:24.411Z serialport:poller received "readable"
2019-02-16T12:06:24.412Z serialport:bindings read
2019-02-16T12:06:24.412Z serialport:unixRead Starting read
2019-02-16T12:06:24.413Z serialport:unixRead Finished read 36 bytes
2019-02-16T12:06:24.414Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:24 GMT cc-znp { sof: 254,
  len: 31,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 73,
     securityuse: 0,
     timestamp: 6324618,
     transseqnumber: 0,
     len: 11,
     data: <Buffer 11 87 07 80 1e 9f 24 00 00 00 00> },
  fcs: 186,
  csum: 186 }
2019-02-16T12:06:24.423Z serialport:main _read reading
2019-02-16T12:06:24.424Z serialport:bindings read
2019-02-16T12:06:24.424Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:24 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 73, securityuse: 0, timestamp: 6324618, transseqnumber: 0, len: 11, data: <Buffer 11 87 07 80 1e 9f 24 00 00 00 00> }
2019-02-16T12:06:24.426Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:24.440Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:24.441Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:24.441Z serialport:poller Polling for "readable"
2019-02-16T12:06:24.442Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:26.667Z serialport:poller received "readable"
2019-02-16T12:06:26.667Z serialport:bindings read
2019-02-16T12:06:26.668Z serialport:unixRead Starting read
2019-02-16T12:06:26.668Z serialport:unixRead Finished read 36 bytes
2019-02-16T12:06:26.669Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:26 GMT cc-znp { sof: 254,
  len: 31,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 13,
     securityuse: 0,
     timestamp: 6331663,
     transseqnumber: 0,
     len: 11,
     data: <Buffer 11 88 07 50 1e 87 32 00 00 00 00> },
  fcs: 162,
  csum: 162 }
2019-02-16T12:06:26.679Z serialport:main _read reading
2019-02-16T12:06:26.679Z serialport:bindings read
2019-02-16T12:06:26.679Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:26 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 13, securityuse: 0, timestamp: 6331663, transseqnumber: 0, len: 11, data: <Buffer 11 88 07 50 1e 87 32 00 00 00 00> }
2019-02-16T12:06:26.681Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:26.688Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:26.690Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:26.690Z serialport:poller Polling for "readable"
2019-02-16T12:06:26.691Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:28.859Z serialport:poller received "readable"
2019-02-16T12:06:28.860Z serialport:bindings read
2019-02-16T12:06:28.860Z serialport:unixRead Starting read
2019-02-16T12:06:28.861Z serialport:unixRead Finished read 36 bytes
2019-02-16T12:06:28.862Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:28 GMT cc-znp { sof: 254,
  len: 31,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 70,
     securityuse: 0,
     timestamp: 6338515,
     transseqnumber: 0,
     len: 11,
     data: <Buffer 11 89 07 80 1e 9f 24 00 00 00 00> },
  fcs: 212,
  csum: 212 }
2019-02-16T12:06:28.872Z serialport:main _read reading
2019-02-16T12:06:28.872Z serialport:bindings read
2019-02-16T12:06:28.872Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:28 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 70, securityuse: 0, timestamp: 6338515, transseqnumber: 0, len: 11, data: <Buffer 11 89 07 80 1e 9f 24 00 00 00 00> }
2019-02-16T12:06:28.874Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:28.881Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:28.883Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:28.883Z serialport:poller Polling for "readable"
2019-02-16T12:06:28.884Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:30.739Z serialport:poller received "readable"
2019-02-16T12:06:30.740Z serialport:bindings read
2019-02-16T12:06:30.740Z serialport:unixRead Starting read
2019-02-16T12:06:30.740Z serialport:unixRead Finished read 36 bytes
2019-02-16T12:06:30.741Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:30 GMT cc-znp { sof: 254,
  len: 31,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 73,
     securityuse: 0,
     timestamp: 6344390,
     transseqnumber: 0,
     len: 11,
     data: <Buffer 11 8a 07 50 1e 87 32 00 00 00 00> },
  fcs: 106,
  csum: 106 }
2019-02-16T12:06:30.746Z serialport:main _read reading
2019-02-16T12:06:30.747Z serialport:bindings read
2019-02-16T12:06:30.747Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:30 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 73, securityuse: 0, timestamp: 6344390, transseqnumber: 0, len: 11, data: <Buffer 11 8a 07 50 1e 87 32 00 00 00 00> }
2019-02-16T12:06:30.748Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:30.752Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:30.753Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:30.753Z serialport:poller Polling for "readable"
2019-02-16T12:06:30.753Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:32.649Z serialport:poller received "readable"
2019-02-16T12:06:32.649Z serialport:bindings read
2019-02-16T12:06:32.650Z serialport:unixRead Starting read
2019-02-16T12:06:32.651Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:32.651Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:32 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 70,
     securityuse: 0,
     timestamp: 6350359,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 11 8b 0a 72 01 0a 00 00 00> },
  fcs: 18,
  csum: 18 }
2019-02-16T12:06:32.661Z serialport:main _read reading
2019-02-16T12:06:32.662Z serialport:bindings read
2019-02-16T12:06:32.662Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:32 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 70, securityuse: 0, timestamp: 6350359, transseqnumber: 0, len: 9, data: <Buffer 11 8b 0a 72 01 0a 00 00 00> }
2019-02-16T12:06:32.664Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:32.671Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:32.672Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:32.673Z serialport:poller Polling for "readable"
2019-02-16T12:06:32.673Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:34.749Z serialport:poller received "readable"
2019-02-16T12:06:34.750Z serialport:bindings read
2019-02-16T12:06:34.750Z serialport:unixRead Starting read
2019-02-16T12:06:34.754Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:34.755Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:34 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 768,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 70,
     securityuse: 0,
     timestamp: 6356901,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 11 8c 0a fa 00 0a 00 00 00> },
  fcs: 55,
  csum: 55 }
2019-02-16T12:06:34.766Z serialport:main _read reading
2019-02-16T12:06:34.767Z serialport:bindings read
2019-02-16T12:06:34.767Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:34 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 768, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 70, securityuse: 0, timestamp: 6356901, transseqnumber: 0, len: 9, data: <Buffer 11 8c 0a fa 00 0a 00 00 00> }
2019-02-16T12:06:34.770Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:34.777Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:34.778Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:34.779Z serialport:poller Polling for "readable"
2019-02-16T12:06:34.780Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:37.086Z serialport:poller received "readable"
2019-02-16T12:06:37.087Z serialport:bindings read
2019-02-16T12:06:37.088Z serialport:unixRead Starting read
2019-02-16T12:06:37.088Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:37.089Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:37 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 8,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 73,
     securityuse: 0,
     timestamp: 6364230,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 11 8d 02 01 2b 0a 00 04 00> },
  fcs: 239,
  csum: 239 }
2019-02-16T12:06:37.100Z serialport:main _read reading
2019-02-16T12:06:37.100Z serialport:bindings read
2019-02-16T12:06:37.101Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:37 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 8, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 73, securityuse: 0, timestamp: 6364230, transseqnumber: 0, len: 9, data: <Buffer 11 8d 02 01 2b 0a 00 04 00> }
2019-02-16T12:06:37.103Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:37.111Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:37.113Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:37.113Z serialport:poller Polling for "readable"
2019-02-16T12:06:37.114Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
  zigbee2mqtt:debug 2019-2-16 13:06:37 Received zigbee message of type 'cmdStep' with data '{"cid":"genLevelCtrl","data":{"stepmode":1,"stepsize":43,"transtime":10}}' of device 'ZBT-Remote-ALL-RGBW' (0x00158d0002ffc91d)
  zigbee2mqtt:info 2019-2-16 13:06:37 MQTT publish: topic 'zigbee2mqtt/0x00158d0002ffc91d', payload '{"action":"brightness_down_click","linkquality":73}'
2019-02-16T12:06:39.122Z serialport:poller received "readable"
2019-02-16T12:06:39.122Z serialport:bindings read
2019-02-16T12:06:39.122Z serialport:unixRead Starting read
2019-02-16T12:06:39.123Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:39.123Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:39 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 8,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 70,
     securityuse: 0,
     timestamp: 6370591,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 11 8e 02 00 2b 0a 00 00 08> },
  fcs: 158,
  csum: 158 }
2019-02-16T12:06:39.129Z serialport:main _read reading
2019-02-16T12:06:39.129Z serialport:bindings read
2019-02-16T12:06:39.129Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:39 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 8, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 70, securityuse: 0, timestamp: 6370591, transseqnumber: 0, len: 9, data: <Buffer 11 8e 02 00 2b 0a 00 00 08> }
2019-02-16T12:06:39.130Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:39.135Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:39.135Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:39.136Z serialport:poller Polling for "readable"
2019-02-16T12:06:39.136Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
  zigbee2mqtt:debug 2019-2-16 13:06:39 Received zigbee message of type 'cmdStep' with data '{"cid":"genLevelCtrl","data":{"stepmode":0,"stepsize":43,"transtime":10}}' of device 'ZBT-Remote-ALL-RGBW' (0x00158d0002ffc91d)
  zigbee2mqtt:info 2019-2-16 13:06:39 MQTT publish: topic 'zigbee2mqtt/0x00158d0002ffc91d', payload '{"action":"brightness_up_click","linkquality":70}'
2019-02-16T12:06:41.135Z serialport:poller received "readable"
2019-02-16T12:06:41.135Z serialport:bindings read
2019-02-16T12:06:41.136Z serialport:unixRead Starting read
2019-02-16T12:06:41.136Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:41.137Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:41 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 15,
     securityuse: 0,
     timestamp: 6376886,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 8f 02 05 40 20 03> },
  fcs: 88,
  csum: 88 }
2019-02-16T12:06:41.143Z serialport:main _read reading
2019-02-16T12:06:41.144Z serialport:bindings read
2019-02-16T12:06:41.144Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:41 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 15, securityuse: 0, timestamp: 6376886, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 8f 02 05 40 20 03> }
2019-02-16T12:06:41.146Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:41.157Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:41.158Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:41.158Z serialport:poller Polling for "readable"
2019-02-16T12:06:41.159Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:42.885Z serialport:poller received "readable"
2019-02-16T12:06:42.886Z serialport:bindings read
2019-02-16T12:06:42.886Z serialport:unixRead Starting read
2019-02-16T12:06:42.887Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:42.888Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:42 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 68,
     securityuse: 0,
     timestamp: 6382349,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 90 02 05 40 20 01> },
  fcs: 143,
  csum: 143 }
2019-02-16T12:06:42.898Z serialport:main _read reading
2019-02-16T12:06:42.899Z serialport:bindings read
2019-02-16T12:06:42.899Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:42 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 68, securityuse: 0, timestamp: 6382349, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 90 02 05 40 20 01> }
2019-02-16T12:06:42.902Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:42.910Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:42.912Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:42.912Z serialport:poller Polling for "readable"
2019-02-16T12:06:42.913Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:44.732Z serialport:poller received "readable"
2019-02-16T12:06:44.733Z serialport:bindings read
2019-02-16T12:06:44.734Z serialport:unixRead Starting read
2019-02-16T12:06:44.735Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:44.735Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:44 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 70,
     securityuse: 0,
     timestamp: 6388125,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 91 02 05 40 20 02> },
  fcs: 5,
  csum: 5 }
2019-02-16T12:06:44.748Z serialport:main _read reading
2019-02-16T12:06:44.748Z serialport:bindings read
2019-02-16T12:06:44.748Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:44 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 70, securityuse: 0, timestamp: 6388125, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 91 02 05 40 20 02> }
2019-02-16T12:06:44.751Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:44.758Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:44.760Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:44.760Z serialport:poller Polling for "readable"
2019-02-16T12:06:44.761Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:46.559Z serialport:poller received "readable"
2019-02-16T12:06:46.559Z serialport:bindings read
2019-02-16T12:06:46.559Z serialport:unixRead Starting read
2019-02-16T12:06:46.560Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:46.560Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:46 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 68,
     securityuse: 0,
     timestamp: 6393834,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 92 02 05 40 20 06> },
  fcs: 129,
  csum: 129 }
2019-02-16T12:06:46.566Z serialport:main _read reading
2019-02-16T12:06:46.566Z serialport:bindings read
2019-02-16T12:06:46.566Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:46 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 68, securityuse: 0, timestamp: 6393834, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 92 02 05 40 20 06> }
2019-02-16T12:06:46.567Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:46.571Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:46.572Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:46.573Z serialport:poller Polling for "readable"
2019-02-16T12:06:46.573Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:48.456Z serialport:poller received "readable"
2019-02-16T12:06:48.457Z serialport:bindings read
2019-02-16T12:06:48.458Z serialport:unixRead Starting read
2019-02-16T12:06:48.459Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:48.460Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:48 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 68,
     securityuse: 0,
     timestamp: 6399763,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 93 02 05 40 20 04> },
  fcs: 83,
  csum: 83 }
2019-02-16T12:06:48.471Z serialport:main _read reading
2019-02-16T12:06:48.472Z serialport:bindings read
2019-02-16T12:06:48.473Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:48 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 68, securityuse: 0, timestamp: 6399763, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 93 02 05 40 20 04> }
2019-02-16T12:06:48.475Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:48.482Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:48.484Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:48.485Z serialport:poller Polling for "readable"
2019-02-16T12:06:48.486Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
2019-02-16T12:06:50.392Z serialport:poller received "readable"
2019-02-16T12:06:50.393Z serialport:bindings read
2019-02-16T12:06:50.394Z serialport:unixRead Starting read
2019-02-16T12:06:50.411Z serialport:unixRead Finished read 34 bytes
2019-02-16T12:06:50.412Z serialport:main binding.read finished
Sat, 16 Feb 2019 12:06:50 GMT cc-znp { sof: 254,
  len: 29,
  type: 'AREQ',
  subsys: 'AF',
  cmd: 'incomingMsg',
  payload:
   { groupid: 16388,
     clusterid: 0,
     srcaddr: 25648,
     srcendpoint: 1,
     dstendpoint: 1,
     wasbroadcast: 0,
     linkquality: 68,
     securityuse: 0,
     timestamp: 6405808,
     transseqnumber: 0,
     len: 9,
     data: <Buffer 14 1b 12 94 02 05 40 20 05> },
  fcs: 239,
  csum: 239 }
2019-02-16T12:06:50.419Z serialport:main _read reading
2019-02-16T12:06:50.420Z serialport:bindings read
2019-02-16T12:06:50.420Z serialport:unixRead Starting read
Sat, 16 Feb 2019 12:06:50 GMT cc-znp:AREQ <-- AF:incomingMsg, { groupid: 16388, clusterid: 0, srcaddr: 25648, srcendpoint: 1, dstendpoint: 1, wasbroadcast: 0, linkquality: 68, securityuse: 0, timestamp: 6405808, transseqnumber: 0, len: 9, data: <Buffer 14 1b 12 94 02 05 40 20 05> }
2019-02-16T12:06:50.422Z zigbee-shepherd:af dispatchIncomingMsg(): type: incomingMsg, msg: [object Object]
2019-02-16T12:06:50.427Z zigbee-shepherd:msgHdlr IND <-- AF:incomingMsg, transId: 0
2019-02-16T12:06:50.428Z serialport:unixRead waiting for readable because of code: EAGAIN
2019-02-16T12:06:50.428Z serialport:poller Polling for "readable"
2019-02-16T12:06:50.428Z zigbee-shepherd:af dispatchIncomingMsg(): type: zclIncomingMsg, msg: [object Object]
  zigbee2mqtt:debug 2019-2-16 13:06:52 Saving state to file /opt/zigbee2mqtt3/data/state.json
  zigbee2mqtt:info 2019-2-16 13:06:52 MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'offline'
  zigbee2mqtt:info 2019-2-16 13:06:52 Disconnecting from MQTT server
2019-02-16T12:06:52.586Z zigbee-shepherd zigbee-shepherd is stopping.
2019-02-16T12:06:52.592Z serialport:main #flush
2019-02-16T12:06:52.594Z serialport:bindings flush
2019-02-16T12:06:52.595Z zigbee-shepherd:request REQ --> ZDO:mgmtPermitJoinReq
Sat, 16 Feb 2019 12:06:52 GMT cc-znp:SREQ --> ZDO:mgmtPermitJoinReq, { addrmode: 15, dstaddr: 65532, duration: 0, tcsignificance: 0 }
2019-02-16T12:06:52.600Z serialport:main _write 10 bytes of data
2019-02-16T12:06:52.600Z serialport:bindings write 10 bytes
2019-02-16T12:06:52.602Z serialport:unixWrite Starting write 10 bytes offset 0 bytesToWrite 10
2019-02-16T12:06:52.609Z serialport:main binding.flush finished
2019-02-16T12:06:52.611Z serialport:main #close
2019-02-16T12:06:52.612Z serialport:bindings close
2019-02-16T12:06:52.614Z serialport:poller Stopping poller
2019-02-16T12:06:52.615Z serialport:poller Destroying poller
2019-02-16T12:06:52.617Z serialport:main binding.read error { Error: Canceled
    at Poller.emitCanceled (/opt/zigbee2mqtt3/node_modules/serialport/lib/bindings/poller.js:102:17)
    at Poller.stop (/opt/zigbee2mqtt3/node_modules/serialport/lib/bindings/poller.js:92:10)
    at close.then (/opt/zigbee2mqtt3/node_modules/serialport/lib/bindings/linux.js:49:21) canceled: true }
2019-02-16T12:06:52.619Z serialport:main _read queueing _read for after open
2019-02-16T12:06:52.620Z serialport:unixWrite write returned null 10
2019-02-16T12:06:52.620Z serialport:unixWrite wrote 10 bytes
2019-02-16T12:06:52.621Z serialport:unixWrite Finished writing 10 bytes
2019-02-16T12:06:52.621Z serialport:main binding.write write finished
2019-02-16T12:06:52.623Z serialport:main binding.close finished
Sat, 16 Feb 2019 12:06:52 GMT cc-znp The serialport /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0018E1A241-if00 is closed.
2019-02-16T12:06:52.629Z zigbee-shepherd zigbee-shepherd is stopped.
  zigbee2mqtt:info 2019-2-16 13:06:52 zigbee-shepherd stopped