Test Alpha Homekit-Controller 0.0.x

apollon77

@fortune95 sagte in Test Alpha Homekit-Controller 0.0.x:

ooookkkkk ... also jetzt am Laptop nochmal log gelesen ...

Ja du hast ein pair gemacht für BLE-F5:A0:3F:34:7E:71, Eve Degree 053B und wenn ich das richtig sehe war da auch erfolgreich. hat 6 Sekunden gebraucht

weil sonst hätte er nicht

state homekit-controller.0.BLE-F5:A0:3F:34:7E:71.admin.pairWithPin deleted

geloggt.

Kannst Du es bitte nochmal mit Debug log versuchen? Und wenn es nicht tut bitte gepaired lassen und mal adapter stoppen, 10s warten und neu starten. Tut es dann ?

Fortune95

@apollon77
Hab den Adpater gestartet, dann die beiden Eve Geräte Degree & Room im Adapter gesehen.
Den Degree per Ident Funktion erfolgreich identifiziert und danach den Pair über den entsprechenden button ausgelöst.
Bis auf die Basis Objekte wurde nichts weiteres kreiert.
Dann habe ich den Adapter gestoppt und nach ca. 25s wieder gestartet.
Jetzt sehe ich den Degree wieder im Adapter aber er wird nicht als gepairt oder pairbar gekennzeichnet.
Mit Apple Home lässt er sich einwandfrei pairen.

Hier der Debug Log vom ganzen Prozedere:

2021-12-12 19:31:53.363 - debug: homekit-controller.0 (1208651) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-12 19:31:53.413 - debug: homekit-controller.0 (1208651) Objects client ready ... initialize now
2021-12-12 19:31:53.420 - debug: homekit-controller.0 (1208651) Objects create System PubSub Client
2021-12-12 19:31:53.424 - debug: homekit-controller.0 (1208651) Objects create User PubSub Client
2021-12-12 19:31:53.427 - debug: homekit-controller.0 (1208651) Objects client initialize lua scripts
2021-12-12 19:31:53.479 - debug: homekit-controller.0 (1208651) Objects connected to redis: 127.0.0.1:9001
2021-12-12 19:31:53.492 - debug: homekit-controller.0 (1208651) objectDB connected
2021-12-12 19:31:53.496 - debug: homekit-controller.0 (1208651) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-12 19:31:53.510 - debug: homekit-controller.0 (1208651) States create System PubSub Client
2021-12-12 19:31:53.512 - debug: homekit-controller.0 (1208651) States create User PubSub Client
2021-12-12 19:31:53.526 - debug: homekit-controller.0 (1208651) States connected to redis: 127.0.0.1:6379
2021-12-12 19:31:53.528 - debug: homekit-controller.0 (1208651) statesDB connected
2021-12-12 19:31:53.810 - debug: homekit-controller.0 (1208651) Plugin sentry Initialize Plugin (enabled=true)
2021-12-12 19:31:54.261 - info: homekit-controller.0 (1208651) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-12 19:31:54.581 - debug: homekit-controller.0 (1208651) Init 2 known devices without discovery ...
2021-12-12 19:31:54.584 - info: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:31:54.586 - debug: homekit-controller.0 (1208651) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":true}
2021-12-12 19:31:54.602 - debug: homekit-controller.0 (1208651) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-12 19:31:54.675 - debug: homekit-controller.0 (1208651) Discovered IP device up: 0E:6C:79:7B:0B:81/Homebridge 4B75
2021-12-12 19:31:54.676 - debug: homekit-controller.0 (1208651) IP-0E:6C:79:7B:0B:81 Discovered IP device: {"name":"Homebridge 4B75","address":"192.168.12.219","port":51861,"c#":3,"ff":0,"id":"0E:6C:79:7B:0B:81","md":"homebridge","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-12 19:31:54.677 - info: homekit-controller.0 (1208651) IP-0E:6C:79:7B:0B:81 (Homebridge 4B75) found without known pairing data and already paired: ignoring
2021-12-12 19:31:54.681 - debug: homekit-controller.0 (1208651) Discovered IP device up: ab:34:18:95:31:5d/IOBroker 83D1
2021-12-12 19:31:54.682 - debug: homekit-controller.0 (1208651) IP-AB:34:18:95:31:5D Discovered IP device: {"name":"IOBroker 83D1","address":"192.168.12.219","port":34237,"c#":157,"ff":0,"id":"ab:34:18:95:31:5d","md":"not configured","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-12 19:31:54.682 - info: homekit-controller.0 (1208651) IP-AB:34:18:95:31:5D (IOBroker 83D1) found without known pairing data and already paired: ignoring
2021-12-12 19:31:54.689 - debug: homekit-controller.0 (1208651) Discovered IP device up: 58:47:6A:51:5B:FE/IN-8003FHD-PoE(c7)
2021-12-12 19:31:54.689 - debug: homekit-controller.0 (1208651) IP-58:47:6A:51:5B:FE Discovered IP device: {"name":"IN-8003FHD-PoE(c7)","address":"192.168.12.199","port":23657,"c#":1,"ff":0,"id":"58:47:6A:51:5B:FE","md":"IN-8003FHD-PoE","pv":"1.0","s#":1,"sf":0,"ci":17,"availableToPair":false}
2021-12-12 19:31:54.690 - info: homekit-controller.0 (1208651) IP-58:47:6A:51:5B:FE (IN-8003FHD-PoE(c7)) found without known pairing data and already paired: ignoring
2021-12-12 19:31:54.700 - debug: homekit-controller.0 (1208651) Discovered BLE device up: e7:21:2e:89:7e:35/Eve Degree 053B
2021-12-12 19:31:54.701 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":true}
2021-12-12 19:31:54.701 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Device initialization already in progress ... ignore call
2021-12-12 19:31:54.707 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.connectionType changed: BLE (ack = true)
2021-12-12 19:31:54.719 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.id changed: e7:21:2e:89:7e:35 (ack = true)
2021-12-12 19:31:54.731 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.connected changed: false (ack = true)
2021-12-12 19:31:54.749 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.isPaired changed: false (ack = true)
2021-12-12 19:31:54.760 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.lastDiscovered changed: 1639333914589 (ack = true)
2021-12-12 19:31:54.775 - info: homekit-controller.0 (1208651) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:31:54.776 - debug: homekit-controller.0 (1208651) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:31:54.804 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-12 19:31:54.815 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-12 19:31:54.827 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-12 19:31:54.850 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-12 19:31:54.871 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639333914777 (ack = true)
2021-12-12 19:31:55.873 - debug: homekit-controller.0 (1208651) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-12 19:31:55.874 - debug: homekit-controller.0 (1208651) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:31:55.875 - info: homekit-controller.0 (1208651) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:31:55.875 - debug: homekit-controller.0 (1208651) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:31:55.907 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-12 19:31:55.919 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-12 19:31:55.932 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-12 19:31:55.952 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-12 19:31:55.963 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639333915876 (ack = true)
2021-12-12 19:31:57.849 - debug: homekit-controller.0 (1208651) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":209,"ack":false,"time":1639333917840},"_id":54081771})
2021-12-12 19:31:57.851 - debug: homekit-controller.0 (1208651) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-E7:21:2E:89:7E:35","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-12 19:31:57.852 - debug: homekit-controller.0 (1208651) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-12 19:32:09.019 - debug: homekit-controller.0 (1208651) Message identify received: {"command":"identify","message":{"deviceId":"BLE-E7:21:2E:89:7E:35"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-E7:21:2E:89:7E:35"},"id":210,"ack":false,"time":1639333929017},"_id":54081772})
2021-12-12 19:32:09.020 - debug: homekit-controller.0 (1208651) Device BLE-E7:21:2E:89:7E:35: Identify triggered
2021-12-12 19:32:12.434 - debug: homekit-controller.0 (1208651) Response to Command identify: {"success":true,"error":false}
2021-12-12 19:32:12.436 - debug: homekit-controller.0 (1208651) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-12 19:32:13.957 - debug: homekit-controller.0 (1208651) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":211,"ack":false,"time":1639333933955},"_id":54081773})
2021-12-12 19:32:13.957 - debug: homekit-controller.0 (1208651) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-E7:21:2E:89:7E:35","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-12 19:32:13.958 - debug: homekit-controller.0 (1208651) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-12 19:32:37.174 - debug: homekit-controller.0 (1208651) Message pairDevice received: {"command":"pairDevice","message":{"deviceId":"BLE-E7:21:2E:89:7E:35","pin":"629-15-584"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-E7:21:2E:89:7E:35","pin":"629-15-584"},"id":212,"ack":false,"time":1639333957172},"_id":54081774})
2021-12-12 19:32:44.315 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Start Homekit Device Client initialization
2021-12-12 19:32:53.785 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Start Homekit Device Client initialization
2021-12-12 19:32:53.786 - debug: homekit-controller.0 (1208651) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":false}
2021-12-12 19:32:53.809 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.pairWithPin deleted
2021-12-12 19:32:53.840 - debug: homekit-controller.0 (1208651) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.identify deleted
2021-12-12 19:33:04.086 - debug: homekit-controller.0 (1208651) Discovered BLE device changed: e7:21:2e:89:7e:35/Eve Degree 053B
2021-12-12 19:33:04.087 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":false}
2021-12-12 19:33:04.088 - debug: homekit-controller.0 (1208651) BLE-E7:21:2E:89:7E:35 Device initialization already in progress ... ignore call
2021-12-12 19:33:23.430 - debug: homekit-controller.0 (1208651) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":213,"ack":false,"time":1639334003428},"_id":54081775})
2021-12-12 19:33:23.431 - debug: homekit-controller.0 (1208651) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-E7:21:2E:89:7E:35","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":true},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-12 19:33:23.431 - debug: homekit-controller.0 (1208651) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-12 19:34:04.645 - info: homekit-controller.0 (1208651) Got terminate signal TERMINATE_YOURSELF
2021-12-12 19:34:04.655 - info: homekit-controller.0 (1208651) terminating
2021-12-12 19:34:04.657 - debug: homekit-controller.0 (1208651) Plugin sentry destroyed
2021-12-12 19:34:04.657 - info: homekit-controller.0 (1208651) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2021-12-12 19:34:33.622 - debug: homekit-controller.0 (1208696) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-12 19:34:33.674 - debug: homekit-controller.0 (1208696) Objects client ready ... initialize now
2021-12-12 19:34:33.680 - debug: homekit-controller.0 (1208696) Objects create System PubSub Client
2021-12-12 19:34:33.684 - debug: homekit-controller.0 (1208696) Objects create User PubSub Client
2021-12-12 19:34:33.687 - debug: homekit-controller.0 (1208696) Objects client initialize lua scripts
2021-12-12 19:34:33.759 - debug: homekit-controller.0 (1208696) Objects connected to redis: 127.0.0.1:9001
2021-12-12 19:34:33.785 - debug: homekit-controller.0 (1208696) objectDB connected
2021-12-12 19:34:33.790 - debug: homekit-controller.0 (1208696) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-12 19:34:33.821 - debug: homekit-controller.0 (1208696) States create System PubSub Client
2021-12-12 19:34:33.823 - debug: homekit-controller.0 (1208696) States create User PubSub Client
2021-12-12 19:34:33.850 - debug: homekit-controller.0 (1208696) States connected to redis: 127.0.0.1:6379
2021-12-12 19:34:33.851 - debug: homekit-controller.0 (1208696) statesDB connected
2021-12-12 19:34:34.246 - debug: homekit-controller.0 (1208696) Plugin sentry Initialize Plugin (enabled=true)
2021-12-12 19:34:34.486 - info: homekit-controller.0 (1208696) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-12 19:34:34.728 - debug: homekit-controller.0 (1208696) Init 2 known devices without discovery ...
2021-12-12 19:34:34.730 - info: homekit-controller.0 (1208696) BLE-E7:21:2E:89:7E:35 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:34:34.732 - debug: homekit-controller.0 (1208696) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":true}
2021-12-12 19:34:34.746 - debug: homekit-controller.0 (1208696) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-12 19:34:34.787 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.connectionType changed: BLE (ack = true)
2021-12-12 19:34:34.838 - debug: homekit-controller.0 (1208696) Discovered IP device up: 0E:6C:79:7B:0B:81/Homebridge 4B75
2021-12-12 19:34:34.839 - debug: homekit-controller.0 (1208696) IP-0E:6C:79:7B:0B:81 Discovered IP device: {"name":"Homebridge 4B75","address":"192.168.12.219","port":51861,"c#":3,"ff":0,"id":"0E:6C:79:7B:0B:81","md":"homebridge","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-12 19:34:34.840 - info: homekit-controller.0 (1208696) IP-0E:6C:79:7B:0B:81 (Homebridge 4B75) found without known pairing data and already paired: ignoring
2021-12-12 19:34:34.845 - debug: homekit-controller.0 (1208696) Discovered IP device up: ab:34:18:95:31:5d/IOBroker 83D1
2021-12-12 19:34:34.846 - debug: homekit-controller.0 (1208696) IP-AB:34:18:95:31:5D Discovered IP device: {"name":"IOBroker 83D1","address":"192.168.12.219","port":34237,"c#":157,"ff":0,"id":"ab:34:18:95:31:5d","md":"not configured","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-12 19:34:34.847 - info: homekit-controller.0 (1208696) IP-AB:34:18:95:31:5D (IOBroker 83D1) found without known pairing data and already paired: ignoring
2021-12-12 19:34:34.853 - debug: homekit-controller.0 (1208696) Discovered IP device up: 58:47:6A:51:5B:FE/IN-8003FHD-PoE(c7)
2021-12-12 19:34:34.853 - debug: homekit-controller.0 (1208696) IP-58:47:6A:51:5B:FE Discovered IP device: {"name":"IN-8003FHD-PoE(c7)","address":"192.168.12.199","port":23657,"c#":1,"ff":0,"id":"58:47:6A:51:5B:FE","md":"IN-8003FHD-PoE","pv":"1.0","s#":1,"sf":0,"ci":17,"availableToPair":false}
2021-12-12 19:34:34.854 - info: homekit-controller.0 (1208696) IP-58:47:6A:51:5B:FE (IN-8003FHD-PoE(c7)) found without known pairing data and already paired: ignoring
2021-12-12 19:34:34.863 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.id changed: e7:21:2e:89:7e:35 (ack = true)
2021-12-12 19:34:34.876 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.connected changed: false (ack = true)
2021-12-12 19:34:34.908 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.isPaired changed: false (ack = true)
2021-12-12 19:34:34.924 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.info.lastDiscovered changed: 1639334074733 (ack = true)
2021-12-12 19:34:34.949 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.pairWithPin changed: (ack = true)
2021-12-12 19:34:34.964 - info: homekit-controller.0 (1208696) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:34:34.965 - debug: homekit-controller.0 (1208696) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:34:34.966 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-E7:21:2E:89:7E:35.admin.identify changed: false (ack = true)
2021-12-12 19:34:35.012 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-12 19:34:35.026 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-12 19:34:35.044 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-12 19:34:35.072 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-12 19:34:35.091 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639334074965 (ack = true)
2021-12-12 19:34:35.221 - debug: homekit-controller.0 (1208696) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-12 19:34:35.223 - debug: homekit-controller.0 (1208696) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:34:35.223 - info: homekit-controller.0 (1208696) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-12 19:34:35.224 - debug: homekit-controller.0 (1208696) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-12 19:34:35.259 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-12 19:34:35.273 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-12 19:34:35.287 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-12 19:34:35.308 - debug: homekit-controller.0 (1208696) Discovered BLE device up: e7:21:2e:89:7e:35/Eve Degree 053B
2021-12-12 19:34:35.308 - debug: homekit-controller.0 (1208696) BLE-E7:21:2E:89:7E:35 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"e7:21:2e:89:7e:35","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"e7:21:2e:89:7e:35","ci":10,"availableToPair":false}
2021-12-12 19:34:35.309 - info: homekit-controller.0 (1208696) BLE-E7:21:2E:89:7E:35 (Eve Degree 053B) found without known pairing data and already paired: ignoring
2021-12-12 19:34:35.314 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-12 19:34:35.328 - debug: homekit-controller.0 (1208696) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639334075225 (ack = true)

apollon77

@fortune95 Hi, ok sehr komisch.

Bitte Installiere mal die Version direkt vom GitHub. Danach bitte Instanz neu starten, Dann bitte das "gleiche" nochmal. Ich habe ne Kleinigkeit optimiert für BLE und auch mehr Logging eingebaut.

Nach dem pair bitte mindestens 45s warten ... falls bei der Initialisierung ein Fehler kommt dann sollte der jetzt nach 45s kommen (waren vorher 2 Minuten)

Fortune95

@apollon77 sagte in Test Alpha Homekit-Controller 0.0.x:

ei der Initialisierung ein Fehler kommt dann sollte der jetzt nach 45s kommen (waren vorher 2 Minuten)

Nach dem Pairen und Neustart des Adapters wurde der Degree wieder als pairbar angezeigt.
Beim erneuten identifizieren, kam jetzt eine Fehlermeldung, das er ihn nicht erreichen konnte.
Laut dem Log konnte er das Gerät nicht ordentlich initialisieren und lief in ein Timeout.

Hier der dazugehörige Debug Log:

2021-12-13 05:29:51.938 - debug: homekit-controller.0 (1213619) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-13 05:29:52.007 - debug: homekit-controller.0 (1213619) Objects client ready ... initialize now
2021-12-13 05:29:52.012 - debug: homekit-controller.0 (1213619) Objects create System PubSub Client
2021-12-13 05:29:52.015 - debug: homekit-controller.0 (1213619) Objects create User PubSub Client
2021-12-13 05:29:52.017 - debug: homekit-controller.0 (1213619) Objects client initialize lua scripts
2021-12-13 05:29:52.060 - debug: homekit-controller.0 (1213619) Objects connected to redis: 127.0.0.1:9001
2021-12-13 05:29:52.086 - debug: homekit-controller.0 (1213619) objectDB connected
2021-12-13 05:29:52.089 - debug: homekit-controller.0 (1213619) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-13 05:29:52.116 - debug: homekit-controller.0 (1213619) States create System PubSub Client
2021-12-13 05:29:52.119 - debug: homekit-controller.0 (1213619) States create User PubSub Client
2021-12-13 05:29:52.143 - debug: homekit-controller.0 (1213619) States connected to redis: 127.0.0.1:6379
2021-12-13 05:29:52.144 - debug: homekit-controller.0 (1213619) statesDB connected
2021-12-13 05:29:52.444 - debug: homekit-controller.0 (1213619) Plugin sentry Initialize Plugin (enabled=true)
2021-12-13 05:29:52.917 - info: homekit-controller.0 (1213619) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-13 05:29:53.205 - debug: homekit-controller.0 (1213619) Init 2 known devices without discovery ...
2021-12-13 05:29:53.207 - info: homekit-controller.0 (1213619) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:29:53.208 - debug: homekit-controller.0 (1213619) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:29:53.223 - debug: homekit-controller.0 (1213619) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-13 05:29:53.262 - debug: homekit-controller.0 (1213619) Discovered IP device up: 0E:6C:79:7B:0B:81/Homebridge 4B75
2021-12-13 05:29:53.263 - debug: homekit-controller.0 (1213619) IP-0E:6C:79:7B:0B:81 Discovered IP device: {"name":"Homebridge 4B75","address":"192.168.12.219","port":51861,"c#":3,"ff":0,"id":"0E:6C:79:7B:0B:81","md":"homebridge","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-13 05:29:53.264 - info: homekit-controller.0 (1213619) IP-0E:6C:79:7B:0B:81 (Homebridge 4B75) found without known pairing data and already paired: ignoring
2021-12-13 05:29:53.268 - debug: homekit-controller.0 (1213619) Discovered IP device up: ab:34:18:95:31:5d/IOBroker 83D1
2021-12-13 05:29:53.268 - debug: homekit-controller.0 (1213619) IP-AB:34:18:95:31:5D Discovered IP device: {"name":"IOBroker 83D1","address":"192.168.12.219","port":34237,"c#":157,"ff":0,"id":"ab:34:18:95:31:5d","md":"not configured","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-13 05:29:53.269 - info: homekit-controller.0 (1213619) IP-AB:34:18:95:31:5D (IOBroker 83D1) found without known pairing data and already paired: ignoring
2021-12-13 05:29:53.275 - debug: homekit-controller.0 (1213619) Discovered IP device up: 58:47:6A:51:5B:FE/IN-8003FHD-PoE(c7)
2021-12-13 05:29:53.275 - debug: homekit-controller.0 (1213619) IP-58:47:6A:51:5B:FE Discovered IP device: {"name":"IN-8003FHD-PoE(c7)","address":"192.168.12.199","port":23657,"c#":1,"ff":0,"id":"58:47:6A:51:5B:FE","md":"IN-8003FHD-PoE","pv":"1.0","s#":1,"sf":0,"ci":17,"availableToPair":false}
2021-12-13 05:29:53.276 - info: homekit-controller.0 (1213619) IP-58:47:6A:51:5B:FE (IN-8003FHD-PoE(c7)) found without known pairing data and already paired: ignoring
2021-12-13 05:29:53.305 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 05:29:53.317 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 05:29:53.329 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 05:29:53.355 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 05:29:53.369 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639369793210 (ack = true)
2021-12-13 05:29:53.386 - info: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:29:53.387 - debug: homekit-controller.0 (1213619) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":true}
2021-12-13 05:29:53.417 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connectionType changed: BLE (ack = true)
2021-12-13 05:29:53.429 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.id changed: 44:6f:8d:45:c2:a0 (ack = true)
2021-12-13 05:29:53.441 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connected changed: false (ack = true)
2021-12-13 05:29:53.460 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.isPaired changed: false (ack = true)
2021-12-13 05:29:53.472 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.lastDiscovered changed: 1639369793387 (ack = true)
2021-12-13 05:29:53.915 - debug: homekit-controller.0 (1213619) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-13 05:29:53.916 - debug: homekit-controller.0 (1213619) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:29:53.916 - info: homekit-controller.0 (1213619) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:29:53.917 - debug: homekit-controller.0 (1213619) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:29:53.949 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 05:29:53.961 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 05:29:53.980 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 05:29:54.003 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 05:29:54.017 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639369793917 (ack = true)
2021-12-13 05:29:54.030 - debug: homekit-controller.0 (1213619) Discovered BLE device up: 44:6f:8d:45:c2:a0/Eve Degree 053B
2021-12-13 05:29:54.030 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":true}
2021-12-13 05:29:54.031 - info: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:29:54.032 - debug: homekit-controller.0 (1213619) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":true}
2021-12-13 05:29:54.107 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connectionType changed: BLE (ack = true)
2021-12-13 05:29:54.119 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.id changed: 44:6f:8d:45:c2:a0 (ack = true)
2021-12-13 05:29:54.133 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connected changed: false (ack = true)
2021-12-13 05:29:54.155 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.isPaired changed: false (ack = true)
2021-12-13 05:29:54.168 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.lastDiscovered changed: 1639369794033 (ack = true)
2021-12-13 05:30:11.800 - debug: homekit-controller.0 (1213619) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":243,"ack":false,"time":1639369811797},"_id":54081811})
2021-12-13 05:30:11.801 - debug: homekit-controller.0 (1213619) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-44:6F:8D:45:C2:A0","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-13 05:30:11.803 - debug: homekit-controller.0 (1213619) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:30:18.314 - debug: homekit-controller.0 (1213619) Message identify received: {"command":"identify","message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"id":244,"ack":false,"time":1639369818312},"_id":54081812})
2021-12-13 05:30:18.315 - debug: homekit-controller.0 (1213619) Device BLE-44:6F:8D:45:C2:A0: Identify triggered
2021-12-13 05:30:20.787 - debug: homekit-controller.0 (1213619) Response to Command identify: {"success":true,"error":false}
2021-12-13 05:30:20.788 - debug: homekit-controller.0 (1213619) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:30:22.303 - debug: homekit-controller.0 (1213619) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":245,"ack":false,"time":1639369822301},"_id":54081813})
2021-12-13 05:30:22.304 - debug: homekit-controller.0 (1213619) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-44:6F:8D:45:C2:A0","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-13 05:30:22.305 - debug: homekit-controller.0 (1213619) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:30:39.527 - debug: homekit-controller.0 (1213619) Message pairDevice received: {"command":"pairDevice","message":{"deviceId":"BLE-44:6F:8D:45:C2:A0","pin":"629-15-584"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-44:6F:8D:45:C2:A0","pin":"629-15-584"},"id":246,"ack":false,"time":1639369839523},"_id":54081814})
2021-12-13 05:30:42.413 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Start Homekit Device Client initialization
2021-12-13 05:30:49.349 - info: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Successfully paired to device.
2021-12-13 05:30:49.350 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Start Homekit Device Client initialization
2021-12-13 05:30:49.351 - debug: homekit-controller.0 (1213619) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":false}
2021-12-13 05:30:49.368 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.pairWithPin deleted
2021-12-13 05:30:49.397 - debug: homekit-controller.0 (1213619) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.identify deleted
2021-12-13 05:30:49.410 - debug: homekit-controller.0 (1213619) Discovered BLE device changed: 44:6f:8d:45:c2:a0/Eve Degree 053B
2021-12-13 05:30:49.411 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":false}
2021-12-13 05:30:49.411 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Device initialization already in progress ... ignore call
2021-12-13 05:30:49.416 - debug: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Request Accessory information
2021-12-13 05:31:34.429 - info: homekit-controller.0 (1213619) BLE-44:6F:8D:45:C2:A0 Could not initialize device: TimeoutError:
at Object. (/opt/iobroker/node_modules/p-queue/dist/index.js:8:22)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
at Module.load (internal/modules/cjs/loader.js:950:32)
at Function.Module._load (internal/modules/cjs/loader.js:790:12)
at Module.require (internal/modules/cjs/loader.js:974:19)
at require (internal/modules/cjs/helpers.js:93:18)
at Object. (/opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js:38:35)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
2021-12-13 05:31:34.430 - debug: homekit-controller.0 (1213619) Response to Command pairDevice: {"success":true,"error":false}
2021-12-13 05:31:34.432 - debug: homekit-controller.0 (1213619) sendTo "pairDevice" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:31:36.473 - debug: homekit-controller.0 (1213619) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":247,"ack":false,"time":1639369896469},"_id":54081815})
2021-12-13 05:31:36.475 - debug: homekit-controller.0 (1213619) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-44:6F:8D:45:C2:A0","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":true},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-13 05:31:36.476 - debug: homekit-controller.0 (1213619) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:34:09.295 - info: homekit-controller.0 (1213619) Got terminate signal TERMINATE_YOURSELF
2021-12-13 05:34:09.306 - info: homekit-controller.0 (1213619) terminating
2021-12-13 05:34:09.308 - debug: homekit-controller.0 (1213619) Plugin sentry destroyed
2021-12-13 05:34:09.309 - info: homekit-controller.0 (1213619) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2021-12-13 05:35:21.992 - debug: homekit-controller.0 (1213728) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-13 05:35:22.054 - debug: homekit-controller.0 (1213728) Objects client ready ... initialize now
2021-12-13 05:35:22.068 - debug: homekit-controller.0 (1213728) Objects create System PubSub Client
2021-12-13 05:35:22.072 - debug: homekit-controller.0 (1213728) Objects create User PubSub Client
2021-12-13 05:35:22.075 - debug: homekit-controller.0 (1213728) Objects client initialize lua scripts
2021-12-13 05:35:22.117 - debug: homekit-controller.0 (1213728) Objects connected to redis: 127.0.0.1:9001
2021-12-13 05:35:22.131 - debug: homekit-controller.0 (1213728) objectDB connected
2021-12-13 05:35:22.135 - debug: homekit-controller.0 (1213728) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-13 05:35:22.151 - debug: homekit-controller.0 (1213728) States create System PubSub Client
2021-12-13 05:35:22.153 - debug: homekit-controller.0 (1213728) States create User PubSub Client
2021-12-13 05:35:22.168 - debug: homekit-controller.0 (1213728) States connected to redis: 127.0.0.1:6379
2021-12-13 05:35:22.170 - debug: homekit-controller.0 (1213728) statesDB connected
2021-12-13 05:35:22.578 - debug: homekit-controller.0 (1213728) Plugin sentry Initialize Plugin (enabled=true)
2021-12-13 05:35:22.816 - info: homekit-controller.0 (1213728) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-13 05:35:23.082 - debug: homekit-controller.0 (1213728) Init 2 known devices without discovery ...
2021-12-13 05:35:23.084 - info: homekit-controller.0 (1213728) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:35:23.086 - debug: homekit-controller.0 (1213728) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:35:23.098 - debug: homekit-controller.0 (1213728) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-13 05:35:23.135 - debug: homekit-controller.0 (1213728) Discovered IP device up: ab:34:18:95:31:5d/IOBroker 83D1
2021-12-13 05:35:23.136 - debug: homekit-controller.0 (1213728) IP-AB:34:18:95:31:5D Discovered IP device: {"name":"IOBroker 83D1","address":"192.168.12.219","port":34237,"c#":157,"ff":0,"id":"ab:34:18:95:31:5d","md":"not configured","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-13 05:35:23.137 - info: homekit-controller.0 (1213728) IP-AB:34:18:95:31:5D (IOBroker 83D1) found without known pairing data and already paired: ignoring
2021-12-13 05:35:23.141 - debug: homekit-controller.0 (1213728) Discovered IP device up: 0E:6C:79:7B:0B:81/Homebridge 4B75
2021-12-13 05:35:23.141 - debug: homekit-controller.0 (1213728) IP-0E:6C:79:7B:0B:81 Discovered IP device: {"name":"Homebridge 4B75","address":"192.168.12.219","port":51861,"c#":3,"ff":0,"id":"0E:6C:79:7B:0B:81","md":"homebridge","pv":"1.1","s#":1,"sf":0,"ci":2,"availableToPair":false}
2021-12-13 05:35:23.142 - info: homekit-controller.0 (1213728) IP-0E:6C:79:7B:0B:81 (Homebridge 4B75) found without known pairing data and already paired: ignoring
2021-12-13 05:35:23.148 - debug: homekit-controller.0 (1213728) Discovered IP device up: 58:47:6A:51:5B:FE/IN-8003FHD-PoE(c7)
2021-12-13 05:35:23.149 - debug: homekit-controller.0 (1213728) IP-58:47:6A:51:5B:FE Discovered IP device: {"name":"IN-8003FHD-PoE(c7)","address":"192.168.12.199","port":23657,"c#":1,"ff":0,"id":"58:47:6A:51:5B:FE","md":"IN-8003FHD-PoE","pv":"1.0","s#":1,"sf":0,"ci":17,"availableToPair":false}
2021-12-13 05:35:23.149 - info: homekit-controller.0 (1213728) IP-58:47:6A:51:5B:FE (IN-8003FHD-PoE(c7)) found without known pairing data and already paired: ignoring
2021-12-13 05:35:23.162 - debug: homekit-controller.0 (1213728) Discovered BLE device up: 44:6f:8d:45:c2:a0/Eve Degree 053B
2021-12-13 05:35:23.163 - debug: homekit-controller.0 (1213728) BLE-44:6F:8D:45:C2:A0 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":false}
2021-12-13 05:35:23.163 - info: homekit-controller.0 (1213728) BLE-44:6F:8D:45:C2:A0 (Eve Degree 053B) found without known pairing data and already paired: ignoring
2021-12-13 05:35:23.185 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 05:35:23.199 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 05:35:23.212 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 05:35:23.245 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 05:35:23.267 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639370123087 (ack = true)
2021-12-13 05:35:23.287 - info: homekit-controller.0 (1213728) BLE-44:6F:8D:45:C2:A0 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:35:23.288 - debug: homekit-controller.0 (1213728) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"44:6f:8d:45:c2:a0","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"44:6f:8d:45:c2:a0","ci":10,"availableToPair":true}
2021-12-13 05:35:23.321 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connectionType changed: BLE (ack = true)
2021-12-13 05:35:23.333 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.id changed: 44:6f:8d:45:c2:a0 (ack = true)
2021-12-13 05:35:23.353 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.connected changed: false (ack = true)
2021-12-13 05:35:23.393 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.isPaired changed: false (ack = true)
2021-12-13 05:35:23.426 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.info.lastDiscovered changed: 1639370123288 (ack = true)
2021-12-13 05:35:23.446 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.pairWithPin changed: (ack = true)
2021-12-13 05:35:23.460 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-44:6F:8D:45:C2:A0.admin.identify changed: false (ack = true)
2021-12-13 05:35:25.454 - debug: homekit-controller.0 (1213728) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-13 05:35:25.455 - debug: homekit-controller.0 (1213728) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:35:25.456 - info: homekit-controller.0 (1213728) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 05:35:25.457 - debug: homekit-controller.0 (1213728) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":1,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 05:35:25.859 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 05:35:25.872 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 05:35:25.884 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 05:35:25.911 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 05:35:25.925 - debug: homekit-controller.0 (1213728) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639370125457 (ack = true)
2021-12-13 05:35:55.790 - debug: homekit-controller.0 (1213728) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":257,"ack":false,"time":1639370155788},"_id":54081825})
2021-12-13 05:35:55.792 - debug: homekit-controller.0 (1213728) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-44:6F:8D:45:C2:A0","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-13 05:35:55.793 - debug: homekit-controller.0 (1213728) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:36:04.911 - debug: homekit-controller.0 (1213728) Message identify received: {"command":"identify","message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"id":258,"ack":false,"time":1639370164909},"_id":54081826})
2021-12-13 05:36:04.913 - debug: homekit-controller.0 (1213728) Device BLE-44:6F:8D:45:C2:A0: Identify triggered
2021-12-13 05:36:04.917 - debug: homekit-controller.0 (1213728) Response to Command identify: {"success":false,"error":"Cannot identify device BLE-44:6F:8D:45:C2:A0 because of error undefined: Cannot read property 'state' of undefined"}
2021-12-13 05:36:04.918 - debug: homekit-controller.0 (1213728) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:36:09.205 - debug: homekit-controller.0 (1213728) Message identify received: {"command":"identify","message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-44:6F:8D:45:C2:A0"},"id":259,"ack":false,"time":1639370169203},"_id":54081827})
2021-12-13 05:36:09.206 - debug: homekit-controller.0 (1213728) Device BLE-44:6F:8D:45:C2:A0: Identify triggered
2021-12-13 05:36:09.211 - debug: homekit-controller.0 (1213728) Response to Command identify: {"success":false,"error":"Cannot identify device BLE-44:6F:8D:45:C2:A0 because of error undefined: Cannot read property 'state' of undefined"}
2021-12-13 05:36:09.212 - debug: homekit-controller.0 (1213728) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:36:11.983 - debug: homekit-controller.0 (1213728) Message identify received: {"command":"identify","message":{"deviceId":"BLE-ED:B2:DF:50:92:D1"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-ED:B2:DF:50:92:D1"},"id":260,"ack":false,"time":1639370171981},"_id":54081828})
2021-12-13 05:36:11.983 - debug: homekit-controller.0 (1213728) Device BLE-ED:B2:DF:50:92:D1: Identify triggered
2021-12-13 05:36:16.847 - debug: homekit-controller.0 (1213728) Response to Command identify: {"success":true,"error":false}
2021-12-13 05:36:16.848 - debug: homekit-controller.0 (1213728) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 05:36:18.366 - debug: homekit-controller.0 (1213728) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":261,"ack":false,"time":1639370178364},"_id":54081829})
2021-12-13 05:36:18.366 - debug: homekit-controller.0 (1213728) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-44:6F:8D:45:C2:A0","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"IP-AB:34:18:95:31:5D","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IOBroker 83D1","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-0E:6C:79:7B:0B:81","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Homebridge 4B75","discoveredCategory":"Bridge","pairedWithThisInstance":false},{"id":"IP-58:47:6A:51:5B:FE","serviceType":"IP","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"IN-8003FHD-PoE(c7)","discoveredCategory":"IP Camera","pairedWithThisInstance":false}]}
2021-12-13 05:36:18.367 - debug: homekit-controller.0 (1213728) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0

apollon77

@fortune95 Seeeehr komisch ... Bitte nochmal vom Github, manuell restarten und nochmal das ganze bitte

Fortune95

@apollon77
Ich dachte ich hättes es über die Katze und dann Github richtig gemacht....
Hab es jetzt über URL und den Link vom Github Repository kopiert / installiert.

2021-12-13 18:46:19.046 - debug: homekit-controller.0 (1219848) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-13 18:46:19.087 - debug: homekit-controller.0 (1219848) States create System PubSub Client
2021-12-13 18:46:19.089 - debug: homekit-controller.0 (1219848) States create User PubSub Client
2021-12-13 18:46:19.097 - debug: homekit-controller.0 (1219848) States connected to redis: 127.0.0.1:6379
2021-12-13 18:46:19.098 - debug: homekit-controller.0 (1219848) statesDB connected
2021-12-13 18:46:19.427 - debug: homekit-controller.0 (1219848) Plugin sentry Initialize Plugin (enabled=true)
2021-12-13 18:46:19.828 - info: homekit-controller.0 (1219848) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-13 18:46:20.168 - debug: homekit-controller.0 (1219848) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-13 18:46:20.465 - debug: homekit-controller.0 (1219848) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-13 18:46:20.466 - debug: homekit-controller.0 (1219848) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 18:46:20.468 - info: homekit-controller.0 (1219848) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 18:46:20.470 - debug: homekit-controller.0 (1219848) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 18:46:20.509 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:46:20.515 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:46:20.532 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 18:46:20.557 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 18:46:20.612 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 18:46:20.636 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639417580471 (ack = true)
2021-12-13 18:46:20.653 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.pairWithPin changed: (ack = true)
2021-12-13 18:46:20.675 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.identify changed: false (ack = true)
2021-12-13 18:46:21.278 - debug: homekit-controller.0 (1219848) Discovered BLE device up: 8e:94:a7:96:8f:c1/Eve Degree 053B
2021-12-13 18:46:21.279 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":true}
2021-12-13 18:46:21.280 - info: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-13 18:46:21.280 - debug: homekit-controller.0 (1219848) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":true}
2021-12-13 18:46:21.317 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:46:21.323 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:46:21.339 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.id changed: 8e:94:a7:96:8f:c1 (ack = true)
2021-12-13 18:46:21.353 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.connected changed: false (ack = true)
2021-12-13 18:46:21.373 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.isPaired changed: false (ack = true)
2021-12-13 18:46:21.387 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.lastDiscovered changed: 1639417581281 (ack = true)
2021-12-13 18:46:21.400 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.pairWithPin changed: (ack = true)
2021-12-13 18:46:21.412 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.identify changed: false (ack = true)
2021-12-13 18:47:27.605 - debug: homekit-controller.0 (1219848) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":341,"ack":false,"time":1639417647603},"_id":54081916})
2021-12-13 18:47:27.607 - debug: homekit-controller.0 (1219848) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-8E:94:A7:96:8F:C1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-13 18:47:27.608 - debug: homekit-controller.0 (1219848) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 18:47:31.324 - debug: homekit-controller.0 (1219848) Message identify received: {"command":"identify","message":{"deviceId":"BLE-8E:94:A7:96:8F:C1"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-8E:94:A7:96:8F:C1"},"id":342,"ack":false,"time":1639417651322},"_id":54081917})
2021-12-13 18:47:31.325 - debug: homekit-controller.0 (1219848) Device BLE-8E:94:A7:96:8F:C1: Identify triggered
2021-12-13 18:47:34.244 - debug: homekit-controller.0 (1219848) Response to Command identify: {"success":true,"error":false}
2021-12-13 18:47:34.245 - debug: homekit-controller.0 (1219848) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 18:47:35.752 - debug: homekit-controller.0 (1219848) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":343,"ack":false,"time":1639417655751},"_id":54081918})
2021-12-13 18:47:35.753 - debug: homekit-controller.0 (1219848) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-8E:94:A7:96:8F:C1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-13 18:47:35.753 - debug: homekit-controller.0 (1219848) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 18:47:53.203 - debug: homekit-controller.0 (1219848) Message pairDevice received: {"command":"pairDevice","message":{"deviceId":"BLE-8E:94:A7:96:8F:C1","pin":"629-15-584"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-8E:94:A7:96:8F:C1","pin":"629-15-584"},"id":344,"ack":false,"time":1639417673202},"_id":54081919})
2021-12-13 18:47:55.853 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Start Homekit Device Client initialization
2021-12-13 18:48:04.076 - info: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Successfully paired to device.
2021-12-13 18:48:04.077 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Start Homekit Device Client initialization
2021-12-13 18:48:04.078 - debug: homekit-controller.0 (1219848) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":false}
2021-12-13 18:48:04.103 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.pairWithPin deleted
2021-12-13 18:48:04.117 - debug: homekit-controller.0 (1219848) Discovered BLE device changed: 8e:94:a7:96:8f:c1/Eve Degree 053B
2021-12-13 18:48:04.119 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":false}
2021-12-13 18:48:04.121 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Device initialization already in progress ... ignore call
2021-12-13 18:48:04.131 - debug: homekit-controller.0 (1219848) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.identify deleted
2021-12-13 18:48:04.135 - debug: homekit-controller.0 (1219848) Discovered BLE device changed: 8e:94:a7:96:8f:c1/Eve Degree 053B
2021-12-13 18:48:04.136 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":false}
2021-12-13 18:48:04.137 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Device initialization already in progress ... ignore call
2021-12-13 18:48:04.146 - debug: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Request Accessory information
2021-12-13 18:48:49.156 - info: homekit-controller.0 (1219848) BLE-8E:94:A7:96:8F:C1 Could not initialize device: TimeoutError:
at Object. (/opt/iobroker/node_modules/p-queue/dist/index.js:8:22)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
at Module.load (internal/modules/cjs/loader.js:950:32)
at Function.Module._load (internal/modules/cjs/loader.js:790:12)
at Module.require (internal/modules/cjs/loader.js:974:19)
at require (internal/modules/cjs/helpers.js:93:18)
at Object. (/opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js:38:35)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
2021-12-13 18:48:49.157 - debug: homekit-controller.0 (1219848) Response to Command pairDevice: {"success":true,"error":false}
2021-12-13 18:48:49.158 - debug: homekit-controller.0 (1219848) sendTo "pairDevice" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-13 18:50:40.570 - info: homekit-controller.0 (1219848) Got terminate signal TERMINATE_YOURSELF
2021-12-13 18:50:40.574 - info: homekit-controller.0 (1219848) terminating
2021-12-13 18:50:40.576 - debug: homekit-controller.0 (1219848) Plugin sentry destroyed
2021-12-13 18:50:40.577 - info: homekit-controller.0 (1219848) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2021-12-13 18:52:05.335 - debug: homekit-controller.0 (1219909) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-13 18:52:05.388 - debug: homekit-controller.0 (1219909) Objects client ready ... initialize now
2021-12-13 18:52:05.394 - debug: homekit-controller.0 (1219909) Objects create System PubSub Client
2021-12-13 18:52:05.399 - debug: homekit-controller.0 (1219909) Objects create User PubSub Client
2021-12-13 18:52:05.404 - debug: homekit-controller.0 (1219909) Objects client initialize lua scripts
2021-12-13 18:52:05.455 - debug: homekit-controller.0 (1219909) Objects connected to redis: 127.0.0.1:9001
2021-12-13 18:52:05.469 - debug: homekit-controller.0 (1219909) objectDB connected
2021-12-13 18:52:05.473 - debug: homekit-controller.0 (1219909) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-13 18:52:05.489 - debug: homekit-controller.0 (1219909) States create System PubSub Client
2021-12-13 18:52:05.491 - debug: homekit-controller.0 (1219909) States create User PubSub Client
2021-12-13 18:52:05.505 - debug: homekit-controller.0 (1219909) States connected to redis: 127.0.0.1:6379
2021-12-13 18:52:05.507 - debug: homekit-controller.0 (1219909) statesDB connected
2021-12-13 18:52:05.853 - debug: homekit-controller.0 (1219909) Plugin sentry Initialize Plugin (enabled=true)
2021-12-13 18:52:06.096 - info: homekit-controller.0 (1219909) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-13 18:52:06.351 - debug: homekit-controller.0 (1219909) Init 2 known devices without discovery ...
2021-12-13 18:52:06.353 - info: homekit-controller.0 (1219909) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 18:52:06.354 - debug: homekit-controller.0 (1219909) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 18:52:06.367 - debug: homekit-controller.0 (1219909) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-13 18:52:06.405 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:52:06.417 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 18:52:06.431 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 18:52:06.461 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 18:52:06.473 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639417926356 (ack = true)
2021-12-13 18:52:06.497 - info: homekit-controller.0 (1219909) BLE-8E:94:A7:96:8F:C1 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-13 18:52:06.499 - debug: homekit-controller.0 (1219909) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":true}
2021-12-13 18:52:06.538 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:52:06.561 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.id changed: 8e:94:a7:96:8f:c1 (ack = true)
2021-12-13 18:52:06.579 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.connected changed: false (ack = true)
2021-12-13 18:52:06.604 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.isPaired changed: false (ack = true)
2021-12-13 18:52:06.625 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.info.lastDiscovered changed: 1639417926501 (ack = true)
2021-12-13 18:52:06.668 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.pairWithPin changed: (ack = true)
2021-12-13 18:52:06.686 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-8E:94:A7:96:8F:C1.admin.identify changed: false (ack = true)
2021-12-13 18:52:06.907 - debug: homekit-controller.0 (1219909) Discovered BLE device up: 8e:94:a7:96:8f:c1/Eve Degree 053B
2021-12-13 18:52:06.908 - debug: homekit-controller.0 (1219909) BLE-8E:94:A7:96:8F:C1 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"8e:94:a7:96:8f:c1","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"8e:94:a7:96:8f:c1","ci":10,"availableToPair":false}
2021-12-13 18:52:06.909 - info: homekit-controller.0 (1219909) BLE-8E:94:A7:96:8F:C1 (Eve Degree 053B) found without known pairing data and already paired: ignoring
2021-12-13 18:52:07.400 - debug: homekit-controller.0 (1219909) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-13 18:52:07.401 - debug: homekit-controller.0 (1219909) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 18:52:07.401 - info: homekit-controller.0 (1219909) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-13 18:52:07.402 - debug: homekit-controller.0 (1219909) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-13 18:52:07.443 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-13 18:52:07.456 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-13 18:52:07.468 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-13 18:52:07.494 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-13 18:52:07.510 - debug: homekit-controller.0 (1219909) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639417927402 (ack = true)
2021-12-13 18:53:05.475 - debug: homekit-controller.0 (1219909) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":359,"ack":false,"time":1639417985472},"_id":54081934})
2021-12-13 18:53:05.476 - debug: homekit-controller.0 (1219909) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-8E:94:A7:96:8F:C1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-13 18:53:05.478 - debug: homekit-controller.0 (1219909) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0

apollon77

@fortune95 Meehhh ... da war was nicht aktuell auf GitHub ... Mega sorry ... Nochmal bitte

Fortune95

@apollon77

Hi, erstmal velen Dank für das Forschen.

Hier das neue Log:

2021-12-14 05:46:23.426 - info: homekit-controller.0 (1225088) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2021-12-14 05:46:27.881 - debug: homekit-controller.0 (1225142) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-14 05:46:27.984 - debug: homekit-controller.0 (1225142) Objects client ready ... initialize now
2021-12-14 05:46:28.013 - debug: homekit-controller.0 (1225142) Objects create System PubSub Client
2021-12-14 05:46:28.016 - debug: homekit-controller.0 (1225142) Objects create User PubSub Client
2021-12-14 05:46:28.018 - debug: homekit-controller.0 (1225142) Objects client initialize lua scripts
2021-12-14 05:46:28.070 - debug: homekit-controller.0 (1225142) Objects connected to redis: 127.0.0.1:9001
2021-12-14 05:46:28.108 - debug: homekit-controller.0 (1225142) objectDB connected
2021-12-14 05:46:28.111 - debug: homekit-controller.0 (1225142) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-14 05:46:28.123 - debug: homekit-controller.0 (1225142) States create System PubSub Client
2021-12-14 05:46:28.125 - debug: homekit-controller.0 (1225142) States create User PubSub Client
2021-12-14 05:46:28.137 - debug: homekit-controller.0 (1225142) States connected to redis: 127.0.0.1:6379
2021-12-14 05:46:28.138 - debug: homekit-controller.0 (1225142) statesDB connected
2021-12-14 05:46:28.919 - debug: homekit-controller.0 (1225142) Plugin sentry Initialize Plugin (enabled=true)
2021-12-14 05:46:29.194 - info: homekit-controller.0 (1225142) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-14 05:46:29.444 - debug: homekit-controller.0 (1225142) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-14 05:46:29.992 - debug: homekit-controller.0 (1225142) Discovered BLE device up: d9:b3:d5:c9:e2:a9/Eve Degree 053B
2021-12-14 05:46:29.993 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":true}
2021-12-14 05:46:29.995 - info: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-14 05:46:29.996 - debug: homekit-controller.0 (1225142) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":true}
2021-12-14 05:46:30.047 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.connectionType changed: BLE (ack = true)
2021-12-14 05:46:30.052 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.connectionType changed: BLE (ack = true)
2021-12-14 05:46:30.066 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.id changed: d9:b3:d5:c9:e2:a9 (ack = true)
2021-12-14 05:46:30.083 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.connected changed: false (ack = true)
2021-12-14 05:46:30.122 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.isPaired changed: false (ack = true)
2021-12-14 05:46:30.135 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.lastDiscovered changed: 1639457189998 (ack = true)
2021-12-14 05:46:30.150 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.pairWithPin changed: (ack = true)
2021-12-14 05:46:30.164 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.identify changed: false (ack = true)
2021-12-14 05:46:30.512 - debug: homekit-controller.0 (1225142) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-14 05:46:30.513 - debug: homekit-controller.0 (1225142) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-14 05:46:30.513 - info: homekit-controller.0 (1225142) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-14 05:46:30.514 - debug: homekit-controller.0 (1225142) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-14 05:46:30.554 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-14 05:46:30.559 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-14 05:46:30.572 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-14 05:46:30.586 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-14 05:46:30.606 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-14 05:46:30.619 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639457190514 (ack = true)
2021-12-14 05:46:30.637 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.pairWithPin changed: (ack = true)
2021-12-14 05:46:30.650 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.identify changed: false (ack = true)
2021-12-14 05:46:58.424 - debug: homekit-controller.0 (1225142) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":410,"ack":false,"time":1639457218420},"_id":54081989})
2021-12-14 05:46:58.426 - debug: homekit-controller.0 (1225142) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-D9:B3:D5:C9:E2:A9","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-14 05:46:58.428 - debug: homekit-controller.0 (1225142) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-14 05:47:02.996 - debug: homekit-controller.0 (1225142) Message identify received: {"command":"identify","message":{"deviceId":"BLE-D9:B3:D5:C9:E2:A9"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-D9:B3:D5:C9:E2:A9"},"id":411,"ack":false,"time":1639457222994},"_id":54081990})
2021-12-14 05:47:02.997 - debug: homekit-controller.0 (1225142) Device BLE-D9:B3:D5:C9:E2:A9: Identify triggered
2021-12-14 05:47:05.734 - debug: homekit-controller.0 (1225142) Response to Command identify: {"success":true,"error":false}
2021-12-14 05:47:05.735 - debug: homekit-controller.0 (1225142) sendTo "identify" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-14 05:47:07.242 - debug: homekit-controller.0 (1225142) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":412,"ack":false,"time":1639457227240},"_id":54081991})
2021-12-14 05:47:07.245 - debug: homekit-controller.0 (1225142) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-D9:B3:D5:C9:E2:A9","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-14 05:47:07.246 - debug: homekit-controller.0 (1225142) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-14 05:47:28.884 - debug: homekit-controller.0 (1225142) Message pairDevice received: {"command":"pairDevice","message":{"deviceId":"BLE-D9:B3:D5:C9:E2:A9","pin":"629-15-584"},"from":"system.adapter.admin.0","callback":{"message":{"deviceId":"BLE-D9:B3:D5:C9:E2:A9","pin":"629-15-584"},"id":413,"ack":false,"time":1639457248883},"_id":54081992})
2021-12-14 05:47:32.502 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Start Homekit Device Client initialization
2021-12-14 05:47:39.499 - info: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Successfully paired to device: {"AccessoryPairingID":"44393a42333a44353a43393a45323a4139","AccessoryLTPK":"becc10cfe0e46c317585dee9831feed81f420226be1bfe25eddc586d5f46e3b7","iOSDevicePairingID":"30303235343564382d373936622d343837322d383230612d326238666163313536656536","iOSDeviceLTSK":"e2cb7d27acdcd9daece11d8c733608b247f136386a6cb4964832b6a796bad95e1316c29f6ffb03bc5d76fa9636994ec13ec5b4f84c2cab4a37ab41a677e0956f","iOSDeviceLTPK":"1316c29f6ffb03bc5d76fa9636994ec13ec5b4f84c2cab4a37ab41a677e0956f"}
2021-12-14 05:47:39.503 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Start Homekit Device Client initialization
2021-12-14 05:47:39.507 - debug: homekit-controller.0 (1225142) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":false}
2021-12-14 05:47:39.540 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.pairWithPin deleted
2021-12-14 05:47:39.547 - debug: homekit-controller.0 (1225142) Discovered BLE device changed: d9:b3:d5:c9:e2:a9/Eve Degree 053B
2021-12-14 05:47:39.551 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":false}
2021-12-14 05:47:39.552 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Device initialization already in progress ... ignore call
2021-12-14 05:47:39.595 - debug: homekit-controller.0 (1225142) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.identify deleted
2021-12-14 05:47:39.611 - debug: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Request Accessory information
2021-12-14 05:48:24.622 - info: homekit-controller.0 (1225142) BLE-D9:B3:D5:C9:E2:A9 Could not initialize device: TimeoutError:
at Object. (/opt/iobroker/node_modules/p-queue/dist/index.js:8:22)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
at Module.load (internal/modules/cjs/loader.js:950:32)
at Function.Module._load (internal/modules/cjs/loader.js:790:12)
at Module.require (internal/modules/cjs/loader.js:974:19)
at require (internal/modules/cjs/helpers.js:93:18)
at Object. (/opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js:38:35)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
2021-12-14 05:48:24.623 - debug: homekit-controller.0 (1225142) Response to Command pairDevice: {"success":true,"error":false}
2021-12-14 05:48:24.623 - debug: homekit-controller.0 (1225142) sendTo "pairDevice" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-14 05:50:18.290 - info: homekit-controller.0 (1225142) Got terminate signal TERMINATE_YOURSELF
2021-12-14 05:50:18.292 - info: homekit-controller.0 (1225142) terminating
2021-12-14 05:50:18.293 - debug: homekit-controller.0 (1225142) Plugin sentry destroyed
2021-12-14 05:50:18.295 - info: homekit-controller.0 (1225142) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2021-12-14 05:52:10.845 - debug: homekit-controller.0 (1225204) Redis Objects: Use Redis connection: 127.0.0.1:9001
2021-12-14 05:52:10.890 - debug: homekit-controller.0 (1225204) Objects client ready ... initialize now
2021-12-14 05:52:10.897 - debug: homekit-controller.0 (1225204) Objects create System PubSub Client
2021-12-14 05:52:10.923 - debug: homekit-controller.0 (1225204) Objects create User PubSub Client
2021-12-14 05:52:10.926 - debug: homekit-controller.0 (1225204) Objects client initialize lua scripts
2021-12-14 05:52:10.986 - debug: homekit-controller.0 (1225204) Objects connected to redis: 127.0.0.1:9001
2021-12-14 05:52:11.012 - debug: homekit-controller.0 (1225204) objectDB connected
2021-12-14 05:52:11.016 - debug: homekit-controller.0 (1225204) Redis States: Use Redis connection: 127.0.0.1:6379
2021-12-14 05:52:11.030 - debug: homekit-controller.0 (1225204) States create System PubSub Client
2021-12-14 05:52:11.032 - debug: homekit-controller.0 (1225204) States create User PubSub Client
2021-12-14 05:52:11.065 - debug: homekit-controller.0 (1225204) States connected to redis: 127.0.0.1:6379
2021-12-14 05:52:11.066 - debug: homekit-controller.0 (1225204) statesDB connected
2021-12-14 05:52:11.379 - debug: homekit-controller.0 (1225204) Plugin sentry Initialize Plugin (enabled=true)
2021-12-14 05:52:11.639 - info: homekit-controller.0 (1225204) starting. Version 0.3.3 in /opt/iobroker/node_modules/iobroker.homekit-controller, node: v14.18.2, js-controller: 3.3.21
2021-12-14 05:52:11.906 - debug: homekit-controller.0 (1225204) Init 2 known devices without discovery ...
2021-12-14 05:52:11.907 - debug: homekit-controller.0 (1225204) Init BLE-D9:B3:D5:C9:E2:A9 as known device
2021-12-14 05:52:11.908 - info: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 (Eve Degree 053B) found without pairing data but available for pairing: Create basic objects
2021-12-14 05:52:11.910 - debug: homekit-controller.0 (1225204) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":1,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":true}
2021-12-14 05:52:11.917 - debug: homekit-controller.0 (1225204) state homekit-controller.0.info.connection changed: false (ack = true)
2021-12-14 05:52:11.954 - debug: homekit-controller.0 (1225204) Discovered BLE device up: d9:b3:d5:c9:e2:a9/Eve Degree 053B
2021-12-14 05:52:11.956 - debug: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":2,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":false}
2021-12-14 05:52:11.956 - debug: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Device initialization already in progress ... ignore call
2021-12-14 05:52:11.972 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.connectionType changed: BLE (ack = true)
2021-12-14 05:52:11.985 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.id changed: d9:b3:d5:c9:e2:a9 (ack = true)
2021-12-14 05:52:11.999 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.connected changed: false (ack = true)
2021-12-14 05:52:12.020 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.isPaired changed: false (ack = true)
2021-12-14 05:52:12.034 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.info.lastDiscovered changed: 1639457531911 (ack = true)
2021-12-14 05:52:12.063 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.pairWithPin changed: (ack = true)
2021-12-14 05:52:12.083 - debug: homekit-controller.0 (1225204) Init BLE-ED:B2:DF:50:92:D1 as known device
2021-12-14 05:52:12.085 - info: homekit-controller.0 (1225204) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-14 05:52:12.087 - debug: homekit-controller.0 (1225204) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-14 05:52:12.091 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.identify changed: false (ack = true)
2021-12-14 05:52:12.129 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-14 05:52:12.142 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-14 05:52:12.156 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-14 05:52:12.179 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-14 05:52:12.191 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639457532088 (ack = true)
2021-12-14 05:52:12.358 - debug: homekit-controller.0 (1225204) Discovered BLE device up: ed:b2:df:50:92:d1/Eve Room 632D
2021-12-14 05:52:12.359 - debug: homekit-controller.0 (1225204) BLE-ED:B2:DF:50:92:D1 Discovered BLE device: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-14 05:52:12.359 - info: homekit-controller.0 (1225204) BLE-ED:B2:DF:50:92:D1 (Eve Room 632D) found without pairing data but available for pairing: Create basic objects
2021-12-14 05:52:12.360 - debug: homekit-controller.0 (1225204) Service: {"name":"Eve Room 632D","CoID":76,"TY":6,"AIL":49,"SF":1,"DeviceID":"ed:b2:df:50:92:d1","ACID":10,"GSN":2,"CN":1,"CV":2,"c#":1,"id":"ed:b2:df:50:92:d1","ci":10,"availableToPair":true}
2021-12-14 05:52:12.389 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connectionType changed: BLE (ack = true)
2021-12-14 05:52:12.401 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.id changed: ed:b2:df:50:92:d1 (ack = true)
2021-12-14 05:52:12.413 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.connected changed: false (ack = true)
2021-12-14 05:52:12.434 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.admin.isPaired changed: false (ack = true)
2021-12-14 05:52:12.447 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-ED:B2:DF:50:92:D1.info.lastDiscovered changed: 1639457532360 (ack = true)
2021-12-14 05:52:38.784 - debug: homekit-controller.0 (1225204) Message getDiscoveredDevices received: {"command":"getDiscoveredDevices","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":421,"ack":false,"time":1639457558781},"_id":54082000})
2021-12-14 05:52:38.785 - debug: homekit-controller.0 (1225204) Response to Command getDiscoveredDevices: {"success":true,"error":false,"devices":[{"id":"BLE-ED:B2:DF:50:92:D1","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":true,"discoveredName":"Eve Room 632D","discoveredCategory":"Sensor","pairedWithThisInstance":false},{"id":"BLE-D9:B3:D5:C9:E2:A9","serviceType":"BLE","connected":false,"discovered":true,"availableToPair":false,"discoveredName":"Eve Degree 053B","discoveredCategory":"Sensor","pairedWithThisInstance":false}]}
2021-12-14 05:52:38.786 - debug: homekit-controller.0 (1225204) sendTo "getDiscoveredDevices" to system.adapter.admin.0 from system.adapter.homekit-controller.0
2021-12-14 05:55:38.779 - debug: homekit-controller.0 (1225204) Discovered BLE device changed: d9:b3:d5:c9:e2:a9/Eve Degree 053B
2021-12-14 05:55:38.779 - debug: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Discovered BLE device: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":3,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":false}
2021-12-14 05:55:38.781 - info: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Found stored Pairing data, try it ...
2021-12-14 05:55:38.782 - debug: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Start Homekit Device Client initialization
2021-12-14 05:55:38.784 - debug: homekit-controller.0 (1225204) Service: {"name":"Eve Degree 053B","CoID":76,"TY":6,"AIL":49,"SF":0,"DeviceID":"d9:b3:d5:c9:e2:a9","ACID":10,"GSN":3,"CN":3,"CV":2,"c#":3,"id":"d9:b3:d5:c9:e2:a9","ci":10,"availableToPair":false}
2021-12-14 05:55:38.810 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.pairWithPin deleted
2021-12-14 05:55:38.845 - debug: homekit-controller.0 (1225204) state homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9.admin.identify deleted
2021-12-14 05:55:38.861 - debug: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Request Accessory information
2021-12-14 05:56:23.872 - info: homekit-controller.0 (1225204) BLE-D9:B3:D5:C9:E2:A9 Could not initialize device: TimeoutError:
at Object. (/opt/iobroker/node_modules/p-queue/dist/index.js:8:22)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
at Module.load (internal/modules/cjs/loader.js:950:32)
at Function.Module._load (internal/modules/cjs/loader.js:790:12)
at Module.require (internal/modules/cjs/loader.js:974:19)
at require (internal/modules/cjs/helpers.js:93:18)
at Object. (/opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js:38:35)
at Module._compile (internal/modules/cjs/loader.js:1085:14)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)

apollon77

@fortune95 Also die realität ist gerade das das gerät nicht auf die Anfrage antwortet seine Accessory Daten rauszurücken.

Dann fangen wir jetzt mal etwas mehr tricky an. Jetzt brauchen wir richtiges Debug:

Adapterinstanz stoppen im Admin
mach eine Shell auf auf den rechner
DEBUG=hap* node /opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js 0 --debug --logs
Dann im Admin in de Adapter Konfig gehen (adapter nicht dort nochmal starten ... der läuft schon auf der Kommendozeile) und einmal pairen und warten bis wieder fehler kommt und so
Dann Log von der Shell bitte posten. Da sollte mehr drin sein. gern als File hier anhängen
Zusätzlich bitte im Admin unter Objekte mal das Device Objekt siuchen von dem Gerät und da rechts auf den Stift klicken und auch das JSON posten was da angezeigt wird unter "Raw" oder so

Uuuuund -weil ich das noch nciht gefragt hatten - das BLE Device/Stick wird nicht zufällig noch von nem anderen Adapter genutzt oder so? Also bitte sicherstellen das das BLE Device "frei" ist für den Adapter! Also kein radar2-Adapter oder so der den gleichen BLE nutzen will!

Fortune95

@apollon77 sagte in Test Alpha Homekit-Controller 0.0.x:

@fortune95 Also die realität ist gerade das das gerät nicht auf die Anfrage antwortet seine Accessory Daten rauszurücken.

Dann fangen wir jetzt mal etwas mehr tricky an. Jetzt brauchen wir richtiges Debug:

Adapterinstanz stoppen im Admin

mach eine Shell auf auf den rechner

DEBUG=hap* node /opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js

Dann im Admin in de Adapter Konfig gehen (adapte rnicht dort nochmal starten ... der läuft scho auf der Kommendozeile) und einmal pairen und warten bis wieder fehler kommt und so

Dann Log von der Shell bitte posten. Da sollte mehr drin sein. gern als File hier anhängen

Zusätzlich bitte im Admin unter Objekte mal das Device Objekt siuchen von dem Gerät und da rechts auf den Stift klicken und auch das JSON posten was da angezeigt wird unter "Raw" oder so

Uuuuund -weil ich das noch nciht gefragt hatten - das BLE Device/Stick wird nicht zufällig noch von nem anderen Adapter genutzt oder so? Also bitte sicherstellen das das BLE Device "frei" ist für den Adapter! Also kein radar2-Adapter oder so der den gleichen BLE nutzen will!

Hi, wo finde ich das Log in der Shell?
hier: /opt/iobroker/log ?

Fortune95

@fortune95

Es wurden 2 Geräte / Objekt Ordner von ein und der selben Hardware angelegt?

JSON von gerade:

{
  "type": "folder",
  "common": {
    "name": "Eve Degree 053B (BLE-D3:60:55:4A:CB:86)"
  },
  "native": {
    "serviceType": "BLE",
    "id": "BLE-D3:60:55:4A:CB:86",
    "lastService": {
      "name": "Eve Degree 053B",
      "CoID": 76,
      "TY": 6,
      "AIL": 49,
      "SF": 1,
      "DeviceID": "d3:60:55:4a:cb:86",
      "ACID": 10,
      "GSN": 1,
      "CN": 3,
      "CV": 2,
      "c#": 3,
      "id": "d3:60:55:4a:cb:86",
      "ci": 10,
      "availableToPair": true
    }
  },
  "from": "system.adapter.homekit-controller.0",
  "user": "system.user.admin",
  "ts": 1639497420956,
  "_id": "homekit-controller.0.BLE-D3:60:55:4A:CB:86",
  "acl": {
    "object": 1636,
    "owner": "system.user.admin",
    "ownerGroup": "system.group.administrator"
  }
}

JSON von heute morgen:

{
  "type": "folder",
  "common": {
    "name": "Eve Degree 053B (BLE-D9:B3:D5:C9:E2:A9)"
  },
  "native": {
    "serviceType": "BLE",
    "id": "BLE-D9:B3:D5:C9:E2:A9",
    "lastService": {
      "name": "Eve Degree 053B",
      "CoID": 76,
      "TY": 6,
      "AIL": 49,
      "SF": 1,
      "DeviceID": "d9:b3:d5:c9:e2:a9",
      "ACID": 10,
      "GSN": 1,
      "CN": 3,
      "CV": 2,
      "c#": 3,
      "id": "d9:b3:d5:c9:e2:a9",
      "ci": 10,
      "availableToPair": true
    }
  },
  "from": "system.adapter.homekit-controller.0",
  "user": "system.user.admin",
  "ts": 1639497418786,
  "_id": "homekit-controller.0.BLE-D9:B3:D5:C9:E2:A9",
  "acl": {
    "object": 1636,
    "owner": "system.user.admin",
    "ownerGroup": "system.group.administrator"
  }
}

Fortune95

@apollon77
Google ist mein Freund...glaube ich jedenfalls....
Nachdem ich dem Debug Befehl noch etwas eränzt hatte, konnte ich schon mehr in der Shell sehen:

DEBUG=hap* node /opt/iobroker/node_modules/iobroker.homekit-controller/build/main.js 0 --debug

Shell_Debug_Eve_Degree.txt

JSON vom Device Objekt:

{
  "type": "folder",
  "common": {
    "name": "Eve Degree 053B (BLE-7A:16:1F:F5:47:C1)"
  },
  "native": {
    "serviceType": "BLE",
    "id": "BLE-7A:16:1F:F5:47:C1",
    "lastService": {
      "name": "Eve Degree 053B",
      "CoID": 76,
      "TY": 6,
      "AIL": 49,
      "SF": 1,
      "DeviceID": "7a:16:1f:f5:47:c1",
      "ACID": 10,
      "GSN": 1,
      "CN": 3,
      "CV": 2,
      "c#": 3,
      "id": "7a:16:1f:f5:47:c1",
      "ci": 10,
      "availableToPair": true
    }
  },
  "from": "system.adapter.homekit-controller.0",
  "user": "system.user.admin",
  "ts": 1639499241143,
  "_id": "homekit-controller.0.BLE-7A:16:1F:F5:47:C1",
  "acl": {
    "object": 1636,
    "owner": "system.user.admin",
    "ownerGroup": "system.group.administrator"
  }
}

apollon77

@fortune95 Ich denke das das Gerät eine neue "Mac"/ID bekommt sobald Du es resetten musstest 8weil paired aber tat nicht mehr)?

Fortune95

@apollon77
Kannst du mit dem Debug Log was anfangen?

apollon77

@fortune95 Ja ... ich denke es hat waas damit zu tun

hap-controller:gatt-connection connect peripheral +78ms
hap-controller:gatt-connection Peripheral disconnected +10s
noble warning: unknown peripheral d9586108e7d9
noble warning: unknown peripheral d9586108e7d9

Scheinbar ändert sich das BLE peripheral ... hab da ne idee... muss mal drüber schlafen. Nur komisch das das bei meinem Eve Energy nicht der Fall war.
Ich verstehe nur nicht warum es dann bei den anderen versuchen nach dem Restart nicht tat ... Aber kriegen wir zusammen raus hoffe ich

EDIT: Kannste mal nochmal pairen (falls nicht schon noch gepairt) Und dann an der Shell den Adapter mit CTRL-C beenden und neu starten und mir auch das Logo vom zweiten lauf schicken?

Fortune95

@apollon77
War noch gepairt.

Shell_Debug_Eve_Degree_2.txt

apollon77

Also irgendwie bricht die BLE Verbindung zusammen und dann blöd. Was ist denn das für ein BLE? Irgendwas Build in im Rechner? Was genau? Oder ein USB Stick?

Fortune95

@apollon77
Ist Build in vom raspberry Pi 4. Hab auf einem separaten PI 4 mal nur diesen Adapter getestet mit gleichem Ergebnis.

apollon77

@fortune95 Alle Treiber und Kernel aktuell? Hast du vllt mal nen BLE USB Stick noch oder so den du versuchen kannst?

Kannst Du mal den Raspi nach dem Pair rebooten (vllt am besten als shutdown mit Strom weg) ... danach gleiches Ergebnis?

Thomas Braun

@fortune95

rfkill

sagt?

NEWS

Test Alpha Homekit-Controller 0.0.x

Support us

869
Online

31.6k
Users

79.4k
Topics

1.3m
Posts

NEWS

Test Alpha Homekit-Controller 0.0.x

Support us

869Online

31.6kUsers

79.4kTopics

1.3mPosts

869
Online

31.6k
Users

79.4k
Topics

1.3m
Posts