npm start

> zigbee2mqtt@1.4.0 start /opt/zigbee2mqtt
> node index.js

  zigbee2mqtt:info 5/14/2019, 1:57:20 PM Logging to directory: '/opt/zigbee2mqtt/data/log/2019-05-14.13-57-20'
  zigbee2mqtt:info 5/14/2019, 1:57:20 PM Starting zigbee2mqtt version 1.4.0 (commit #6b75465)
  zigbee2mqtt:info 5/14/2019, 1:57:20 PM Starting zigbee-shepherd
^C  zigbee2mqtt:error 5/14/2019, 1:57:33 PM Not connected to MQTT server!
  zigbee2mqtt:error 5/14/2019, 1:57:33 PM Cannot send message: topic: 'zigbee2mqtt/bridge/state', payload: 'offline
  zigbee2mqtt:info 5/14/2019, 1:57:33 PM zigbee-shepherd stopped

root@vizio-CN15:/opt/zigbee2mqtt# DEBUG=* npm start

> zigbee2mqtt@1.4.0 start /opt/zigbee2mqtt
> node index.js

  zigbee2mqtt:info 5/14/2019, 1:58:14 PM Logging to directory: '/opt/zigbee2mqtt/data/log/2019-05-14.13-58-14'
  serialport/bindings loading LinuxBinding +0ms
  zigbee2mqtt:debug 5/14/2019, 1:58:14 PM Using zigbee-shepherd with settings: '{"net":{"panId":6754,"extPanId":[221,221,221,221,221,221,221,221],"channelList":[11],"precfgkey":"HIDDEN"},"dbPath":"/opt/zigbee2mqtt/data/database.db","coordBackupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","sp":{"baudRate":115200,"rtscts":true}}'
  zigbee2mqtt:debug 5/14/2019, 1:58:14 PM Loaded state from file /opt/zigbee2mqtt/data/state.json
  zigbee2mqtt:debug 5/14/2019, 1:58:14 PM Saving state to file /opt/zigbee2mqtt/data/state.json
  zigbee2mqtt:info 5/14/2019, 1:58:14 PM Starting zigbee2mqtt version 1.4.0 (commit #6b75465)
  zigbee2mqtt:info 5/14/2019, 1:58:14 PM Starting zigbee-shepherd
  zigbee-shepherd:init zigbee-shepherd booting... +0ms
  cc-znp Initialize, opening serial port +0ms
  serialport/stream opening path: /dev/ttyACM0 +0ms
  serialport/binding-abstract open +0ms
  serialport/stream _read queueing _read for after open +1ms
  serialport/bindings/poller Creating poller +0ms
  serialport/stream opened path: /dev/ttyACM0 +2ms
  serialport/stream _write 1 bytes of data +0ms
  serialport/binding-abstract write 1 bytes +3ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixWrite Starting write 1 bytes offset 0 bytesToWrite 1 +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixWrite write returned null 1 +1ms
  serialport/bindings/unixWrite wrote 1 bytes +0ms
  serialport/bindings/unixWrite Finished writing 1 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  cc-znp The serialport /dev/ttyACM0 is opened. +1s
  zigbee-shepherd:request REQ --> SYS:version +0ms
  cc-znp:SREQ --> SYS:version, {} +0ms
  serialport/stream _write 5 bytes of data +1s
  serialport/binding-abstract write 5 bytes +1s
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +1s
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +1ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +1s
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1s
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +2ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 11 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'version',
  cc-znp   payload:
  cc-znp    { transportrev: 2,
  cc-znp      product: 1,
  cc-znp      majorrel: 2,
  cc-znp      minorrel: 7,
  cc-znp      maintrel: 1,
  cc-znp      revision: 20190426 },
  cc-znp   fcs: 145,
  cc-znp   csum: 145 } +18ms
  serialport/stream _read reading +4ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +4ms
  cc-znp:SRSP <-- SYS:version, { transportrev: 2, product: 1, majorrel: 2, minorrel: 7, maintrel: 1, revision: 20190426 } +0ms
  zigbee-shepherd:request RSP <-- SYS:version +20ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +0ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 96, offset: 0, len: 1, value: [ 85 ] } +17ms
  serialport/stream _write 8 bytes of data +2ms
  serialport/binding-abstract write 8 bytes +2ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +3ms
  serialport/bindings/poller Polling for "readable" +11ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +4ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +4ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +5ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 1, value: <Buffer 55> },
  cc-znp   fcs: 62,
  cc-znp   csum: 62 } +11ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 1, value: <Buffer 55> } +9ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 132, len: 4, offset: 0, value: [ 0, 8, 0, 0 ] } +20ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +13ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +19ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +14ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +15ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 3 bytes +1ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 6,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 4, value: <Buffer 00 08 00 00> },
  cc-znp   fcs: 99,
  cc-znp   csum: 99 } +18ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 4, value: <Buffer 00 08 00 00> } +19ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +7ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +10ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 99, len: 1, offset: 0, value: [ 0 ] } +17ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +18ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +17ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +17ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +5ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 1, value: <Buffer 00> },
  cc-znp   fcs: 107,
  cc-znp   csum: 107 } +20ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 1, value: <Buffer 00> } +20ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +10ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 98, len: 16, offset: 0, value: [ 1, 3, 5, 7, 9, 11, 13, 15, 0, 2, 4, 6, 8, 10, 12, 13 ] } +22ms
  serialport/stream _write 8 bytes of data +14ms
  serialport/binding-abstract write 8 bytes +13ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +21ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +1ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +17ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +18ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +5ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 15 bytes +1ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 16,
  cc-znp      value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  cc-znp   fcs: 104,
  cc-znp   csum: 104 } +22ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 16, value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> } +22ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +5ms
  zigbee-shepherd:request REQ --> UTIL:getDeviceInfo +11ms
  cc-znp:SREQ --> UTIL:getDeviceInfo, {} +22ms
  serialport/stream _write 5 bytes of data +13ms
  serialport/binding-abstract write 5 bytes +13ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +21ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +1ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +17ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +17ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 11 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'UTIL',
  cc-znp   cmd: 'getDeviceInfo',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      ieeeaddr: '0x00124b0014f42265',
  cc-znp      shortaddr: 0,
  cc-znp      devicetype: 7,
  cc-znp      devicestate: 8,
  cc-znp      numassocdevices: 0,
  cc-znp      assocdeviceslist: <Buffer > },
  cc-znp   fcs: 152,
  cc-znp   csum: 152 } +24ms
  serialport/stream _read reading +5ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +5ms
  cc-znp:SRSP <-- UTIL:getDeviceInfo, { status: 0, ieeeaddr: '0x00124b0014f42265', shortaddr: 0, devicetype: 7, devicestate: 8, numassocdevices: 0, assocdeviceslist: <Buffer > } +24ms
  zigbee-shepherd:request RSP <-- UTIL:getDeviceInfo, status: 0 +12ms
  zigbee-shepherd:init Start the ZNP as a coordinator... +0ms
  zigbee-shepherd:request REQ --> ZDO:startupFromApp +1ms
  cc-znp:SREQ --> ZDO:startupFromApp, { startdelay: 100 } +13ms
  serialport/stream _write 7 bytes of data +1ms
  serialport/binding-abstract write 7 bytes +1ms
  serialport/bindings/unixWrite Starting write 7 bytes offset 0 bytesToWrite 7 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +8ms
  serialport/bindings/unixWrite write returned null 7 +0ms
  serialport/bindings/unixWrite wrote 7 bytes +0ms
  serialport/bindings/unixWrite Finished writing 7 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +2ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +2ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'startupFromApp',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 36,
  cc-znp   csum: 36 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:startupFromApp, { status: 0 } +5ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +2ms
^C  zigbee2mqtt:debug 5/14/2019, 2:02:52 PM Saving state to file /opt/zigbee2mqtt/data/state.json
  zigbee2mqtt:error 5/14/2019, 2:02:52 PM Not connected to MQTT server!
  zigbee2mqtt:error 5/14/2019, 2:02:52 PM Cannot send message: topic: 'zigbee2mqtt/bridge/state', payload: 'offline
  zigbee-shepherd zigbee-shepherd is stopping. +0ms
  zigbee-shepherd zigbee-shepherd is stopped. +0ms
  zigbee2mqtt:info 5/14/2019, 2:02:52 PM zigbee-shepherd stopped

root@vizio-CN15:/opt/zigbee2mqtt# ^C
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# 
root@vizio-CN15:/opt/zigbee2mqtt# DEBUG=* npm start

> zigbee2mqtt@1.4.0 start /opt/zigbee2mqtt
> node index.js

  zigbee2mqtt:info 5/14/2019, 2:09:23 PM Logging to directory: '/opt/zigbee2mqtt/data/log/2019-05-14.14-09-23'
  serialport/bindings loading LinuxBinding +0ms
  zigbee2mqtt:debug 5/14/2019, 2:09:24 PM Using zigbee-shepherd with settings: '{"net":{"panId":6754,"extPanId":[221,221,221,221,221,221,221,221],"channelList":[11],"precfgkey":"HIDDEN"},"dbPath":"/opt/zigbee2mqtt/data/database.db","coordBackupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","sp":{"baudRate":115200,"rtscts":true}}'
  zigbee2mqtt:debug 5/14/2019, 2:09:24 PM Loaded state from file /opt/zigbee2mqtt/data/state.json
  zigbee2mqtt:debug 5/14/2019, 2:09:24 PM Saving state to file /opt/zigbee2mqtt/data/state.json
  zigbee2mqtt:info 5/14/2019, 2:09:24 PM Starting zigbee2mqtt version 1.4.0 (commit #6b75465)
  zigbee2mqtt:info 5/14/2019, 2:09:24 PM Starting zigbee-shepherd
  zigbee-shepherd:init zigbee-shepherd booting... +0ms
  cc-znp Initialize, opening serial port +0ms
  serialport/stream opening path: /dev/ttyACM0 +0ms
  serialport/binding-abstract open +0ms
  serialport/stream _read queueing _read for after open +1ms
  serialport/bindings/poller Creating poller +0ms
  serialport/stream opened path: /dev/ttyACM0 +2ms
  serialport/stream _write 1 bytes of data +0ms
  serialport/binding-abstract write 1 bytes +3ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixWrite Starting write 1 bytes offset 0 bytesToWrite 1 +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixWrite write returned null 1 +1ms
  serialport/bindings/unixWrite wrote 1 bytes +0ms
  serialport/bindings/unixWrite Finished writing 1 bytes +0ms
  serialport/stream binding.write write finished +2ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  cc-znp The serialport /dev/ttyACM0 is opened. +1s
  zigbee-shepherd:request REQ --> SYS:version +0ms
  cc-znp:SREQ --> SYS:version, {} +0ms
  serialport/stream _write 5 bytes of data +1s
  serialport/binding-abstract write 5 bytes +1s
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +1s
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +1s
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +1s
  serialport/bindings/unixRead Finished read 16 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +5ms
  serialport/binding-abstract read +6ms
  serialport/bindings/unixRead Starting read +7ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'version',
  cc-znp   payload:
  cc-znp    { transportrev: 2,
  cc-znp      product: 1,
  cc-znp      majorrel: 2,
  cc-znp      minorrel: 7,
  cc-znp      maintrel: 1,
  cc-znp      revision: 20190426 },
  cc-znp   fcs: 145,
  cc-znp   csum: 145 } +24ms
  serialport/stream _read reading +7ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +7ms
  cc-znp:SRSP <-- SYS:version, { transportrev: 2, product: 1, majorrel: 2, minorrel: 7, maintrel: 1, revision: 20190426 } +0ms
  zigbee-shepherd:request RSP <-- SYS:version +28ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +1ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 96, offset: 0, len: 1, value: [ 85 ] } +27ms
  serialport/stream _write 8 bytes of data +4ms
  serialport/binding-abstract write 8 bytes +4ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +21ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +4ms
  serialport/bindings/poller Polling for "readable" +19ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +1ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +8ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +8ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +7ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 1, value: <Buffer 55> },
  cc-znp   fcs: 62,
  cc-znp   csum: 62 } +17ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 1, value: <Buffer 55> } +13ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +2ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +10ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 132, len: 4, offset: 0, value: [ 0, 8, 0, 0 ] } +21ms
  serialport/stream _write 8 bytes of data +11ms
  serialport/binding-abstract write 8 bytes +11ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +20ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +15ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +15ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 6,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 4, value: <Buffer 00 08 00 00> },
  cc-znp   fcs: 99,
  cc-znp   csum: 99 } +18ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 4, value: <Buffer 00 08 00 00> } +18ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 99, len: 1, offset: 0, value: [ 0 ] } +19ms
  serialport/stream _write 8 bytes of data +13ms
  serialport/binding-abstract write 8 bytes +13ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +19ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +1ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +19ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +20ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +7ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 1, value: <Buffer 00> },
  cc-znp   fcs: 107,
  cc-znp   csum: 107 } +22ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 1, value: <Buffer 00> } +23ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 98, len: 16, offset: 0, value: [ 1, 3, 5, 7, 9, 11, 13, 15, 0, 2, 4, 6, 8, 10, 12, 13 ] } +23ms
  serialport/stream _write 8 bytes of data +13ms
  serialport/binding-abstract write 8 bytes +13ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +22ms
  serialport/bindings/unixWrite write returned null 8 +1ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +20ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +20ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +8ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 15 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 16,
  cc-znp      value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  cc-znp   fcs: 104,
  cc-znp   csum: 104 } +25ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 16, value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> } +24ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +14ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +5ms
  zigbee-shepherd:request REQ --> UTIL:getDeviceInfo +10ms
  cc-znp:SREQ --> UTIL:getDeviceInfo, {} +25ms
  serialport/stream _write 5 bytes of data +12ms
  serialport/binding-abstract write 5 bytes +12ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +23ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +1ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +16ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +16ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 11 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'UTIL',
  cc-znp   cmd: 'getDeviceInfo',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      ieeeaddr: '0x00124b0014f42265',
  cc-znp      shortaddr: 65534,
  cc-znp      devicetype: 7,
  cc-znp      devicestate: 0,
  cc-znp      numassocdevices: 0,
  cc-znp      assocdeviceslist: <Buffer > },
  cc-znp   fcs: 145,
  cc-znp   csum: 145 } +23ms
  serialport/stream _read reading +5ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +6ms
  cc-znp:SRSP <-- UTIL:getDeviceInfo, { status: 0, ieeeaddr: '0x00124b0014f42265', shortaddr: 65534, devicetype: 7, devicestate: 0, numassocdevices: 0, assocdeviceslist: <Buffer > } +24ms
  zigbee-shepherd:request RSP <-- UTIL:getDeviceInfo, status: 0 +13ms
  zigbee-shepherd:init Start the ZNP as a coordinator... +0ms
  zigbee-shepherd:request REQ --> ZDO:startupFromApp +1ms
  cc-znp:SREQ --> ZDO:startupFromApp, { startdelay: 100 } +13ms
  serialport/stream _write 7 bytes of data +3ms
  serialport/binding-abstract write 7 bytes +3ms
  serialport/bindings/unixWrite Starting write 7 bytes offset 0 bytesToWrite 7 +13ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +3ms
  serialport/bindings/poller Polling for "readable" +10ms
  serialport/bindings/unixWrite write returned null 7 +1ms
  serialport/bindings/unixWrite wrote 7 bytes +0ms
  serialport/bindings/unixWrite Finished writing 7 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +749ms
  serialport/binding-abstract read +751ms
  serialport/bindings/unixRead Starting read +750ms
  serialport/bindings/unixRead Finished read 16 bytes +0ms
  serialport/stream binding.read finished +751ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'startupFromApp',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 36,
  cc-znp   csum: 36 } +757ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'stateChangeInd',
  cc-znp   payload: { state: 9 },
  cc-znp   fcs: 141,
  cc-znp   csum: 141 } +2ms
  serialport/stream _read reading +4ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +5ms
  cc-znp:SRSP <-- ZDO:startupFromApp, { status: 0 } +758ms
  cc-znp:AREQ <-- ZDO:stateChangeInd, { state: 9 } +0ms
  zigbee-shepherd:request REQ --> SYS:osalNvWrite +758ms
  cc-znp:SREQ --> SYS:osalNvWrite, { id: 132, len: 4, offset: 0, value: [ 0, 8, 0, 0 ] } +758ms
  serialport/stream _write 13 bytes of data +2ms
  serialport/binding-abstract write 13 bytes +2ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:stateChangeInd +0ms
  zigbee-shepherd:init Now the ZNP is a coordinator. +761ms
  zigbee-shepherd:request REQ --> SYS:version +2ms
  serialport/bindings/unixWrite Starting write 13 bytes offset 0 bytesToWrite 13 +759ms
  serialport/bindings/unixRead Finished read 4 bytes +4ms
  serialport/stream binding.read finished +2ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'APP_CNF',
  cc-znp   cmd: 'bdbComissioningNotifcation',
  cc-znp   payload: { status: 13 },
  cc-znp   fcs: 197,
  cc-znp   csum: 197 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +2ms
  serialport/bindings/unixWrite write returned null 13 +2ms
  serialport/bindings/unixWrite wrote 13 bytes +0ms
  serialport/bindings/unixWrite Finished writing 13 bytes +0ms
  serialport/stream binding.write write finished +1ms
  cc-znp:AREQ <-- APP_CNF:bdbComissioningNotifcation, { status: 13 } +6ms
  zigbee-shepherd:msgHdlr IND <-- APP_CNF:bdbComissioningNotifcation +3ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +12ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 6 bytes +1ms
  serialport/stream binding.read finished +4ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvWrite',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 105,
  cc-znp   csum: 105 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvWrite, { status: 0 } +11ms
  zigbee-shepherd:request RSP <-- SYS:osalNvWrite, status: 0 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +2ms
  cc-znp:SREQ --> SYS:version, {} +11ms
  serialport/stream _write 5 bytes of data +1ms
  serialport/binding-abstract write 5 bytes +1ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +6ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +2ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'version',
  cc-znp   payload:
  cc-znp    { transportrev: 2,
  cc-znp      product: 1,
  cc-znp      majorrel: 2,
  cc-znp      minorrel: 7,
  cc-znp      maintrel: 1,
  cc-znp      revision: 20190426 },
  cc-znp   fcs: 145,
  cc-znp   csum: 145 } +5ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:version, { transportrev: 2, product: 1, majorrel: 2, minorrel: 7, maintrel: 1, revision: 20190426 } +5ms
  zigbee-shepherd:request RSP <-- SYS:version +5ms
  zigbee-shepherd:request REQ --> UTIL:getDeviceInfo +1ms
  cc-znp:SREQ --> UTIL:getDeviceInfo, {} +5ms
  serialport/stream _write 5 bytes of data +1ms
  serialport/binding-abstract write 5 bytes +1ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +5ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +2ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +1ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +5ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +5ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'UTIL',
  cc-znp   cmd: 'getDeviceInfo',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      ieeeaddr: '0x00124b0014f42265',
  cc-znp      shortaddr: 0,
  cc-znp      devicetype: 7,
  cc-znp      devicestate: 9,
  cc-znp      numassocdevices: 0,
  cc-znp      assocdeviceslist: <Buffer > },
  cc-znp   fcs: 153,
  cc-znp   csum: 153 } +8ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- UTIL:getDeviceInfo, { status: 0, ieeeaddr: '0x00124b0014f42265', shortaddr: 0, devicetype: 7, devicestate: 9, numassocdevices: 0, assocdeviceslist: <Buffer > } +9ms
  zigbee-shepherd:request RSP <-- UTIL:getDeviceInfo, status: 0 +8ms
  zigbee-shepherd:request REQ --> UTIL:getDeviceInfo +0ms
  cc-znp:SREQ --> UTIL:getDeviceInfo, {} +8ms
  serialport/stream _write 5 bytes of data +1ms
  serialport/binding-abstract write 5 bytes +1ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +4ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +4ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +5ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'UTIL',
  cc-znp   cmd: 'getDeviceInfo',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      ieeeaddr: '0x00124b0014f42265',
  cc-znp      shortaddr: 0,
  cc-znp      devicetype: 7,
  cc-znp      devicestate: 9,
  cc-znp      numassocdevices: 0,
  cc-znp      assocdeviceslist: <Buffer > },
  cc-znp   fcs: 153,
  cc-znp   csum: 153 } +9ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- UTIL:getDeviceInfo, { status: 0, ieeeaddr: '0x00124b0014f42265', shortaddr: 0, devicetype: 7, devicestate: 9, numassocdevices: 0, assocdeviceslist: <Buffer > } +8ms
  zigbee-shepherd:request RSP <-- UTIL:getDeviceInfo, status: 0 +8ms
  zigbee-shepherd:request REQ --> UTIL:getDeviceInfo +1ms
  cc-znp:SREQ --> UTIL:getDeviceInfo, {} +9ms
  serialport/stream _write 5 bytes of data +1ms
  serialport/binding-abstract write 5 bytes +1ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  serialport/bindings/unixWrite write returned null 5 +0ms
  serialport/bindings/unixWrite wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +1ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +5ms
  serialport/binding-abstract read +5ms
  serialport/bindings/unixRead Starting read +5ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +4ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'UTIL',
  cc-znp   cmd: 'getDeviceInfo',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      ieeeaddr: '0x00124b0014f42265',
  cc-znp      shortaddr: 0,
  cc-znp      devicetype: 7,
  cc-znp      devicestate: 9,
  cc-znp      numassocdevices: 0,
  cc-znp      assocdeviceslist: <Buffer > },
  cc-znp   fcs: 153,
  cc-znp   csum: 153 } +8ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- UTIL:getDeviceInfo, { status: 0, ieeeaddr: '0x00124b0014f42265', shortaddr: 0, devicetype: 7, devicestate: 9, numassocdevices: 0, assocdeviceslist: <Buffer > } +8ms
  zigbee-shepherd:request RSP <-- UTIL:getDeviceInfo, status: 0 +8ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +0ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 132, len: 4, offset: 0, value: [ 0, 8, 0, 0 ] } +8ms
  serialport/stream _write 8 bytes of data +1ms
  serialport/binding-abstract write 8 bytes +1ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +7ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  serialport/bindings/unixWrite write returned null 8 +1ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +2ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +2ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 6,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 4, value: <Buffer 00 08 00 00> },
  cc-znp   fcs: 99,
  cc-znp   csum: 99 } +5ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 4, value: <Buffer 00 08 00 00> } +6ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +5ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +0ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 131, len: 2, offset: 0, value: [ 98, 26 ] } +5ms
  serialport/stream _write 8 bytes of data +1ms
  serialport/binding-abstract write 8 bytes +1ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +5ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +7ms
  serialport/binding-abstract read +7ms
  serialport/bindings/unixRead Starting read +7ms
  serialport/bindings/unixRead Finished read 9 bytes +0ms
  serialport/stream binding.read finished +6ms
  cc-znp { sof: 254,
  cc-znp   len: 4,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 2, value: <Buffer ff ff> },
  cc-znp   fcs: 111,
  cc-znp   csum: 111 } +10ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 2, value: <Buffer ff ff> } +10ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +10ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +0ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 45, len: 8, offset: 0, value: [ 221, 221, 221, 221, 221, 221, 221, 221 ] } +10ms
  serialport/stream _write 8 bytes of data +2ms
  serialport/binding-abstract write 8 bytes +2ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +10ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +3ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +7ms
  serialport/binding-abstract read +7ms
  serialport/bindings/unixRead Starting read +7ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +7ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 10,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> },
  cc-znp   fcs: 149,
  cc-znp   csum: 149 } +11ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> } +10ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +11ms
  zigbee-shepherd:request REQ --> ZDO:nodeDescReq +1ms
  cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 } +14ms
  serialport/stream _write 9 bytes of data +4ms
  serialport/binding-abstract write 9 bytes +4ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +14ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +5ms
  serialport/bindings/poller Polling for "readable" +6ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 102,
  cc-znp   csum: 102 } +9ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 } +10ms
  serialport/bindings/unixRead Finished read 16 bytes +0ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 5 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      logicaltype_cmplxdescavai_userdescavai: 0,
  cc-znp      apsflags_freqband: 64,
  cc-znp      maccapflags: 143,
  cc-znp      manufacturercode: 0,
  cc-znp      maxbuffersize: 80,
  cc-znp      maxintransfersize: 160,
  cc-znp      servermask: 11265,
  cc-znp      maxouttransfersize: 160,
  cc-znp      descriptorcap: 0 },
  cc-znp   fcs: 103,
  cc-znp   csum: 103 } +3ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +2ms
  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: 11265, maxouttransfersize: 160, descriptorcap: 0 } +74ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp +74ms
  zigbee-shepherd:request REQ --> ZDO:activeEpReq +12ms
  cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 } +10ms
  serialport/stream _write 9 bytes of data +3ms
  serialport/binding-abstract write 9 bytes +3ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +10ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +7ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 97,
  cc-znp   csum: 97 } +7ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 } +10ms
  serialport/bindings/unixRead Finished read 9 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 6,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      activeepcount: 0,
  cc-znp      activeeplist: <Buffer > },
  cc-znp   fcs: 198,
  cc-znp   csum: 198 } +2ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 0, activeeplist: <Buffer > } +8ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp +9ms
  zigbee-shepherd:request REQ --> AF:register +9ms
  cc-znp:SREQ --> AF:register, { endpoint: 1, appprofid: 260, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +9ms
  serialport/stream _write 14 bytes of data +3ms
  serialport/binding-abstract write 14 bytes +3ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +3ms
  serialport/bindings/poller Polling for "readable" +6ms
  serialport/bindings/unixWrite write returned null 14 +0ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +8ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +5ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  zigbee-shepherd:init Register delegator, epId: 1, profId: 260  +102ms
  zigbee-shepherd:request REQ --> AF:register +10ms
  cc-znp:SREQ --> AF:register, { endpoint: 2, appprofid: 257, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +15ms
  serialport/stream _write 14 bytes of data +11ms
  serialport/binding-abstract write 14 bytes +11ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +15ms
  serialport/bindings/unixWrite write returned null 14 +0ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +14ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +14ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +4ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +16ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +16ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +6ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  zigbee-shepherd:init Register delegator, epId: 2, profId: 257  +17ms
  zigbee-shepherd:request REQ --> AF:register +11ms
  cc-znp:SREQ --> AF:register, { endpoint: 3, appprofid: 261, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +18ms
  serialport/stream _write 14 bytes of data +13ms
  serialport/binding-abstract write 14 bytes +13ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +18ms
  serialport/bindings/unixWrite write returned null 14 +0ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +15ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +16ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +19ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +19ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +3ms
  zigbee-shepherd:init Register delegator, epId: 3, profId: 261  +18ms
  zigbee-shepherd:request REQ --> AF:register +10ms
  cc-znp:SREQ --> AF:register, { endpoint: 4, appprofid: 263, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +17ms
  serialport/stream _write 14 bytes of data +12ms
  serialport/binding-abstract write 14 bytes +12ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +17ms
  serialport/bindings/unixWrite write returned null 14 +0ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +14ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +14ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +4ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +16ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +16ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +6ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +3ms
  zigbee-shepherd:init Register delegator, epId: 4, profId: 263  +17ms
  zigbee-shepherd:request REQ --> AF:register +11ms
  cc-znp:SREQ --> AF:register, { endpoint: 5, appprofid: 264, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +18ms
  serialport/stream _write 14 bytes of data +13ms
  serialport/binding-abstract write 14 bytes +13ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +18ms
  serialport/bindings/unixWrite write returned null 14 +1ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +16ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +16ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +20ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +3ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +20ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:init Register delegator, epId: 5, profId: 264  +20ms
  zigbee-shepherd:request REQ --> AF:register +11ms
  cc-znp:SREQ --> AF:register, { endpoint: 6, appprofid: 265, appdeviceid: 5, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 0, appoutclusterlist: [] } +20ms
  serialport/stream _write 14 bytes of data +14ms
  serialport/binding-abstract write 14 bytes +14ms
  serialport/bindings/unixWrite Starting write 14 bytes offset 0 bytesToWrite 14 +19ms
  serialport/bindings/unixWrite write returned null 14 +1ms
  serialport/bindings/unixWrite wrote 14 bytes +0ms
  serialport/bindings/unixWrite Finished writing 14 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +15ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +17ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +19ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +19ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  serialport/bindings/poller received "readable" +8ms
  serialport/binding-abstract read +9ms
  serialport/bindings/unixRead Starting read +8ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +10ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'APP_CNF',
  cc-znp   cmd: 'bdbComissioningNotifcation',
  cc-znp   payload: { status: 8 },
  cc-znp   fcs: 198,
  cc-znp   csum: 198 } +11ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:AREQ <-- APP_CNF:bdbComissioningNotifcation, { status: 8 } +108ms
  zigbee-shepherd:msgHdlr IND <-- APP_CNF:bdbComissioningNotifcation +107ms
  zigbee-shepherd:init Register delegator, epId: 6, profId: 265  +19ms
  zigbee-shepherd:request REQ --> ZDO:nodeDescReq +11ms
  cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 } +19ms
  serialport/stream _write 9 bytes of data +2ms
  serialport/binding-abstract write 9 bytes +2ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +18ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +4ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +4ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 102,
  cc-znp   csum: 102 } +7ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 } +18ms
  serialport/bindings/unixRead Finished read 16 bytes +0ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 5 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      logicaltype_cmplxdescavai_userdescavai: 0,
  cc-znp      apsflags_freqband: 64,
  cc-znp      maccapflags: 143,
  cc-znp      manufacturercode: 0,
  cc-znp      maxbuffersize: 80,
  cc-znp      maxintransfersize: 160,
  cc-znp      servermask: 11265,
  cc-znp      maxouttransfersize: 160,
  cc-znp      descriptorcap: 0 },
  cc-znp   fcs: 103,
  cc-znp   csum: 103 } +3ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  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: 11265, maxouttransfersize: 160, descriptorcap: 0 } +10ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp +10ms
  zigbee-shepherd:request REQ --> ZDO:activeEpReq +11ms
  cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 } +11ms
  serialport/stream _write 9 bytes of data +4ms
  serialport/binding-abstract write 9 bytes +3ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +11ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +3ms
  serialport/bindings/poller Polling for "readable" +8ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 97,
  cc-znp   csum: 97 } +9ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 } +11ms
  serialport/bindings/unixRead Finished read 15 bytes +1ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 12,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      activeepcount: 6,
  cc-znp      activeeplist: <Buffer 06 05 04 03 02 01> },
  cc-znp   fcs: 205,
  cc-znp   csum: 205 } +1ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 6, activeeplist: <Buffer 06 05 04 03 02 01> } +10ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp +10ms
  zigbee-shepherd:request REQ --> ZDO:mgmtPermitJoinReq +9ms
  cc-znp:SREQ --> ZDO:mgmtPermitJoinReq, { addrmode: 2, dstaddr: 0, duration: 0, tcsignificance: 0 } +9ms
  serialport/stream _write 10 bytes of data +1ms
  serialport/binding-abstract write 10 bytes +1ms
  serialport/bindings/unixWrite Starting write 10 bytes offset 0 bytesToWrite 10 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +6ms
  serialport/bindings/unixWrite write returned null 10 +1ms
  serialport/bindings/unixWrite wrote 10 bytes +0ms
  serialport/bindings/unixWrite Finished writing 10 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +4ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'mgmtPermitJoinReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 82,
  cc-znp   csum: 82 } +7ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:mgmtPermitJoinReq, { status: 0 } +8ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'mgmtPermitJoinRsp',
  cc-znp   payload: { srcaddr: 0, status: 0 },
  cc-znp   fcs: 240,
  cc-znp   csum: 240 } +1ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:AREQ <-- ZDO:mgmtPermitJoinRsp, { srcaddr: 0, status: 0 } +7ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:mgmtPermitJoinRsp +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:init Loading devices from database done. +2s
  zigbee-shepherd zigbee-shepherd is _ready and _enabled +0ms
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM zigbee-shepherd started
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM Coordinator firmware version: '20190426'
  zigbee2mqtt:debug 5/14/2019, 2:09:26 PM zigbee-shepherd info: {"enabled":true,"net":{"state":"Coordinator","channel":"11","panId":65535,"extPanId":[0,0,18,20,34,75,101,244],"ieeeAddr":"0x00124b0014f42265","nwkAddr":0},"firmware":{"transportrev":2,"product":1,"version":"2.7.1","revision":20190426},"startTime":1557868166,"joinTimeLeft":0}
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM Currently 0 devices are joined:
  zigbee2mqtt:warn 5/14/2019, 2:09:26 PM `permit_join` set to  `true` in configuration.yaml.
  zigbee2mqtt:warn 5/14/2019, 2:09:26 PM Allowing new devices to join.
  zigbee2mqtt:warn 5/14/2019, 2:09:26 PM Set `permit_join` to `false` once you joined all devices.
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM Zigbee: allowing new devices to join.
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM Connecting to MQTT server at mqtt://localhost
  zigbee-shepherd:request REQ --> SYS:osalNvRead +51ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 1, offset: 0 } +51ms
  serialport/stream _write 8 bytes of data +45ms
  serialport/binding-abstract write 8 bytes +45ms
  zigbee-shepherd:request REQ --> ZDO:mgmtPermitJoinReq +0ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +51ms
  zigbee2mqtt:info 5/14/2019, 2:09:26 PM zigbee-shepherd ready
  zigbee-shepherd:request REQ --> AF:register +2ms
  serialport/bindings/unixWrite write returned null 8 +1ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +2ms
  serialport/bindings/poller received "readable" +51ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +51ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +6ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +0ms
  serialport/bindings/poller received "readable" +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 10,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> },
  cc-znp   fcs: 149,
  cc-znp   csum: 149 } +54ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> } +56ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  cc-znp:SREQ --> ZDO:mgmtPermitJoinReq, { addrmode: 15, dstaddr: 65532, duration: 255, tcsignificance: 0 } +10ms
  serialport/stream _write 10 bytes of data +0ms
  serialport/binding-abstract write 10 bytes +1ms
  serialport/bindings/unixWrite Starting write 10 bytes offset 0 bytesToWrite 10 +9ms
  serialport/bindings/unixWrite write returned null 10 +0ms
  serialport/bindings/unixWrite wrote 10 bytes +0ms
  serialport/bindings/unixWrite Finished writing 10 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +8ms
  serialport/binding-abstract read +7ms
  serialport/bindings/unixRead Starting read +8ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +7ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'mgmtPermitJoinReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 82,
  cc-znp   csum: 82 } +11ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- ZDO:mgmtPermitJoinReq, { status: 0 } +10ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 33, offset: 0 } +11ms
  serialport/stream _write 8 bytes of data +11ms
  serialport/binding-abstract write 8 bytes +11ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +20ms
  serialport/bindings/unixRead Finished read 6 bytes +11ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 3,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'mgmtPermitJoinRsp',
  cc-znp   payload: { srcaddr: 0, status: 0 },
  cc-znp   fcs: 240,
  cc-znp   csum: 240 } +12ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:AREQ <-- ZDO:mgmtPermitJoinRsp, { srcaddr: 0, status: 0 } +79ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:mgmtPermitJoinRsp +78ms
  serialport/bindings/unixWrite write returned null 8 +3ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +2ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +2ms
  serialport/bindings/poller Polling for "readable" +16ms
  serialport/bindings/poller received "readable" +5ms
  serialport/binding-abstract read +7ms
  serialport/bindings/unixRead Starting read +5ms
  serialport/bindings/unixRead Finished read 48 bytes +0ms
  serialport/stream binding.read finished +5ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +2ms
  serialport/stream binding.read finished +2ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 24 bytes +0ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +2ms
  serialport/stream binding.read finished +2ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 27 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 118,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 116,
  cc-znp      value:
  cc-znp       <Buffer 69 05 02 15 14 15 00 1e 00 00 00 01 05 01 8f 00 07 00 02 05 1e 00 00 00 0b 00 00 00 00 00 00 00 00 00 00 00 18 e2 08 00 00 08 00 00 0f 0f 04 00 01 00 ... > },
  cc-znp   fcs: 165,
  cc-znp   csum: 165 } +15ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 116, value: <Buffer 69 05 02 15 14 15 00 1e 00 00 00 01 05 01 8f 00 07 00 02 05 1e 00 00 00 0b 00 00 00 00 00 00 00 00 00 00 00 18 e2 08 00 00 08 00 00 0f 0f 04 00 01 00 ... > } +27ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +27ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +9ms
  cc-znp:SREQ --> AF:register, { endpoint: 11, appprofid: 260, appdeviceid: 1024, appdevver: 0, latencyreq: 0, appnuminclusters: 0, appinclusterlist: [], appnumoutclusters: 1, appoutclusterlist: [ 1280 ] } +27ms
  serialport/stream _write 16 bytes of data +1ms
  serialport/binding-abstract write 16 bytes +1ms
  serialport/bindings/unixWrite Starting write 16 bytes offset 0 bytesToWrite 16 +14ms
  serialport/bindings/unixWrite write returned null 16 +0ms
  serialport/bindings/unixWrite wrote 16 bytes +1ms
  serialport/bindings/unixWrite Finished writing 16 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +4ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +4ms
  serialport/bindings/unixRead Finished read 6 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'AF',
  cc-znp   cmd: 'register',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 101,
  cc-znp   csum: 101 } +5ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- AF:register, { status: 0 } +6ms
  zigbee-shepherd:request RSP <-- AF:register, status: 0 +5ms
  zigbee-shepherd Register zApp, epId: 11, profId: 260  +91ms
  zigbee-shepherd:request REQ --> ZDO:nodeDescReq +0ms
  cc-znp:SREQ --> ZDO:nodeDescReq, { dstaddr: 0, nwkaddrofinterest: 0 } +5ms
  serialport/stream _write 9 bytes of data +1ms
  serialport/binding-abstract write 9 bytes +1ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +5ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +2ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +3ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +3ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 102,
  cc-znp   csum: 102 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:nodeDescReq, { status: 0 } +5ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +6ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 45, offset: 0 } +6ms
  serialport/stream _write 8 bytes of data +1ms
  serialport/binding-abstract write 8 bytes +1ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +5ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/unixRead Finished read 13 bytes +1ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'nodeDescRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      logicaltype_cmplxdescavai_userdescavai: 0,
  cc-znp      apsflags_freqband: 64,
  cc-znp      maccapflags: 143,
  cc-znp      manufacturercode: 0,
  cc-znp      maxbuffersize: 80,
  cc-znp      maxintransfersize: 160,
  cc-znp      servermask: 11265,
  cc-znp      maxouttransfersize: 160,
  cc-znp      descriptorcap: 0 },
  cc-znp   fcs: 103,
  cc-znp   csum: 103 } +2ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  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: 11265, maxouttransfersize: 160, descriptorcap: 0 } +27ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:nodeDescRsp +27ms
  zigbee-shepherd:request REQ --> ZDO:activeEpReq +2ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +4ms
  serialport/bindings/poller received "readable" +5ms
  serialport/binding-abstract read +6ms
  serialport/bindings/unixRead Starting read +5ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 10,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> },
  cc-znp   fcs: 149,
  cc-znp   csum: 149 } +8ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 8, value: <Buffer 65 22 f4 14 00 4b 12 00> } +10ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +8ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +2ms
  cc-znp:SREQ --> ZDO:activeEpReq, { dstaddr: 0, nwkaddrofinterest: 0 } +10ms
  serialport/stream _write 9 bytes of data +1ms
  serialport/binding-abstract write 9 bytes +1ms
  serialport/bindings/unixWrite Starting write 9 bytes offset 0 bytesToWrite 9 +10ms
  serialport/bindings/unixWrite write returned null 9 +0ms
  serialport/bindings/unixWrite wrote 9 bytes +0ms
  serialport/bindings/unixWrite Finished writing 9 bytes +1ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +4ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +4ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  cc-znp { sof: 254,
  cc-znp   len: 1,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpReq',
  cc-znp   payload: { status: 0 },
  cc-znp   fcs: 97,
  cc-znp   csum: 97 } +6ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- ZDO:activeEpReq, { status: 0 } +6ms
  serialport/bindings/unixRead Finished read 16 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 13,
  cc-znp   type: 'AREQ',
  cc-znp   subsys: 'ZDO',
  cc-znp   cmd: 'activeEpRsp',
  cc-znp   payload:
  cc-znp    { srcaddr: 0,
  cc-znp      status: 0,
  cc-znp      nwkaddr: 0,
  cc-znp      activeepcount: 7,
  cc-znp      activeeplist: <Buffer 0b 06 05 04 03 02 01> },
  cc-znp   fcs: 198,
  cc-znp   csum: 198 } +1ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:AREQ <-- ZDO:activeEpRsp, { srcaddr: 0, status: 0, nwkaddr: 0, activeepcount: 7, activeeplist: <Buffer 0b 06 05 04 03 02 01> } +15ms
  zigbee-shepherd:msgHdlr IND <-- ZDO:activeEpRsp +15ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 58, offset: 0 } +11ms
  serialport/stream _write 8 bytes of data +4ms
  serialport/binding-abstract write 8 bytes +4ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +11ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  zigbee2mqtt:debug 5/14/2019, 2:09:26 PM Mounted the cieApp (epId 11)
  serialport/bindings/poller received "readable" +9ms
  serialport/binding-abstract read +6ms
  serialport/bindings/unixRead Starting read +9ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +5ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +0ms
  serialport/bindings/poller received "readable" +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 19,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 17,
  cc-znp      value: <Buffer 00 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  cc-znp   fcs: 104,
  cc-znp   csum: 104 } +13ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 17, value: <Buffer 00 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> } +15ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +2ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +10ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 59, offset: 0 } +19ms
  serialport/stream _write 8 bytes of data +11ms
  serialport/binding-abstract write 8 bytes +11ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +19ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +16ms
  serialport/binding-abstract read +6ms
  serialport/bindings/unixRead Starting read +17ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +6ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 19,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 17,
  cc-znp      value: <Buffer 00 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  cc-znp   fcs: 104,
  cc-znp   csum: 104 } +20ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 17, value: <Buffer 00 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> } +20ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +10ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 71, offset: 0 } +21ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +21ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +18ms
  serialport/binding-abstract read +7ms
  serialport/bindings/unixRead Starting read +18ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +7ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 10,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0, len: 8, value: <Buffer 00 00 00 00 00 00 00 00> },
  cc-znp   fcs: 107,
  cc-znp   csum: 107 } +22ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 8, value: <Buffer 00 00 00 00 00 00 00 00> } +22ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +11ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +10ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 98, offset: 0 } +21ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +21ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +18ms
  serialport/binding-abstract read +8ms
  serialport/bindings/unixRead Starting read +19ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +8ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 16,
  cc-znp      value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> },
  cc-znp   fcs: 104,
  cc-znp   csum: 104 } +22ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 16, value: <Buffer 01 03 05 07 09 0b 0d 0f 00 02 04 06 08 0a 0c 0d> } +22ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +12ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +5ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 257, offset: 0 } +23ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +23ms
  serialport/bindings/unixWrite write returned null 8 +1ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +17ms
  serialport/binding-abstract read +6ms
  serialport/bindings/unixRead Starting read +18ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +6ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +1ms
  serialport/stream binding.read finished +1ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 18,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 16,
  cc-znp      value: <Buffer 4a e9 3e d6 f4 4c ea 86 5f ff df a9 d5 5b 7d e9> },
  cc-znp   fcs: 56,
  cc-znp   csum: 56 } +22ms
  serialport/stream _read reading +2ms
  serialport/binding-abstract read +2ms
  serialport/bindings/unixRead Starting read +2ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 16, value: <Buffer 4a e9 3e d6 f4 4c ea 86 5f ff df a9 d5 5b 7d e9> } +22ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +11ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +4ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 132, offset: 0 } +22ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +22ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +1ms
  serialport/bindings/poller received "readable" +15ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +15ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +0ms
  cc-znp { sof: 254,
  cc-znp   len: 6,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload: { status: 0, len: 4, value: <Buffer 00 08 00 00> },
  cc-znp   fcs: 99,
  cc-znp   csum: 99 } +18ms
  serialport/stream _read reading +4ms
  serialport/binding-abstract read +4ms
  serialport/bindings/unixRead Starting read +4ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 4, value: <Buffer 00 08 00 00> } +20ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +9ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +5ms
  zigbee-shepherd:request REQ --> SYS:osalNvRead +11ms
  cc-znp:SREQ --> SYS:osalNvRead, { id: 117, offset: 0 } +20ms
  serialport/stream _write 8 bytes of data +12ms
  serialport/binding-abstract write 8 bytes +12ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +19ms
  serialport/bindings/unixWrite write returned null 8 +0ms
  serialport/bindings/unixWrite wrote 8 bytes +0ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +14ms
  serialport/binding-abstract read +3ms
  serialport/bindings/unixRead Starting read +15ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +3ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead Finished read 8 bytes +0ms
  serialport/stream binding.read finished +0ms
  serialport/stream _read reading +0ms
  serialport/binding-abstract read +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +1ms
  serialport/bindings/poller received "readable" +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  serialport/bindings/unixRead Finished read 3 bytes +0ms
  serialport/stream binding.read finished +1ms
  cc-znp { sof: 254,
  cc-znp   len: 14,
  cc-znp   type: 'SRSP',
  cc-znp   subsys: 'SYS',
  cc-znp   cmd: 'osalNvRead',
  cc-znp   payload:
  cc-znp    { status: 0,
  cc-znp      len: 12,
  cc-znp      value: <Buffer c5 09 00 00 65 22 f4 14 00 4b 12 00> },
  cc-znp   fcs: 89,
  cc-znp   csum: 89 } +20ms
  serialport/stream _read reading +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +1ms
  cc-znp:SRSP <-- SYS:osalNvRead, { status: 0, len: 12, value: <Buffer c5 09 00 00 65 22 f4 14 00 4b 12 00> } +17ms
  zigbee-shepherd:request RSP <-- SYS:osalNvRead, status: 0 +7ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +2ms
  zigbee2mqtt:error 5/14/2019, 2:09:36 PM Not connected to MQTT server!