| 26 Dec 2024 |
 spacekitteh | has anyone successfully used matter-server to commission a new device? | 05:56:43 |
| spacekitteh | specifically, with bluetooth | 05:57:20 |
| spacekitteh | it can't seem to connect to dbus | 05:58:08 |
| spacekitteh | Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.750 (MainThread) INFO [matter_server.server.device_controller] Starting Matter commissioning with code using Node ID 14.
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.750 (Dummy-2) CHIP_PROGRESS [chip.native.CTL] Setting thread operational dataset from parameters
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.750 (Dummy-2) CHIP_PROGRESS [chip.native.CTL] Setting attestation nonce to random value
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.750 (Dummy-2) CHIP_PROGRESS [chip.native.CTL] Setting CSR nonce to random value
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_DETAIL [chip.native.CTL] Stopping commissioning discovery over DNS-SD
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_PROGRESS [chip.native.CTL] Starting commissioning discovery over BLE
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_PROGRESS [chip.native.CTL] Starting commissioning discovery over DNS-SD
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on enp1s0: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (ThreadPoolExecutor-0_0) DEBUG [matter_server.server.storage] Saved data to persistent storage
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on vm-otbr: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.751 (Dummy-2) CHIP_DETAIL [chip.native.DIS] mDNS broadcast had only partial success: 2 successes and 2 failures.
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.752 (Dummy-3) CHIP_ERROR [chip.native.DL] FAIL: Get D-Bus system bus: Unable to create socket: Address family not supported by protocol
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.752 (Dummy-2) CHIP_ERROR [chip.native.DL] Disabling CHIPoBLE service due to error: src/platform/Linux/bluez/BluezObjectManager.cpp:112: CHIP Error 0x000000AC: Internal error
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.752 (Dummy-2) CHIP_ERROR [chip.native.CTL] Commissioning discovery over BLE failed: src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.752 (Dummy-2) CHIP_ERROR [chip.native.-] src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable at src/controller/SetUpCodePairer.cpp:324
Dec 26 15:23:07 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:07.752 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on enp1s0: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:07 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:07.752 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on vm-otbr: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:07 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:07.752 (Dummy-2) CHIP_DETAIL [chip.native.DIS] mDNS broadcast had only partial success: 2 successes and 2 failures.
Dec 26 15:23:09 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:09.754 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on enp1s0: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:09 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:09.754 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on vm-otbr: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:09 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:09.754 (Dummy-2) CHIP_DETAIL [chip.native.DIS] mDNS broadcast had only partial success: 2 successes and 2 failures.
Dec 26 15:23:13 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:13.757 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on enp1s0: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:13 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:13.757 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on vm-otbr: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:13 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:13.757 (Dummy-2) CHIP_DETAIL [chip.native.DIS] mDNS broadcast had only partial success: 2 successes and 2 failures.
Dec 26 15:23:21 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:21.766 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on enp1s0: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:21 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:21.766 (Dummy-2) CHIP_DETAIL [chip.native.DIS] Warning: Attempt to mDNS broadcast failed on vm-otbr: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000063: Cannot assign requested address
Dec 26 15:23:21 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:21.766 (Dummy-2) CHIP_DETAIL [chip.native.DIS] mDNS broadcast had only partial success: 2 successes and 2 failures.
Dec 26 15:23:36 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:36.767 (Dummy-2) CHIP_ERROR [chip.native.CTL] Discovery timed out
Dec 26 15:23:36 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:36.767 (Dummy-2) CHIP_DETAIL [chip.native.CTL] Stopping commissioning discovery over DNS-SD
Dec 26 15:23:36 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:36.767 (Dummy-2) CHIP_ERROR [chip.native.ZCL] Secure Pairing Failed
Dec 26 15:23:36 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:36.767 (Dummy-2) WARNING [chip.ChipDeviceCtrl] Failed to establish secure session to device: src/controller/python/ChipDeviceController-ScriptDevicePairingDelegate.cpp:89: CHIP Error 0x00000003: Incorrect state
Dec 26 15:23:36 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:36.767 (MainThread) ERROR [matter_server.server.client_handler] [140121862675472] Error while handling: commission_with_code: Commission with code failed for node 14.
| 05:58:15 |
| spacekitteh | (ignore the mDNS stuff) | 05:58:36 |
| spacekitteh | also hexa matter-server 7.0.0 was released a few days ago fyi | 07:37:08 |
 @hexa:lossy.network | Will be updated when home-assistang wants it | 08:08:43 |
| @hexa:lossy.network | * | 08:08:51 |
| spacekitteh | oh :( | 10:36:10 |
| @hexa:lossy.network | where does it say that? | 10:39:25 |
| spacekitteh |
Dec 26 15:23:06 cluster-fancynode matter-server[39263]: 2024-12-26 15:23:06.752 (Dummy-3) CHIP_ERROR [chip.native.DL] FAIL: Get D-Bus system bus: Unable to create socket: Address family not supported by protocol
| 10:39:49 |
| @hexa:lossy.network | ok, likely missing AF_UNIX permissions then | 10:40:02 |
| spacekitteh | ahh | 10:40:10 |
| spacekitteh | i tried adding AF_BLUETOOTH but that didn't fix it | 10:40:19 |
 K900 | AF_BLUETOOTH is for raw Bluetooth sockets which you should not need unless you're rewriting BlueZ | 10:41:22 |
| spacekitteh | oh good point | 10:42:06 |
| spacekitteh | well the error has changed now | 10:51:37 |
| spacekitteh | IP_ERROR [chip.native.CTL] Commissioning discovery over BLE failed: src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable
IP_ERROR [chip.native.-] src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable at src/controller/SetUpCodePairer.cpp:324
RNING [chip.ChipDeviceCtrl] Failed to establish secure session to device: src/controller/python/ChipDeviceController-ScriptDevicePairingDelegate.cpp:89: CHIP Error 0x00000003: Incorre>
ERROR [matter_server.server.client_handler] [140513889796752] Error while handling: commission_with_code: Commission with code failed for node 18.
| 10:51:48 |
| @hexa:lossy.network | that's good, new errors imply progress | 10:52:08 |
| @hexa:lossy.network | do you have bluez running? | 10:52:28 |
| @hexa:lossy.network | aka bluetooth.service | 10:52:35 |
| spacekitteh | i do yeah | 10:52:39 |
| @hexa:lossy.network | anything interesting in its logs? | 10:52:45 |
| spacekitteh | *
Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.CTL] Commissioning discovery over BLE failed: src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable
Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.-] src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable at src/controller/SetUpCodePairer.cpp:324
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.387 (Dummy-2) WARNING [chip.ChipDeviceCtrl] Failed to establish secure session to device: src/controller/python/ChipDeviceController-ScriptDevicePairingDelegate.cpp:89: CHIP Error 0x00000003: Incorrect state
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.388 (MainThread) ERROR [matter_server.server.client_handler] [140513889796752] Error while handling: commission_with_code: Commission with code failed for node 18.
| 10:53:21 |
| spacekitteh | *
Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.CTL] Commissioning discovery over BLE failed: src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable
Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.-] src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable at src/controller/SetUpCodePairer.cpp:324
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.387 (Dummy-2) WARNING [chip.ChipDeviceCtrl] Failed to establish secure session to device: src/controller/python/ChipDeviceController-ScriptDevicePairingDelegate.cpp:89: CHIP Error 0x00000003: Incorrect state
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.388 (MainThread) ERROR [matter_server.server.client_handler] [140513889796752] Error while handling: commission_with_code: Commission with code failed for node 18.
| 10:53:27 |
| spacekitteh | * Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.CTL] Commissioning discovery over BLE failed: src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable
Dec 26 20:43:50 cluster-fancynode matter-server[83864]: 2024-12-26 20:43:50.373 (Dummy-2) CHIP_ERROR [chip.native.-] src/platform/Linux/BLEManagerImpl.cpp:743: Ble Error 0x00000401: BLE adapter unavailable at src/controller/SetUpCodePairer.cpp:324
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.387 (Dummy-2) WARNING [chip.ChipDeviceCtrl] Failed to establish secure session to device: src/controller/python/ChipDeviceController-ScriptDevicePairingDelegate.cpp:89: CHIP Error 0x00000003: Incorrect state
Dec 26 20:44:20 cluster-fancynode matter-server[83864]: 2024-12-26 20:44:20.388 (MainThread) ERROR [matter_server.server.client_handler] [140513889796752] Error while handling: commission_with_code: Commission with code failed for node 18.
| 10:53:31 |
| spacekitteh | this is the only relevant stuff | 10:53:46 |
| @hexa:lossy.network | so nothing in bluetooth.service? | 10:54:44 |
| spacekitteh | nope | 10:55:16 |
| spacekitteh | nor in bluetooth-logger.service | 10:55:23 |
