gistfile1.txt

[root@amethyst /opt/zigbee2mqtt]# DEBUG=* npm start

> zigbee2mqtt@1.12.2-dev start /opt/zigbee2mqtt
> node index.js

winston:create-logger Define prototype method for "error"
winston:create-logger Define prototype method for "warn"
winston:create-logger Define prototype method for "info"
winston:create-logger Define prototype method for "http"
winston:create-logger Define prototype method for "verbose"
winston:create-logger Define prototype method for "debug"
winston:create-logger Define prototype method for "silly"
winston:create-logger Define prototype method for "error"
winston:create-logger Define prototype method for "warn"
winston:create-logger Define prototype method for "info"
winston:create-logger Define prototype method for "http"
winston:create-logger Define prototype method for "verbose"
winston:create-logger Define prototype method for "debug"
winston:create-logger Define prototype method for "silly"
zigbee2mqtt:info  2020-04-15 13:30:04: Logging to console and directory: '/opt/zigbee2mqtt/data/log/2020-04-15.13-30-04' filename: log.txt
winston:file written true false
zigbee2mqtt:debug 2020-04-15 13:30:04: Removing old log directory '/opt/zigbee2mqtt/data/log/2020-04-15.13-23-02'
winston:file written true false
zigbee2mqtt:debug 2020-04-15 13:30:04: Loaded state from file /opt/zigbee2mqtt/data/state.json
winston:file written true false
winston:file logged 127 info  2020-04-15 13:30:04: Logging to console and directory: '/opt/zigbee2mqtt/data/log/2020-04-15.13-30-04' filename: log.txt

winston:file logged 229 debug 2020-04-15 13:30:04: Removing old log directory '/opt/zigbee2mqtt/data/log/2020-04-15.13-23-02'

winston:file logged 312 debug 2020-04-15 13:30:04: Loaded state from file /opt/zigbee2mqtt/data/state.json

winston:file ENOENT ok /opt/zigbee2mqtt/data/log/2020-04-15.13-30-04/log.txt
winston:file stat done: log.txt { size: 0 }
winston:file create stream start /opt/zigbee2mqtt/data/log/2020-04-15.13-30-04/log.txt { flags: 'a' }
winston:file create stream ok /opt/zigbee2mqtt/data/log/2020-04-15.13-30-04/log.txt
winston:file file open ok /opt/zigbee2mqtt/data/log/2020-04-15.13-30-04/log.txt
zigbee2mqtt:info  2020-04-15 13:30:04: Starting zigbee2mqtt version 1.12.2-dev (commit #62254bb)
winston:file written true false
zigbee2mqtt:info  2020-04-15 13:30:04: Starting zigbee-herdsman...
winston:file written true false
zigbee2mqtt:debug 2020-04-15 13:30:04: Using zigbee-herdsman with settings: '{"network":{"panID":6753,"extendedPanID":[195,214,239,95,31,21,212,49],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/cua/0"},"adapter":{"concurrent":null}}'
winston:file written true false
  serialport/bindings loading LinuxBinding +0ms
  serialport/stream .list +0ms
winston:file logged 85 info  2020-04-15 13:30:04: Starting zigbee2mqtt version 1.12.2-dev (commit #62254bb)

winston:file logged 140 info  2020-04-15 13:30:04: Starting zigbee-herdsman...

winston:file logged 593 debug 2020-04-15 13:30:04: Using zigbee-herdsman with settings: '{"network":{"panID":6753,"extendedPanID":[195,214,239,95,31,21,212,49],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/cua/0"},"adapter":{"concurrent":null}}'

  zigbee-herdsman:adapter Failed to validate path: 'Error: spawn udevadm ENOENT' +0ms
  zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[48,169,157,42,178,4,136,227,21,224,223,165,92,166,52,244],"panID":6753,"extendedPanID":[195,214,239,95,31,21,212,49],"channelList":[11]},"serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/cua/0"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","adapter":{"concurrent":null}}' +0ms
  zigbee-herdsman:adapter:zStack:znp:log Opening SerialPort with /dev/cua/0 and {"baudRate":115200,"rtscts":true,"autoOpen":false} +0ms
  serialport/stream opening path: /dev/cua/0 +71ms
  serialport/binding-abstract open +0ms
  serialport/stream _read queueing _read for after open +2ms
  serialport/bindings/poller Creating poller +0ms
  serialport/stream opened path: /dev/cua/0 +4ms
  serialport/stream _read reading { start: 0, toRead: 65536 } +0ms
  serialport/binding-abstract read +6ms
  zigbee-herdsman:adapter:zStack:znp:log Serialport opened +11ms
  zigbee-herdsman:adapter:zStack:znp:log Writing skip bootloader payload +0ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> buffer [239] +0ms
  serialport/stream _write 1 bytes of data +3ms
  serialport/binding-abstract write 1 bytes +3ms
  serialport/bindings/unixWrite Starting write 1 bytes offset 0 bytesToWrite 1 +0ms
  serialport/bindings/unixRead Starting read +0ms
  serialport/bindings/unixWrite write returned: wrote 1 bytes +1ms
  serialport/bindings/unixWrite Finished writing 1 bytes +0ms
  serialport/stream binding.write write finished +3ms
  serialport/bindings/unixRead read error { [Error: EAGAIN: resource temporarily unavailable, read] errno: -11, code: 'EAGAIN', syscall: 'read' } +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +7ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1} +0ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32] +1s
  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: wrote 5 bytes +0ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/poller received "readable" +1s
  serialport/bindings/unixRead Starting read +1s
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished { bytesRead: 7 } +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,1,121,1,26] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,1,121,1,26] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 1 - [121,1] - 26 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - ping - {"capabilities":377} +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +3ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - version - {} +15ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,2,35] +10ms
  serialport/stream _write 5 bytes of data +5ms
  serialport/binding-abstract write 5 bytes +9ms
  serialport/bindings/unixWrite Starting write 5 bytes offset 0 bytesToWrite 5 +9ms
  serialport/stream _read reading { start: 7, toRead: 65529 } +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +7ms
  serialport/bindings/unixWrite write returned: wrote 5 bytes +1ms
  serialport/bindings/unixWrite Finished writing 5 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/unixRead read error { [Error: EAGAIN: resource temporarily unavailable, read] errno: -11, code: 'EAGAIN', syscall: 'read' } +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +1ms
  serialport/bindings/poller Polling for "readable" +8ms
  serialport/bindings/poller received "readable" +2ms
  serialport/bindings/unixRead Starting read +2ms
  serialport/bindings/unixRead Finished read 19 bytes +0ms
  serialport/stream binding.read finished { bytesRead: 19 } +3ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,97,2,2,0,2,6,3,218,59,52,1,2,0,0,0,0,190] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,97,2,2,0,2,6,3,218,59,52,1,2,0,0,0,0,190] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 3 - 1 - 2 - [2,0,2,6,3,218,59,52,1,2,0,0,0,0] - 190 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - version - {"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20200410} +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:adapter Adapter concurrent: 2 +0ms
  zigbee-herdsman:adapter:zStack:adapter Detected znp version 'zStack12' ({"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20200410}) +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":3840,"offset":0,"len":1,"value":{"type":"Buffer","data":[85]}} +7ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,0,15,0,37] +8ms
  serialport/stream _write 8 bytes of data +4ms
  serialport/binding-abstract write 8 bytes +7ms
  serialport/bindings/unixWrite Starting write 8 bytes offset 0 bytesToWrite 8 +7ms
  serialport/stream _read reading { start: 26, toRead: 65510 } +1ms
  serialport/binding-abstract read +1ms
  serialport/bindings/unixRead Starting read +5ms
  serialport/bindings/unixWrite write returned: wrote 8 bytes +1ms
  serialport/bindings/unixWrite Finished writing 8 bytes +0ms
  serialport/stream binding.write write finished +0ms
  serialport/bindings/unixRead read error { [Error: EAGAIN: resource temporarily unavailable, read] errno: -11, code: 'EAGAIN', syscall: 'read' } +0ms
  serialport/bindings/unixRead waiting for readable because of code: EAGAIN +0ms
  serialport/bindings/poller Polling for "readable" +5ms
  serialport/bindings/poller received "readable" +15ms
  serialport/bindings/unixRead Starting read +15ms
  serialport/bindings/unixRead Finished read 7 bytes +0ms
  serialport/stream binding.read finished { bytesRead: 7 } +15ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,8,2,0,105] +19ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,8,2,0,105] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 8 - [2,0] - 105 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":2,"len":0,"value":{"type":"Buffer","data":[]}} +20ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
zigbee2mqtt:error 2020-04-15 13:30:06: Error while starting zigbee-herdsman
winston:file written true false
zigbee2mqtt:error 2020-04-15 13:30:06: Failed to start zigbee
winston:file written true false
zigbee2mqtt:error 2020-04-15 13:30:06: Exiting...
winston:file written true false
zigbee2mqtt:error 2020-04-15 13:30:06: Error: SREQ '--> SYS - osalNvRead - {"id":3840,"offset":0,"len":1,"value":{"type":"Buffer","data":[85]}}' failed with status '2' (expected '0')
    at Znp.<anonymous> (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:246:27)
    at Generator.next (<anonymous>)
    at fulfilled (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:5:58)
winston:file written true false
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! zigbee2mqtt@1.12.2-dev start: `node index.js`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the zigbee2mqtt@1.12.2-dev start script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.

npm ERR! A complete log of this run can be found in:
npm ERR!     /root/.npm/_logs/2020-04-15T13_30_06_336Z-debug.log