search

AlCalzone / ioBroker.ble

Monitor Bluetooth Low Energy beacons
MIT License
41 stars 14 forks source link

'driver state unkown' und Meldung im Log #399

Open darkman242 opened 4 years ago

darkman242 commented 4 years ago

Describe the bug Nachfolgende Log-Meldung kommt in unregelmäßigen Abständen. Danach steht der DP 'driverState' auf 'driver state unkown' und die Instanz inkl. hcidevice muss neu gestartet werden

Screenshots & Logfiles
instance system.adapter.ble.0 terminated with code 1 (JS_CONTROLLER_STOPPED) uncaught exception: The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6

Versions:

AlCalzone commented 4 years ago

Wenn es reproduzierbar ist wäre ein log mit Stufe debug hilfreich

darkman242 commented 4 years ago

hier ein Log (tritt halt sporadisch 1/2x am Tag auf.

2020-05-05 18:49:39.712  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:40.202  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:40.202  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:40.202  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:40.202  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:40.202  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:40.202  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:40.202  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:40.204  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:40.204  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:40.204  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:40.204  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:40.204  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:40.248  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:40.249  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:40.572  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:40.572  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:40.572  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:40.572  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:40.572  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:40.572  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:41.491  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:41.491  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:41.491  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:41.491  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:41.491  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:41.491  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:41.491  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:41.494  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:41.494  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:41.494  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:41.495  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:41.495  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:41.497  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:41.497  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:42.291  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:42.291  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:42.291  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:42.291  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:42.291  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:42.291  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:42.770  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:42.770  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:42.770  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:42.770  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:42.770  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:42.770  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:42.770  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:42.772  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:42.772  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:42.772  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:42.772  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:42.772  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:42.774  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:42.774  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:43.147  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:43.147  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:43.147  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:43.147  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:43.147  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:43.147  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:44.063  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:44.063  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:44.063  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:44.063  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:44.063  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:44.063  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:44.063  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:44.065  - debug: ble.0 (25909) updating rssi state for d3:01:00:03:5d:6d
2020-05-05 18:49:44.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:44.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:44.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:44.109  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:44.109  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:44.110  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:44.152  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:44.864  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:44.864  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:44.864  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:44.864  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:44.864  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:44.864  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:45.360  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:45.360  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:45.360  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:45.360  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:45.360  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:45.360  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:45.360  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:45.362  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:45.362  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:45.362  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:45.362  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:45.362  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:45.363  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:45.364  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:47.434  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:47.434  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:47.434  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:47.434  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:47.435  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:47.435  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:47.944  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:47.945  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:47.945  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:47.945  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:47.945  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:47.945  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:47.945  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:47.947  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:47.947  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:47.947  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:47.947  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:47.947  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:47.948  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:47.949  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:48.290  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:48.290  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:48.290  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:48.290  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:48.290  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:48.290  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:49.019  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:68:58
2020-05-05 18:49:49.019  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:49.019  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:49.019  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,104,88]}}]
2020-05-05 18:49:49.019  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:49.019  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00036858c5
2020-05-05 18:49:49.019  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:68:58
2020-05-05 18:49:49.021  - debug: ble.0 (25909) updating rssi state for d3:01:00:03:68:58
2020-05-05 18:49:49.065  - debug: ble.0 (25909) _default: d3:01:00:03:68:58 > got data 7 for fe65
2020-05-05 18:49:49.066  - debug: ble.0 (25909) _default: d3:01:00:03:68:58 > got data 01070100d30100036858 for fe33
2020-05-05 18:49:49.066  - debug: ble.0 (25909) _default: d3:01:00:03:68:58 > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00036858c5
2020-05-05 18:49:49.066  - debug: ble.0 (25909) d3:01:00:03:68:58 > got values: {"services.fe65":7,"services.fe33":"01070100d30100036858","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00036858c5"}
2020-05-05 18:49:49.066  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:68:58.services.fe65
2020-05-05 18:49:49.108  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:68:58.services.fe33
2020-05-05 18:49:49.110  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:68:58.services.manufacturerData
2020-05-05 18:49:49.154  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:49.154  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:49.154  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:49.154  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:49.154  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:49.154  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:50.525  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:50.525  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:50.525  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:50.525  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:50.525  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:50.525  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:50.525  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:50.534  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:50.534  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:50.534  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:50.534  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:50.534  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:50.536  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:50.537  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:50.861  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:50.861  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:50.861  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:50.861  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:50.861  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:50.861  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:49:53.106  - debug: ble.0 (25909) discovered peripheral d3:01:00:03:5d:6d
2020-05-05 18:49:53.106  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:53.106  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:53.106  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe65","data":{"type":"Buffer","data":[7]}},{"uuid":"fe33","data":{"type":"Buffer","data":[1,7,1,0,211,1,0,3,93,109]}}]
2020-05-05 18:49:53.106  - debug: ble.0 (25909)   has manufacturerData: true
2020-05-05 18:49:53.106  - debug: ble.0 (25909)   manufacturerData = 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:53.106  - debug: ble.0 (25909) plugin _default is handling d3:01:00:03:5d:6d
2020-05-05 18:49:53.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 7 for fe65
2020-05-05 18:49:53.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got data 01070100d30100035d6d for fe33
2020-05-05 18:49:53.108  - debug: ble.0 (25909) _default: d3:01:00:03:5d:6d > got manufacturer data 4c0002150f73d45375244862a8643420875dba4b00035d6dc5
2020-05-05 18:49:53.108  - debug: ble.0 (25909) d3:01:00:03:5d:6d > got values: {"services.fe65":7,"services.fe33":"01070100d30100035d6d","services.manufacturerData":"4c0002150f73d45375244862a8643420875dba4b00035d6dc5"}
2020-05-05 18:49:53.108  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe65
2020-05-05 18:49:53.152  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.fe33
2020-05-05 18:49:53.153  - debug: ble.0 (25909) setting state ble.0.d3:01:00:03:5d:6d.services.manufacturerData
2020-05-05 18:49:53.433  - debug: ble.0 (25909) discovered peripheral c8:d5:5e:af:d2:e9
2020-05-05 18:49:53.433  - debug: ble.0 (25909)   has advertisement: true
2020-05-05 18:49:53.434  - debug: ble.0 (25909)   has serviceData: true
2020-05-05 18:49:53.434  - debug: ble.0 (25909)   serviceData = [{"uuid":"fe0f","data":{"type":"Buffer","data":[32,16,105,40,14]}}]
2020-05-05 18:49:53.434  - debug: ble.0 (25909)   has manufacturerData: false
2020-05-05 18:49:53.434  - debug: ble.0 (25909) plugin _default is handling c8:d5:5e:af:d2:e9
2020-05-05 18:50:00.018  - info: host.iobroker-01 instance system.adapter.dwd.0 started with pid 27597
2020-05-05 18:50:02.308  - info: host.iobroker-01 instance system.adapter.dwd.0 terminated with code 0 (NO_ERROR)
2020-05-05 18:51:22.204  - error: ble.0 (25909) uncaught exception: The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6
2020-05-05 18:51:22.219  - error: host.iobroker-01 instance system.adapter.ble.0 terminated with code 1 (JS_CONTROLLER_STOPPED)
2020-05-05 18:51:22.220  - info: host.iobroker-01 Restart adapter system.adapter.ble.0 because enabled
2020-05-05 18:51:52.233  - info: host.iobroker-01 instance system.adapter.ble.0 started with pid 27672
2020-05-05 18:51:52.467  - debug: ble.0 (27672) Redis Objects: Use Redis connection: 127.0.0.1:9001
2020-05-05 18:51:52.491  - debug: ble.0 (27672) Objects client ready ... initialize now
2020-05-05 18:51:52.492  - debug: ble.0 (27672) Objects create System PubSub Client
2020-05-05 18:51:52.493  - debug: ble.0 (27672) Objects create User PubSub Client
2020-05-05 18:51:52.493  - debug: ble.0 (27672) Objects client initialize lua scripts
2020-05-05 18:51:52.501  - debug: ble.0 (27672) Objects connected to redis: 127.0.0.1:9001
2020-05-05 18:51:52.503  - debug: ble.0 (27672) objectDB connected
2020-05-05 18:51:52.503  - debug: ble.0 (27672) Redis States: Use Redis connection: 127.0.0.1:9000
2020-05-05 18:51:52.508  - debug: ble.0 (27672) States create User PubSub Client
2020-05-05 18:51:52.509  - debug: ble.0 (27672) States create System PubSub Client
2020-05-05 18:51:52.515  - debug: ble.0 (27672) States connected to redis: 127.0.0.1:9000
2020-05-05 18:51:52.515  - debug: ble.0 (27672) statesDB connected
2020-05-05 18:51:53.259  - info: ble.0 (27672) starting. Version 0.11.4 in /opt/iobroker/node_modules/iobroker.ble, node: v12.16.3, js-controller: 3.0.20
2020-05-05 18:51:53.358  - info: ble.0 (27672) loaded plugins: Xiaomi, mi-flora, ruuvi-tag, _default
2020-05-05 18:51:53.359  - info: ble.0 (27672) enabled plugins: _default
2020-05-05 18:51:53.359  - info: ble.0 (27672) monitored services:
AlCalzone commented 4 years ago

Ich finde keinen Code im Adapter, der das auslösen könnte. Vermutlich hängt das mit einem der folgenden Issues zusammen: https://github.com/abandonware/noble/issues/19 https://github.com/noble/noble/issues/927 (... gibt noch einige davon)

Könntest du mal aus dem Admin die Log-Datei herunterladen, ob dort mehr Kontext zu dem Fehler steht?

darkman242 commented 4 years ago

Wie kann ich dir (nicht öffentlich) das Log zukommen lassen?

AlCalzone commented 4 years ago

z.B. per PN im ioBroker-Forum. Oder als verschlüsseltes Archiv hier hochladen und mir das Passwort dort zuschicken.

Im Grunde interessiert mich aber nur der Abschnitt hier, da Admin manchmal Zeilen verschluckt:

2020-05-05 18:51:22.204  - �[31merror�[39m: ble.0 (25909) uncaught exception: The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6

[... steht hier im vollständigen Log mehr?]

2020-05-05 18:51:22.219  - �[31merror�[39m: host.iobroker-01 instance system.adapter.ble.0 terminated with code 1 (JS_CONTROLLER_STOPPED)
darkman242 commented 4 years ago

Sorry, gerade nochmal nachgeschaut. Im Log steht nichts mehr in deinem genannten Abschnitt.

AlCalzone commented 4 years ago

Ok, ich versuche in v0.11.5 (in ein paar Minuten verfügbar) den Fehler einfach zu ignorieren.

darkman242 commented 4 years ago

habe die 0.11.5 installiert. Jetzt kommt folgende Fehler im Log (kein debug-Log). `

ble.0 2020-05-07 18:09:48.091 error (17366) The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6
ble.0 2020-05-07 18:09:48.091 error at BluetoothHciSocket.emit (events.js:310:20)
ble.0 2020-05-07 18:09:48.091 error at Hci.onSocketData (/opt/iobroker/node_modules/@abandonware/noble/lib/hci-socket/hci.js:436:27)
ble.0 2020-05-07 18:09:48.091 error at Uint8Array.readUInt8 (internal/buffer.js:243:5)
ble.0 2020-05-07 18:09:48.091 error at boundsError (internal/buffer.js:77:9)
ble.0 2020-05-07 18:09:48.091 error (17366) RangeError [ERR_OUT_OF_RANGE]: The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6
ble.0 2020-05-07 18:09:48.091 error (17366) uncaught exception: The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6
ble.0 2020-05-07 18:09:48.090 error (17366) The value of "offset" is out of range. It must be >= 0 and <= 3. Received 4
ble.0 2020-05-07 18:09:48.089 error at BluetoothHciSocket.emit (events.js:310:20)
ble.0 2020-05-07 18:09:48.089 error at Hci.onSocketData (/opt/iobroker/node_modules/@abandonware/noble/lib/hci-socket/hci.js:451:18)
ble.0 2020-05-07 18:09:48.089 error at Uint8Array.readUInt16LE (internal/buffer.js:234:5)
ble.0 2020-05-07 18:09:48.089 error at boundsError (internal/buffer.js:77:9)
ble.0 2020-05-07 18:09:48.089 error (17366) RangeError [ERR_OUT_OF_RANGE]: The value of "offset" is out of range. It must be >= 0 and <= 3. Received 4
ble.0 2020-05-07 18:09:48.089 error (17366) uncaught exception: The value of "offset" is out of range. It must be >= 0 and <= 3. Received 4

`

AlCalzone commented 4 years ago

Nevermind, ist doch besser. Der Log-Output sagt was los ist und der Adapter schmiert nicht ab. Habe ein Issue im noble-Repo aufgemacht, damit das gefixt werden kann.

darkman242 commented 4 years ago

Okay, ich teste (dann). Hab dann mal mein Skript gestoppt, welche den Adapter neu startet falls der DP driverstate auf unkown steht.

darkman242 commented 4 years ago

Nachfolgende Fehlermeldung kommt im Log. Der Adapter wird aber nicht beendet und der Scan läuft weiter.

2020-05-08 17:53:10.708 - error: ble.0 (22577) The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6 2020-05-08 18:07:11.898 - error: ble.0 (22577) The value of "offset" is out of range. It must be >= 0 and <= 3. Received 4 2020-05-08 18:07:11.900 - error: ble.0 (22577) The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6 2020-05-08 18:07:14.828 - error: ble.0 (22577) The value of "offset" is out of range. It must be >= 0 and <= 3. Received 4 2020-05-08 18:07:14.831 - error: ble.0 (22577) The value of "offset" is out of range. It must be >= 0 and <= 5. Received 6