Basic help getting things setup?

[dsugar100]

Is anyone available for just some basic setup help. My end goal is to use a Raspberry Pi to create a smart home device as a DIY project. I'm not interested in making something commercial.

As my first steps of this adventure, I have cloned the rs-matter repo and built everything, including the examples. For this initial learning about rs-matter I'm doing this on a laptop running Ubuntu 26.04. I'm using a Google Home Mini as the matter server. In console.home.google.com I added a test matter device with Vendor ID 0xfff1 and Product ID 0x8001.

Then trying the onoff_light_bt example. I built the example with the following options:
cargo build --bin onoff_light_bt --features avahi --features zbus --features zeroconf

Once built, it starts, I get the QR code on the console and my phone automatically will say to 'Set up your device'. On my phone I scan the QR Code in google home, it seems to try to add the device, I see messages on the console about the bluetooth service. My phone shows 'Connecting to Device'. On the console I see the handshake request and response. But then an unsubscribe request and disconnect message.

So, it seems to be trying. All devices are on the same network (2.4 GHz WiFi, I have ipv6 address on the laptop), firewall on the laptop is turned off.

I know these examples are for testing, but from the pages it sounds like this should also work as proof things work. But maybe that isn't the case.

And also, maybe this is not a good starting point for what I want to do, in which case just say so and I will try a different path.

Thanks for any and all insights and assistance.

[gmarcosb]

Should definitely work for proof-of-concept, but I have not tried it (only localhost with chip-tool) - unfortunately it can be really hard to debug the commissioning process

I used to work on the commissioner in Google Home - if you're able to attach to your phone with USB/adb & get a logcat, you may find something useful there (e.g. a callstack)

[ivmarkov]

It definitely works with Google and Alexa, and up until recently - with Apple too (now again we have mdns issues with apple).

If you paste the full logs from the rpi, I might be able to help.

Also describe what error the phone displays.

[dsugar100]

Just to clarify (I don't think it will matter). Just to get me started in my testing, I'm running these tests on an Ubuntu 26.04 laptop, not on the raspberry pi (but a respberry pi is my eventual target).

Ok, so interactions:
1 - I start the example from command line : ./target/debug/onoff_light_bt wlp3s0
2 - See the QR Code and bluetooth logs
3 - Phone shows "Setup your device" with buttons "Not Now" or "Scan QR Code"
4 - Press "Scan QR Code" and get the camera window.
5 - Scan the code - see check mark
6 - Shows 'MyTest' (which is what I named the matter project on console.home.google.com), 'Matter device manufacturer' and 'Light'. With a button 'Next' (which I press)
7 - the 'Connect this device to your Google Account' screen (press Agree)
8 - the 'Get ready for setup' screen press 'I'm Ready"
9 - "Searching" screen (for a moment)
10 - "Connecting to device..." screen (for a bit)
11 - 'Can't connect to your device' message (buttons 'Exit Setup' or 'Try Again')

The MAC address below is from my phone (which makes sense). I am not seeing very much traffic in tcpdump on while this is running. I see some 5353 interactions. But I would expect to see more. I can ping the Google Home Mini on the ipv6 address.

Logs:
./target/debug/onoff_light_bt wlp3s0
[2026-02-12T23:23:52Z INFO onoff_light_bt] Using NetworkManager to manage Wifi networks
[2026-02-12T23:23:52Z INFO rs_matter] SetupQRCode: [MT:-24J0AFN00KA064IJ3P0WISA0DK5N1K8SQ1RYCU1O0]
[2026-02-12T23:23:52Z INFO rs_matter] PairingCode: [3497-0112-332]
[2026-02-12T23:23:52Z INFO rs_matter] !!!! QR Code removed - it was here !!!!!
[2026-02-12T23:23:52Z INFO rs_matter::sc::pase] PASE Basic Commissioning Window opened
[2026-02-12T23:23:52Z INFO rs_matter::transport] Running Matter transport
[2026-02-12T23:23:52Z INFO rs_matter::respond] Responder: Creating 4 handlers
[2026-02-12T23:23:52Z DEBUG rs_matter::respond] Responder: Handlers size: 28136B
[2026-02-12T23:23:52Z INFO rs_matter::respond] Busy Responder: Creating 4 handlers
[2026-02-12T23:23:52Z DEBUG rs_matter::respond] Busy Responder: Handlers size: 2952B
[2026-02-12T23:23:52Z INFO rs_matter::transport::network::btp::gatt::bluez] Serving Matter GATT BTP service on Bluetooth adapter /org/bluez/hci0
2026-02-12T23:23:52Z INFO rs_matter::transport::network::btp::gatt::bluez] Registered Matter GATT BTP service on Bluetooth adapter /org/bluez/hci0
[2026-02-12T23:23:52Z INFO rs_matter::transport::network::btp::gatt::bluez] Advertising Matter GATT BTP service on Bluetooth adapter /org/bluez/hci0
[2026-02-12T23:23:57Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:24:02Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:24:07Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:24:12Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
2026-02-12T23:27:07Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:27:12Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [H|M|B|E,OP:6c]
HANDSHAKE REQ HandshakeReq { versions: 4, mtu: 517, window_size: 5 }
Selected version: 4, MTU: 244, window size: 5
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::context] Connect request from 94:45:60:2F:45:42
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::context] Subscribe request from 94:45:60:2F:45:42
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [H|M|B|E,OP:6c]
HANDSHAKE RESP HandshakeResp { version: 4, mtu: 244, window_size: 5 }
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::context] Unsubscribe request from 94:45:60:2F:45:42
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::context] Session 94:45:60:2F:45:42 removed
[2026-02-12T23:27:14Z DEBUG rs_matter::transport::network::btp::context] Disconnect request from 94:45:60:2F:45:42
[2026-02-12T23:27:14Z WARN rs_matter::transport::network::btp::context] Dropping data from address 94:45:60:2F:45:42 because there is no session for it
[2026-02-12T23:27:17Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:27:22Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request

And if I do 'try again' from the phone:

[2026-02-12T23:34:07Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:12Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:17Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:22Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:27Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:32Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [H|M|B|E,OP:6c]
HANDSHAKE REQ HandshakeReq { versions: 4, mtu: 517, window_size: 5 }
Selected version: 4, MTU: 244, window size: 5
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::context] Connect request from 94:45:60:2F:45:42
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::context] Subscribe request from 94:45:60:2F:45:42
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [H|M|B|E,OP:6c]
HANDSHAKE RESP HandshakeResp { version: 4, mtu: 244, window_size: 5 }
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:0,CTR:0,LEN:62]
READ 98B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::session] New exchange: 0::0 [SID:0,RSID:0,EID:5d37] :: Responder(AcceptPending)
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [S,SID:0,CTR:2fe14da,SRC:7bc8f426bbfe0a15][I,EID:5d37,PROTO:0,OP:20]
SC::PBKDFParamRequest
=> Processing (new exchange)
[2026-02-12T23:34:33Z DEBUG rs_matter::transport] Exchange 0::0 [SID:0,RSID:0,EID:5d37]: Accepted
[2026-02-12T23:34:33Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 0::0: Starting
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [U,SID:0,CTR:e9fc73c,DST:7bc8f426bbfe0a15][EID:5d37,PROTO:0,OP:21]
SC::PBKDFParamResponse
=> Sending
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:0,CTR:1,LEN:8b]
WRITE 139B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:1,CTR:1,LEN:5c]
READ 92B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [S,SID:0,CTR:2fe14db,SRC:7bc8f426bbfe0a15][I,EID:5d37,PROTO:0,OP:22]
SC::PASEPake1
=> Processing
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [U,SID:0,CTR:e9fc73d,DST:7bc8f426bbfe0a15][EID:5d37,PROTO:0,OP:23]
SC::PASEPake2
=> Sending
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:1,CTR:2,LEN:7f]
WRITE 127B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:2,CTR:2,LEN:3b]
READ 59B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [S,SID:0,CTR:2fe14dc,SRC:7bc8f426bbfe0a15][I,EID:5d37,PROTO:0,OP:24]
SC::PASEPake3
=> Processing
[2026-02-12T23:34:33Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 0::0: Completed
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [U,SID:0,CTR:e9fc73e,DST:7bc8f426bbfe0a15][EID:5d37,PROTO:0,OP:40]
SC::StatusReport
=> Sending
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:2,CTR:3,LEN:1e]
WRITE 30B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:3,CTR:3,LEN:33]
READ 51B
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::session] New exchange: 1::0 [SID:1,RSID:8625,EID:5d38] :: Responder(AcceptPending)
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22a8][I,EID:5d38,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:33Z DEBUG rs_matter::transport] Exchange 1::0 [SID:1,RSID:8625,EID:5d38]: Accepted
[2026-02-12T23:34:33Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Starting
[2026-02-12T23:34:33Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(29), attr: Some(1), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:33Z DEBUG rs_matter::dm::clusters::decl::descriptor] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read -> (build) +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 29 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 31 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 40 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 48 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 51 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 60 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 62 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 63 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 49 +
[2026-02-12T23:34:33Z DEBUG rs_matter::tlv::traits::builder] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read[]::TLV[] -> 54 +
[2026-02-12T23:34:33Z DEBUG rs_matter::dm::clusters::decl::descriptor] Endpt(0x00)::Cluster::Descriptor(0x001d)::Attr::ServerList(0x01)::Read (end) -> Ok(())
[2026-02-12T23:34:33Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Completed
[2026-02-12T23:34:33Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4863][EID:5d38,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:33Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:3,CTR:4,LEN:53]
WRITE 83B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:4,CTR:4,LEN:34]
READ 52B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::1 [SID:1,RSID:8625,EID:5d39] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22a9][I,EID:5d39,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::1 [SID:1,RSID:8625,EID:5d39]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::1: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(49), attr: Some(65532), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::types::cluster] Endpt(0x??)::Cluster(0x0031)::Attr::FeatureMap(0xfffc)::Read -> Ok(00000001)
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::1: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4864][EID:5d39,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:4,CTR:5,LEN:40]
WRITE 64B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:5,CTR:5,LEN:33]
READ 51B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::2 [SID:1,RSID:8625,EID:5d3a] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22aa][I,EID:5d3a,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::2 [SID:1,RSID:8625,EID:5d3a]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::2: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(40), attr: Some(2), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::basic_information] Endpt(0x00)::Cluster::BasicInformation(0x0028)::Attr::VendorID(0x02)::Read -> Ok(65521)
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::2: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4865][EID:5d3a,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:5,CTR:6,LEN:40]
WRITE 64B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:6,CTR:6,LEN:33]
READ 51B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::3 [SID:1,RSID:8625,EID:5d3b] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22ab][I,EID:5d3b,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::3 [SID:1,RSID:8625,EID:5d3b]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::3: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(40), attr: Some(4), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::basic_information] Endpt(0x00)::Cluster::BasicInformation(0x0028)::Attr::ProductID(0x04)::Read -> Ok(32769)
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::3: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4866][EID:5d3b,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:6,CTR:7,LEN:40]
WRITE 64B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:7,CTR:7,LEN:33]
READ 51B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::4 [SID:1,RSID:8625,EID:5d3c] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22ac][I,EID:5d3c,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::4 [SID:1,RSID:8625,EID:5d3c]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::4: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(62), attr: Some(3), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Attr::CommissionedFabrics(0x03)::Read -> Ok(0)
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::4: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4867][EID:5d3c,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:7,CTR:8,LEN:3f]
WRITE 63B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:8,CTR:8,LEN:33]
READ 51B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::0 [SID:1,RSID:8625,EID:5d3d] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22ad][I,EID:5d3d,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::0 [SID:1,RSID:8625,EID:5d3d]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(62), attr: Some(2), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(true), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Attr::SupportedFabrics(0x02)::Read -> Ok(5)
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4868][EID:5d3d,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:8,CTR:9,LEN:3f]
WRITE 63B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:9,CTR:9,LEN:33]
READ 51B
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::session] New exchange: 1::0 [SID:1,RSID:8625,EID:5d3e] :: Responder(AcceptPending)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22ae][I,EID:5d3e,PROTO:1,OP:2]
IM::ReadRequest
=> Processing (new exchange)
[2026-02-12T23:34:34Z DEBUG rs_matter::transport] Exchange 1::0 [SID:1,RSID:8625,EID:5d3e]: Accepted
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Starting
[2026-02-12T23:34:34Z DEBUG rs_matter::dm] IM: Read request: ReadReqRef { attr_requests: Ok(Some([Ok(AttrPath { tag_compression: None, node: None, endpoint: Some(0), cluster: Some(62), attr: Some(1), list_index: None })])), event_requests: Ok(None), event_filters: Ok(None), fabric_filtered: Ok(false), dataver_filters: Ok(None) }
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Attr::Fabrics(0x01)::Read -> (build) +
[2026-02-12T23:34:34Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Attr::Fabrics(0x01)::Read (end) -> Ok(())
[2026-02-12T23:34:34Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Completed
[2026-02-12T23:34:34Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d4869][EID:5d3e,PROTO:1,OP:5]
IM::ReportData
=> Sending
[2026-02-12T23:34:34Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:9,CTR:a,LEN:3f]
WRITE 63B
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::session]
>>RCV (BTP IO) 94:45:60:2F:45:42 [A|B|E,ACTR:a,CTR:a,LEN:3e]
READ 62B
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::session] New exchange: 1::0 [SID:1,RSID:8625,EID:5d3f] :: Responder(AcceptPending)
[2026-02-12T23:34:35Z DEBUG rs_matter::transport]
>>RCV BTP 94:45:60:2F:45:42 [SID:1,CTR:cae22af][I,EID:5d3f,PROTO:1,OP:8]
IM::InvokeRequest
=> Processing (new exchange)
[2026-02-12T23:34:35Z DEBUG rs_matter::transport] Exchange 1::0 [SID:1,RSID:8625,EID:5d3f]: Accepted
[2026-02-12T23:34:35Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Starting
[2026-02-12T23:34:35Z DEBUG rs_matter::dm] IM: Invoke request: InvReqRef { suppress_response: Ok(false), timed_request: Ok(false), inv_requests: Ok(Some([Ok(CmdData { path: CmdPath { endpoint: Some(0), cluster: Some(62), cmd: Some(2) }, data: 1: {
0: U8(0x01)
} })])) }
[2026-02-12T23:34:35Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Cmd::CertificateChainRequest(0x02)::Invoke(CertificateChainRequestRequest {certificate_type: DACCertificate,}) -> (build) +
[2026-02-12T23:34:35Z INFO rs_matter::dm::clusters::noc] Got Cert Chain Request
[2026-02-12T23:34:35Z DEBUG rs_matter::dm::clusters::decl::operational_credentials] Endpt(0x00)::Cluster::OperationalCredentials(0x003e)::Cmd::CertificateChainRequest(0x02)::Invoke (end) -> Ok(())
[2026-02-12T23:34:35Z DEBUG rs_matter::respond] Responder: Handler 0 / exchange 1::0: Completed
[2026-02-12T23:34:35Z DEBUG rs_matter::transport]
<<SND BTP 94:45:60:2F:45:42 [SID:8625,CTR:c0d486a][EID:5d3f,PROTO:1,OP:9]
IM::InvokeResponse
=> Sending
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [A|B,ACTR:a,CTR:b,LEN:229]
WRITE 239B
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [C,CTR:c]
WRITE 242B
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::session]
<<SND (BTP IO) 94:45:60:2F:45:42 [C|E,CTR:d]
WRITE 72B
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::context] Unsubscribe request from 94:45:60:2F:45:42
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::context] Session 94:45:60:2F:45:42 removed
[2026-02-12T23:34:35Z DEBUG rs_matter::transport::network::btp::context] Disconnect request from 94:45:60:2F:45:42
[2026-02-12T23:34:37Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:42Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request
[2026-02-12T23:34:47Z INFO rs_matter::dm::clusters::on_off::test] Emulation: out of band toggle request

[ivmarkov]

6 - Shows 'MyTest' (which is what I named the matter project on console.home.google.com), 'Matter device manufacturer' and 'Light'. With a button 'Next' (which I press)

I think setting up stuff in "console.home.google.com" is not necessary anymore. So you could remove everything from there.

3 - Phone shows "Setup your device" with buttons "Not Now" or "Scan QR Code"

From your phone, Google Home app, tap the "+" sign in the top right corner, then from the popup menu select "Device" and scan the QR code.

[dsugar100]

Ok, I have removed everything from console.home.google.com (and reboot the google home in case that matters). Then I used the '+' and 'Add matter device' and scanned the QR Code. I see basically the same thing in on the console. The bluetooth interaction but nothing else. I'm running as a regular user (not root).

Is there a matter server as part of this library (rather than using the google home) to maybe take that out of the loop as being as issue (not sure why it would be). Or other ways to debug where things are going wrong?

Clearly I'm doing something wrong, but I'm not sure what.
I appreciate the assistance.

[ivmarkov]

cargo xtask itest as a side effect will build the chiptool executable, that you could then use as a Matter Controller for commissioning this device.

[ivmarkov]

BTW - I just got your error when my Android phone lost connection to my Google controller (Nest Hub Gen 2).
Are you sure you can access your Google Matter Controller from within your phone?

[tcarmelveilleux]

@ivmarkov can you share logs and a screen recording of Google home app showing what you are trying to do? What is the device under test? Matter support on Google only works if the full mandatory data model is correctly supported for some core parts of the spec. It also requires correctly operating subscriptions, including wildcard subscriptions

[ivmarkov]

@tcarmelveilleux I'm myself not having any problem at all with Google. Commissioning with Google + rs-matter works for me since a couple of years.