Error: environment variable not found

Zercerium commented 11 months ago

generated a new project with cargo generate esp-rs/esp-idf-template cargo
added rs-matter as a dependency
copied the onoff_light example code into it
used the provided command from the README.md export MCU=esp32; export CARGO_TARGET_XTENSA_ESP32_ESPIDF_LINKER=ldproxy; export RUSTFLAGS="-C default-linker-libraries"; export WIFI_SSID=ssid;export WIFI_PASS=pass; cargo build --no-default-features --target xtensa-esp32-espidf -Zbuild-std=std,panic_abort

expected: successful build outcome: error: failed to run custom build command for rs-matter v0.1.1 Error: environment variable not found

quite not sure which env variable is not set

ivmarkov commented 11 months ago

Do you have the build.rs file in the root of your binary crate, as generated by esp-idf-template?
Can you paste the full build log here (please do export RUST_BACKTRACE=1 before running the build)

Zercerium commented 11 months ago

I'm working on it, I think I got some deps wrong, will update as soon as I make progress.

ivmarkov commented 11 months ago

Make sure to have a direct dependency on esp-idf-sys and on embuild in your binary crate - even if you don't use esp-idf-sys directly.

Zercerium commented 11 months ago

ok finally, got it working thanks for your work

# didnt touched things above from the template in the Cargo.toml file
[dependencies]
log = { version = "0.4.17", default-features = false }
embassy-futures = "0.1" #use embassy
async-io = { version = "=1.12" } #use async-io
esp-idf-sys = { version = "0.33", optional = true, default-features = false, features = [
    "native",
] }
esp-idf-hal = { version = "0.41", optional = true, features = [
    "embassy-sync",
    "critical-section",
] }
esp-idf-svc = { version = "0.46", optional = true, features = [
    "embassy-time-driver",
] }
embedded-svc = { version = "0.25", optional = true }
rs-matter = { path = "../rs-matter/rs-matter", default-features = false, features = [
    "esp-idf", # use needed features
] }

[target.'cfg(target_os = "espidf")'.dev-dependencies]
esp-idf-sys = { version = "0.33", default-features = false, features = [
    "binstart",
] }

[build-dependencies]
embuild = "0.31.2"

# For compatibility with ESP IDF
[patch.crates-io] # Patch crates
polling = { git = "https://github.com/esp-rs-compat/polling" }
socket2 = { git = "https://github.com/esp-rs-compat/socket2" }

only use export WIFI_SSID=ssid;export WIFI_PASS=pass; cargo build

sadly I have an out of memory error

E (902) pthread: Failed to create task!
thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: Os { code: 12, kind: OutOfMemory, message: "Not enough space" }', src/main.rs:51:10
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace

ivmarkov commented 11 months ago

Try to build in --release
Increase a little bit the memory in main, by 10 - 20K

ivmarkov commented 11 months ago

Oh and one more thing: since you've created a separate binary crate - put in your Cargo.toml:

[profile.release]
opt-level = 3

[profile.dev]
debug = true
opt-level = 3

... and delete the existing ones. You really need opt-level = 3 for all the memory optimizations to kick-in. And by default, the esp-idf-template uses z and s which are not good enough. The above would also allow you to stick to debug builds.

Zercerium commented 11 months ago

thanks a lot, changed opt-level and after some testing, set it to .stack_size(140 * 1024) what worked now

but the search is going on 🙈

I (8259) rs_matter::transport::core: Comissioning started
I (8259) rs_matter::transport::core: Creating queue for 1 exchanges
I (8269) rs_matter::transport::core: Creating 8 handlers
I (8269) rs_matter::transport::core: Handlers size: 9992
I (8279) rs_matter::transport::core: Transport: waiting for incoming packets
I (8289) rs_matter::transport::udp::async_io: Listening on [::]:5353
I (8299) rs_matter::transport::udp::async_io: Joined IPV6 multicast ff02::fb/2
I (8299) rs_matter::transport::udp::async_io: Joined IP multicast 224.0.0.251/192.168.178.59
I (8319) rs_matter::mdns::builtin: Broadcasting mDNS entry to 224.0.0.251:5353
I (8329) rs_matter::mdns::builtin: Broadcasting mDNS entry to ff02::fb:5353
W (8329) rs_matter::transport::udp::async_io: Error on the network: Os { code: 118, kind: HostUnreachable, message: "Host is unreachable" }
Error: Error::Network

ivmarkov commented 11 months ago

You are hitting #100. To workaround it, temporarily downgrade to esp idf v4.4.

ivmarkov commented 11 months ago

After that, if you are using a Google Controller, you'll likely hit #102 as well, which we are analyzing right now.

Zercerium commented 11 months ago

Ok, thanks. I tried the onoff_light example on my Host and yeah with the chip-tool everything works fine, but with Alexa I also got an error.

ivmarkov commented 11 months ago

@Zercerium Can you try if #107 fixes the provisioning issue for you? Note that provisioning might still generate an error at the end (device not found - that's an old issue because we don't properly implement true subscriptions) but the device should afterwards be nevertheless visible.

Zercerium commented 11 months ago

ofc I switched to your main and run the onoff_light example

The Alexa App says: "Something gone wrong, an unexpected error occurred, please try again later" (Translated)

following the log, say if u need more infos

Log

``` Running `target/debug/examples/onoff_light` [2023-10-03T15:07:03Z INFO onoff_light] Matter memory: mDNS=72, Matter=32176, MdnsBuffers=2815, RunBuffers=26235 [2023-10-03T15:07:03Z INFO onoff_light] Will use network interface en0 with 192.168.178.68/fe80::1c9e:ed1f:e8e9:242c for mDNS [2023-10-03T15:07:03Z INFO onoff_light] mDNS initialized [2023-10-03T15:07:03Z INFO onoff_light] Matter initialized [2023-10-03T15:07:03Z INFO rs_matter::persist::fileio] Persisting from/to /var/folders/zj/8rb853c575z_3p_225ltqpnm0000gn/T/rs-matter [2023-10-03T15:07:03Z INFO onoff_light] Matter transport runner memory: 17224 [2023-10-03T15:07:03Z INFO rs_matter::transport::udp::async_io] Listening on [::]:5540 [2023-10-03T15:07:03Z INFO rs_matter::transport::core] Running Matter transport [2023-10-03T15:07:03Z INFO rs_matter::pairing::code] Pairing Code: 0087-6800-071 [2023-10-03T15:07:03Z INFO rs_matter::pairing::qr] QR Code Text: MT:Y.K90Q1212Z6Q66D33P084L90Z.I332SQ43I15T0 [2023-10-03T15:07:03Z INFO rs_matter::pairing::qr] █████████████████████████████████████ █████████████████████████████████████ ████ ▄▄▄▄▄ █▄▄▄▀████▀▄ ▄ █ ▄▄▄▄▄ ████ ████ █ █ █▀█▄▄▀▄▀ ▀█▄ █ █ █ ████ ████ █▄▄▄█ █ █ █ ▀▄ ▀▄▄█ █▄▄▄█ ████ ████▄▄▄▄▄▄▄█ ▀ ▀▄█ █▄▀▄█ █▄▄▄▄▄▄▄████ ████▄█▀▀▄▀▄ ▄ ▀ █▄▄▄▄▀ ▄▄▄ ▄█▀▄████ ████▄█ ▄▄▄▄ ▄ ▄▀▀ ▄ ▀ ▄█▄▀ ▀█▄▀█▀████ ████▀▄█▀▄█▄█ ▄ ▀█▀ █▀▀▄▄ ▄▄ ████ ██████▄▄▄▄▄█▄▄█▄▄█▀█▄▄ █ ▀▄██▀▄█████ ████▀▀▀▄▄ ▄█▄▀▀ ▄▄▄▄▄▀█▄▀█▀▄▄▄█ ████ ██████ ▀ ▀▄▀█▀█ ▄ ▀ ▀█▄ ▀▄██▀▄█████ ████▄▄█▄█▄▄▄ ▄ ▀█▀▀█▀ ▄▄▄ ▀ ████ ████ ▄▄▄▄▄ █▄█ █▀█▀█▄▄█▀ █▄█ ▄▄▄▀████ ████ █ █ █▄▀ ▄▄ ▄▄█ █▀ ▄▄ ▄ ▄ █████ ████ █▄▄▄█ ██▄ ▄█▄▀ ▀▄██ █▄ ▄ ▄████ ████▄▄▄▄▄▄▄█▄▄███▄████▄█▄███▄▄███████ █████████████████████████████████████ █████████████████████████████████████ [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] Registering mDNS service 663AB34FCBECB8D3/Commissionable(250) [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key D val 250 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key CM val 1 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key DN val OnOff Light [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key VP val 65521+32768 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key SII val 5000 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key SAI val 300 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key PH val 33 [2023-10-03T15:07:03Z INFO rs_matter::mdns::astro] mDNS TXT key PI val [2023-10-03T15:07:04Z INFO astro_dnssd::os::apple::register] Got reply [2023-10-03T15:07:04Z INFO astro_dnssd::os::apple::register] Reply info sent [2023-10-03T15:07:04Z INFO rs_matter::transport::core] Comissioning started [2023-10-03T15:07:04Z INFO rs_matter::transport::core] Creating queue for 1 exchanges [2023-10-03T15:07:04Z INFO rs_matter::transport::core] Creating 8 handlers [2023-10-03T15:07:04Z INFO rs_matter::transport::core] Handlers size: 13768 [2023-10-03T15:07:04Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193290 [2023-10-03T15:07:57Z INFO rs_matter::transport::session] Creating new session [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 32, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48044, session_id: SessionId { id: 0, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: Some(1129672451880335488), is_encrypted: false } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:PBKDFParamRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[32][7, f9, d2, e4, 43, a5, 75, 19, 59, fb, 8e, b4, a9, 4a, 9f, d9, 65, 3b, 86, 5c, a3, 3d, f7, c5, 5b, 9b, 3e, 5c, 89, e5, 46, 83] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U16(26983) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 5: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U16(5000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U16(300) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48044, session_id: SessionId { id: 0, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: Some(1129672451880335488), is_encrypted: false } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48044, session_id: SessionId { id: 0, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: Some(1129672451880335488), is_encrypted: false } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet SC:PBKDFParamResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[32][7, f9, d2, e4, 43, a5, 75, 19, 59, fb, 8e, b4, a9, 4a, 9f, d9, 65, 3b, 86, 5c, a3, 3d, f7, c5, 5b, 9b, 3e, 5c, 89, e5, 46, 83] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: len[32][87, 94, 66, 35, 82, 88, f, 4d, 69, 91, 87, b2, 6f, e8, 8c, 8f, f3, 75, de, 1a, 8d, 48, 7f, 7e, ff, 82, ae, e1, 46, cd, c1, 22] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U16(2000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: len[32][77, 3b, 1a, 7c, 84, d9, e, ac, a5, a9, 5f, d3, 47, 9, a, 56, f1, 8d, 4, 3a, 71, cb, f0, 3d, 88, 90, 50, cb, 64, d2, 52, a2] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48044, Proto: 0, Opcode: 33, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193291 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193292 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 34, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:PASEPake1: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[65][4, d2, 2a, 54, c5, 42, 2b, 2e, ba, df, bf, cd, 7f, 78, bb, 3f, e6, 91, 9d, 5d, c0, 57, 83, 1f, ac, e5, ad, 47, 98, fe, b8, 71, e5, de, e3, 8c, c6, 43, 55, 7a, 46, 8, 82, a3, 80, f6, 0, 73, c1, d0, 29, cb, b5, 7d, 45, 4f, e8, 60, e, 9b, d9, 29, 40, c2, 85] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet SC:PASEPake2: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[65][4, c3, fc, 0, 71, e7, 73, dc, 5e, bd, ae, bf, ad, af, 50, f8, 2c, 26, f8, 1c, b4, bf, 94, cf, 80, 93, 60, 9a, 63, 10, 4a, 33, 1, 19, 50, e, 5d, 58, ff, 75, af, 13, 47, b, 7, 89, e5, 4f, aa, 48, 92, 14, d5, 7, 78, 70, e1, d5, e5, 28, b5, c1, 53, c9, 9f] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: len[32][5e, f2, 9, 33, 36, c3, 87, e8, d5, a2, d, d1, 6c, 3b, b3, 63, a3, a1, e4, 5d, 77, 3, c2, 9a, 57, 3f, 19, 23, e1, 4a, f2, fb] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48044, Proto: 0, Opcode: 35, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193293 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193294 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 36, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:PASEPake3: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[32][55, 88, 67, 10, 55, 44, fa, d0, 85, 0, 67, 9d, 5b, ce, 98, c6, 15, 30, 83, fd, 89, 83, c2, 26, 56, 6f, 2e, 62, 37, fe, 92, 69] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::mdns::astro] Deregistering mDNS service 663AB34FCBECB8D3 [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet SC:StatusReport: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] S8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] S8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] S8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] S8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48044, Proto: 0, Opcode: 64, Flags: R|A| [2023-10-03T15:07:57Z ERROR astro_dnssd::os::apple::register] Error processing: -65563, exiting thread [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193295 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48044, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114273 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48045, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48045, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48045, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48045, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48045, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114274 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48045, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(80) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::general_commissioning] Handling command ARM Fail Safe [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[0]"" [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48045, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114275 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48045, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114276 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48046, Proto: 1, Opcode: 2, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48046, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:ReadRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(49) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U16(65532) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48046, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48046, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:ReportData: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U32(532960115) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(49) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U16(65532) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(4) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48046, Proto: 1, Opcode: 5, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114277 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48046, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114278 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48047, Proto: 1, Opcode: 2, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48047, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:ReadRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U8(3) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48047, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48047, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:ReportData: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U32(1359638237) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U8(3) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48047, Proto: 1, Opcode: 5, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114279 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48047, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114280 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48048, Proto: 1, Opcode: 2, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48048, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:ReadRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48048, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48048, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:ReportData: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U32(1359638237) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 3: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 4: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48048, Proto: 1, Opcode: 5, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114281 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48048, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114282 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48049, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48049, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48049, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48049, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48049, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114283 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48049, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[2]"DE" [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::general_commissioning] Handling command Set Regulatory Config [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::general_commissioning] Received country code: [68, 69] [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(48) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(3) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[0]"" [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48049, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114284 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48049, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114285 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48050, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48050, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48050, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48050, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48050, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114286 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48050, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Handling command CertChainRequest [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received data: 1: { [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received Cert Type:1 [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(3) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[492][30, 82, 1, e8, 30, 82, 1, 8e, a0, 3, 2, 1, 2, 2, 8, 52, 72, 4d, 21, e2, c1, 74, af, 30, a, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 30, 3d, 31, 25, 30, 23, 6, 3, 55, 4, 3, c, 1c, 4d, 61, 74, 74, 65, 72, 20, 44, 65, 76, 20, 50, 41, 49, 20, 30, 78, 46, 46, 46, 31, 20, 6e, 6f, 20, 50, 49, 44, 31, 14, 30, 12, 6, a, 2b, 6, 1, 4, 1, 82, a2, 7c, 2, 1, c, 4, 46, 46, 46, 31, 30, 20, 17, d, 32, 32, 30, 32, 30, 35, 30, 30, 30, 30, 30, 30, 5a, 18, f, 39, 39, 39, 39, 31, 32, 33, 31, 32, 33, 35, 39, 35, 39, 5a, 30, 53, 31, 25, 30, 23, 6, 3, 55, 4, 3, c, 1c, 4d, 61, 74, 74, 65, 72, 20, 44, 65, 76, 20, 44, 41, 43, 20, 30, 78, 46, 46, 46, 31, 2f, 30, 78, 38, 30, 30, 32, 31, 14, 30, 12, 6, a, 2b, 6, 1, 4, 1, 82, a2, 7c, 2, 1, c, 4, 46, 46, 46, 31, 31, 14, 30, 12, 6, a, 2b, 6, 1, 4, 1, 82, a2, 7c, 2, 2, c, 4, 38, 30, 30, 32, 30, 59, 30, 13, 6, 7, 2a, 86, 48, ce, 3d, 2, 1, 6, 8, 2a, 86, 48, ce, 3d, 3, 1, 7, 3, 42, 0, 4, da, 93, f1, 67, 36, 25, 67, 50, d9, 3, b0, 34, ba, 45, 88, ab, af, 58, 95, 4f, 77, aa, 9f, d9, 98, 9d, fd, 40, d, 7a, b3, fd, c9, 75, 3b, 3b, 92, 1b, 29, 4c, 95, f, d9, d2, 80, d1, 4c, 43, 86, 2f, 16, dc, 85, 4b, 0, ed, 39, e7, 50, ba, bf, 1d, c4, ca, a3, 60, 30, 5e, 30, c, 6, 3, 55, 1d, 13, 1, 1, ff, 4, 2, 30, 0, 30, e, 6, 3, 55, 1d, f, 1, 1, ff, 4, 4, 3, 2, 7, 80, 30, 1d, 6, 3, 55, 1d, e, 4, 16, 4, 14, ef, 6, 56, 11, 9c, 1c, 91, a7, 9a, 94, e6, dc, f3, 79, 79, db, d0, 7f, f8, a3, 30, 1f, 6, 3, 55, 1d, 23, 4, 18, 30, 16, 80, 14, 63, 54, e, 47, f6, 4b, 1c, 38, d1, 38, 84, a4, 62, d1, 6c, 19, 5d, 8f, fb, 3c, 30, a, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 3, 48, 0, 30, 45, 2, 20, 46, 86, 81, 7, 33, bf, d, c8, ff, 4c, b5, 14, 5a, 6b, fa, 1a, ec, ff, a8, b6, da, b6, c3, 51, aa, ee, cd, af, b8, be, 95, 7d, 2, 21, 0, e8, c2, 8d, 6b, fc, c8, 7a, 7d, 54, 2e, ad, 6e, da, ca, 14, 8d, 5f, a5, 6, 1e, 51, 7c, be, 4f, 24, a7, 20, e1, c0, 59, de, 1a] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48050, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114287 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48050, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114288 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48051, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48051, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48051, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48051, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48051, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114289 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48051, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(2) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Handling command CertChainRequest [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received data: 1: { [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received Cert Type:2 [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(3) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[463][30, 82, 1, cb, 30, 82, 1, 71, a0, 3, 2, 1, 2, 2, 8, 56, ad, 82, 22, ad, 94, 5b, 64, 30, a, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 30, 30, 31, 18, 30, 16, 6, 3, 55, 4, 3, c, f, 4d, 61, 74, 74, 65, 72, 20, 54, 65, 73, 74, 20, 50, 41, 41, 31, 14, 30, 12, 6, a, 2b, 6, 1, 4, 1, 82, a2, 7c, 2, 1, c, 4, 46, 46, 46, 31, 30, 20, 17, d, 32, 32, 30, 32, 30, 35, 30, 30, 30, 30, 30, 30, 5a, 18, f, 39, 39, 39, 39, 31, 32, 33, 31, 32, 33, 35, 39, 35, 39, 5a, 30, 3d, 31, 25, 30, 23, 6, 3, 55, 4, 3, c, 1c, 4d, 61, 74, 74, 65, 72, 20, 44, 65, 76, 20, 50, 41, 49, 20, 30, 78, 46, 46, 46, 31, 20, 6e, 6f, 20, 50, 49, 44, 31, 14, 30, 12, 6, a, 2b, 6, 1, 4, 1, 82, a2, 7c, 2, 1, c, 4, 46, 46, 46, 31, 30, 59, 30, 13, 6, 7, 2a, 86, 48, ce, 3d, 2, 1, 6, 8, 2a, 86, 48, ce, 3d, 3, 1, 7, 3, 42, 0, 4, 41, 9a, 93, 15, c2, 17, 3e, c, 8c, 87, 6d, 3, cc, fc, 94, 48, 52, 64, 7f, 7f, ec, 5e, 50, 82, f4, 5, 99, 28, ec, a8, 94, c5, 94, 15, 13, 9, ac, 63, 1e, 4c, b0, 33, 92, af, 68, 4b, b, af, b7, e6, 5b, 3b, 81, 62, c2, f5, 2b, f9, 31, b8, e7, 7a, aa, 82, a3, 66, 30, 64, 30, 12, 6, 3, 55, 1d, 13, 1, 1, ff, 4, 8, 30, 6, 1, 1, ff, 2, 1, 0, 30, e, 6, 3, 55, 1d, f, 1, 1, ff, 4, 4, 3, 2, 1, 6, 30, 1d, 6, 3, 55, 1d, e, 4, 16, 4, 14, 63, 54, e, 47, f6, 4b, 1c, 38, d1, 38, 84, a4, 62, d1, 6c, 19, 5d, 8f, fb, 3c, 30, 1f, 6, 3, 55, 1d, 23, 4, 18, 30, 16, 80, 14, 6a, fd, 22, 77, 1f, 51, 1f, ec, bf, 16, 41, 97, 67, 10, dc, dc, 31, a1, 71, 7e, 30, a, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 3, 48, 0, 30, 45, 2, 21, 0, b2, ef, 27, f4, 9a, e9, b5, f, b9, 1e, ea, c9, 4c, 4d, b, db, b8, d7, 92, 9c, 6c, b8, 8f, ac, e5, 29, 36, 8d, 12, 5, 4c, c, 2, 20, 65, 5d, c9, 2b, 86, bd, 90, 98, 82, a6, c6, 21, 77, b8, 25, d7, d0, 5e, db, e7, c2, 2f, 9f, ea, 71, 22, e, 7e, a7, 3, f8, 91] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48051, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114290 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48051, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114291 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48052, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48052, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48052, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48052, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48052, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114292 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48052, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[32][e8, 32, bb, a6, 95, c0, 9b, 87, 72, 8, 6b, 22, 8d, 2, 72, 4f, a7, 28, cf, a5, ab, b8, 9a, 63, d7, b0, 92, 11, df, 4, 56, c7] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Handling command AttestationRequest [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received Attestation Nonce:OctetStr([232, 50, 187, 166, 149, 192, 155, 135, 114, 8, 107, 34, 141, 2, 114, 79, 167, 40, 207, 165, 171, 184, 154, 99, 215, 176, 146, 17, 223, 4, 86, 199]) [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[588][15, 31, 1, 1d, 2, 30, 82, 2, 19, 6, 9, 2a, 86, 48, 86, f7, d, 1, 7, 2, a0, 82, 2, a, 30, 82, 2, 6, 2, 1, 3, 31, d, 30, b, 6, 9, 60, 86, 48, 1, 65, 3, 4, 2, 1, 30, 82, 1, 71, 6, 9, 2a, 86, 48, 86, f7, d, 1, 7, 1, a0, 82, 1, 62, 4, 82, 1, 5e, 15, 24, 0, 1, 25, 1, f1, ff, 36, 2, 5, 0, 80, 5, 1, 80, 5, 2, 80, 5, 3, 80, 5, 4, 80, 5, 5, 80, 5, 6, 80, 5, 7, 80, 5, 8, 80, 5, 9, 80, 5, a, 80, 5, b, 80, 5, c, 80, 5, d, 80, 5, e, 80, 5, f, 80, 5, 10, 80, 5, 11, 80, 5, 12, 80, 5, 13, 80, 5, 14, 80, 5, 15, 80, 5, 16, 80, 5, 17, 80, 5, 18, 80, 5, 19, 80, 5, 1a, 80, 5, 1b, 80, 5, 1c, 80, 5, 1d, 80, 5, 1e, 80, 5, 1f, 80, 5, 20, 80, 5, 21, 80, 5, 22, 80, 5, 23, 80, 5, 24, 80, 5, 25, 80, 5, 26, 80, 5, 27, 80, 5, 28, 80, 5, 29, 80, 5, 2a, 80, 5, 2b, 80, 5, 2c, 80, 5, 2d, 80, 5, 2e, 80, 5, 2f, 80, 5, 30, 80, 5, 31, 80, 5, 32, 80, 5, 33, 80, 5, 34, 80, 5, 35, 80, 5, 36, 80, 5, 37, 80, 5, 38, 80, 5, 39, 80, 5, 3a, 80, 5, 3b, 80, 5, 3c, 80, 5, 3d, 80, 5, 3e, 80, 5, 3f, 80, 5, 40, 80, 5, 41, 80, 5, 42, 80, 5, 43, 80, 5, 44, 80, 5, 45, 80, 5, 46, 80, 5, 47, 80, 5, 48, 80, 5, 49, 80, 5, 4a, 80, 5, 4b, 80, 5, 4c, 80, 5, 4d, 80, 5, 4e, 80, 5, 4f, 80, 5, 50, 80, 5, 51, 80, 5, 52, 80, 5, 53, 80, 5, 54, 80, 5, 55, 80, 5, 56, 80, 5, 57, 80, 5, 58, 80, 5, 59, 80, 5, 5a, 80, 5, 5b, 80, 5, 5c, 80, 5, 5d, 80, 5, 5e, 80, 5, 5f, 80, 5, 60, 80, 5, 61, 80, 5, 62, 80, 5, 63, 80, 18, 24, 3, 16, 2c, 4, 13, 5a, 49, 47, 32, 30, 31, 34, 32, 5a, 42, 33, 33, 30, 30, 30, 33, 2d, 32, 34, 24, 5, 0, 24, 6, 0, 25, 7, 94, 26, 24, 8, 0, 18, 31, 7d, 30, 7b, 2, 1, 3, 80, 14, 62, fa, 82, 33, 59, ac, fa, a9, 96, 3e, 1c, fa, 14, a, dd, f5, 4, f3, 71, 60, 30, b, 6, 9, 60, 86, 48, 1, 65, 3, 4, 2, 1, 30, a, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 4, 47, 30, 45, 2, 20, 24, e5, d1, f4, 7a, 7d, 7b, d, 20, 6a, 26, ef, 69, 9b, 7c, 97, 57, b7, 2d, 46, 90, 89, de, 31, 92, e6, 78, c7, 45, e7, f6, c, 2, 21, 0, f8, aa, 2f, a7, 11, fc, b7, 9b, 97, e3, 97, ce, da, 66, 7b, ae, 46, 4e, 2b, d3, ff, df, c3, cc, ed, 7a, a8, ca, 5f, 4c, 1a, 7c, 30, 2, 20, e8, 32, bb, a6, 95, c0, 9b, 87, 72, 8, 6b, 22, 8d, 2, 72, 4f, a7, 28, cf, a5, ab, b8, 9a, 63, d7, b0, 92, 11, df, 4, 56, c7, 26, 3, 4d, 2e, 1c, 65, 18] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[64][1d, b3, ae, f2, 12, 9a, 93, 63, 56, 14, 7b, fc, d9, b9, d9, cf, 3c, 85, 6b, 4c, 2, 94, e6, b2, 5e, b0, 2b, 18, 8, 12, a0, 2f, e0, d6, 68, a0, 46, 21, bf, 9f, ec, b, 7c, 5a, 6d, 41, 50, c, 39, ee, 6e, 83, c7, a9, 58, 72, 55, 50, a7, b2, c8, b7, 21, cb] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48052, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114293 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48052, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114294 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48053, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48053, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48053, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48053, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48053, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114295 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48053, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(4) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[32][36, 12, 6e, cb, d2, 9, 52, 44, 11, bd, 66, 9e, c0, d8, ed, 67, 0, bc, d, ab, 75, a7, e, 4c, 58, a0, 5e, 37, a4, b4, fe, da] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Handling command CSRRequest [2023-10-03T15:07:57Z INFO rs_matter::data_model::sdm::noc] Received CSR Nonce:OctetStr([54, 18, 110, 203, 210, 9, 82, 68, 17, 189, 102, 158, 192, 216, 237, 103, 0, 188, 13, 171, 117, 167, 14, 76, 88, 160, 94, 55, 164, 180, 254, 218]) [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 2: U8(5) [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 0: len[244][15, 30, 1, cc, 30, 81, c9, 30, 70, 2, 1, 0, 30, e, 31, c, 30, a, 6, 3, 55, 4, a, c, 3, 43, 53, 52, 30, 59, 30, 13, 6, 7, 2a, 86, 48, ce, 3d, 2, 1, 6, 8, 2a, 86, 48, ce, 3d, 3, 1, 7, 3, 42, 0, 4, 81, 61, 23, 65, bb, 6f, d9, ef, 8e, fc, 1f, c9, 1a, dd, 30, 59, 6a, 7c, 24, 2, 8c, e4, 74, 83, 15, d1, 99, d7, 8c, c0, 63, 8f, 56, 79, 55, f5, fb, 32, 2a, 7, b7, 87, 73, 24, 4, b8, 4f, f1, 66, fc, 2d, 78, b6, 82, a, 58, c1, 8a, 94, cf, 5c, c1, 6d, d, a0, 0, 30, c, 6, 8, 2a, 86, 48, ce, 3d, 4, 3, 2, 5, 0, 3, 47, 0, 30, 44, 2, 20, 35, f1, ca, 98, 11, 8e, 65, 55, 7e, e3, cc, 2a, fb, f5, be, 96, cf, 68, be, 9d, 3c, a6, e4, 58, c4, 7c, 28, ff, ac, 74, fa, 23, 2, 20, 22, f5, ec, 1e, 93, 50, 0, 61, 3c, ce, 1c, f8, 92, 8a, d4, ee, 4e, cd, 99, 41, 4e, 31, d7, bf, e6, d5, 7c, b3, 53, 83, 13, e4, 30, 2, 20, 36, 12, 6e, cb, d2, 9, 52, 44, 11, bd, 66, 9e, c0, d8, ed, 67, 0, bc, d, ab, 75, a7, e, 4c, 58, a0, 5e, 37, a4, b4, fe, da, 18] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] 1: len[64][ce, b6, 3b, 74, f3, a3, a4, 15, 7, a7, b8, 4c, 12, 19, 9a, 9, a2, 11, 63, ae, 25, 55, 5f, 33, 10, c7, ef, 5a, b2, f9, 26, 6f, a8, fa, ec, b5, d5, a3, ac, 2f, 0, 16, 2b, f8, 57, 8b, 77, 23, 1c, c4, 5e, 3b, 41, 96, 72, da, 82, 87, 5f, 25, 3d, 9e, 38, e1] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48053, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:57Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114296 [2023-10-03T15:07:57Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48053, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:57Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:57Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:57Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:58Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114297 [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48054, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48054, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48054, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48054, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48054, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:58Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114298 [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48054, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: U8(11) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: len[261][15, 30, 1, 11, 0, 8f, 7e, 89, d8, d6, 7b, a, ec, de, ae, 6d, 21, 2d, ea, a3, b7, 24, 2, 1, 37, 3, 27, 14, d8, 94, 47, 7f, d1, 55, 36, 1, 18, 26, 4, 53, 7a, c7, 2a, 26, 5, e3, 78, cd, 59, 37, 6, 27, 14, d8, 94, 47, 7f, d1, 55, 36, 1, 18, 24, 7, 1, 24, 8, 1, 30, 9, 41, 4, 4, 73, 2, d8, c1, c4, 29, 4, 90, 55, 34, 6a, 31, cf, 2c, 7e, 62, d7, 26, 6a, 3d, de, b6, a, 85, 48, 10, d9, a8, d3, ed, 50, a2, e9, b, b5, f1, fa, d5, 1b, 29, b7, 27, c1, 53, 94, 6b, 64, 71, 8a, e2, 42, 2e, a1, d, fc, 9b, 7d, b0, cb, c4, 81, 64, d4, 37, a, 35, 1, 29, 1, 18, 30, 4, 14, c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59, 24, 2, 61, 30, 5, 14, c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59, 18, 30, b, 40, 9, 2a, 58, 6a, ce, 30, 1, 8a, 5f, a6, 14, cc, a2, c2, b2, 3c, 58, ab, 6c, 75, dc, 8e, 75, 9f, 95, a7, ba, 13, db, 97, d8, 40, 43, b5, 32, 50, 5e, c, f6, 1c, 18, 7d, 29, c0, f, 56, f9, a, 49, 52, df, b1, 95, 16, 3d, 49, bb, b1, f5, 40, 2e, 52, ad, 98, 18] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::data_model::sdm::noc] Handling command AddTrustedRootCert [2023-10-03T15:07:58Z INFO rs_matter::data_model::sdm::noc] Received Trusted Cert:OctetStr([15, 30, 1, 11, 0, 8f, 7e, 89, d8, d6, 7b, a, ec, de, ae, 6d, 21, 2d, ea, a3, b7, 24, 2, 1, 37, 3, 27, 14, d8, 94, 47, 7f, d1, 55, 36, 1, 18, 26, 4, 53, 7a, c7, 2a, 26, 5, e3, 78, cd, 59, 37, 6, 27, 14, d8, 94, 47, 7f, d1, 55, 36, 1, 18, 24, 7, 1, 24, 8, 1, 30, 9, 41, 4, 4, 73, 2, d8, c1, c4, 29, 4, 90, 55, 34, 6a, 31, cf, 2c, 7e, 62, d7, 26, 6a, 3d, de, b6, a, 85, 48, 10, d9, a8, d3, ed, 50, a2, e9, b, b5, f1, fa, d5, 1b, 29, b7, 27, c1, 53, 94, 6b, 64, 71, 8a, e2, 42, 2e, a1, d, fc, 9b, 7d, b0, cb, c4, 81, 64, d4, 37, a, 35, 1, 29, 1, 18, 30, 4, 14, c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59, 24, 2, 61, 30, 5, 14, c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59, 18, 30, b, 40, 9, 2a, 58, 6a, ce, 30, 1, 8a, 5f, a6, 14, cc, a2, c2, b2, 3c, 58, ab, 6c, 75, dc, 8e, 75, 9f, 95, a7, ba, 13, db, 97, d8, 40, 43, b5, 32, 50, 5e, c, f6, 1c, 18, 7d, 29, c0, f, 56, f9, a, 49, 52, df, b1, 95, 16, 3d, 49, bb, b1, f5, 40, 2e, 52, ad, 98, 18]) [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: U8(11) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48054, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:58Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114299 [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48054, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:58Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114300 [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48055, Proto: 1, Opcode: 10, Flags: R|I| [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48055, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] Got packet IM:TimedRequest: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U16(10000) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48055, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48055, session_id: SessionId { id: 1, peer_addr: [2003:c4:670e:dc00:12bf:67ff:fe65:e6d8]:5541 , peer_nodeid: None, is_encrypted: true } } [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] About to send packet IM:StatusResponse: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48055, Proto: 1, Opcode: 1, Flags: R|A| [2023-10-03T15:07:58Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:58Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114301 [2023-10-03T15:07:58Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48055, Proto: 1, Opcode: 8, Flags: R|A|I| [2023-10-03T15:07:58Z INFO rs_matter::transport::packet] Got packet IM:InvokeRequest: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: True [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: U8(6) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 0: len[278][15, 30, 1, 11, 0, 94, 92, cd, c, 43, d7, 1f, 61, ea, 96, ad, 94, 7e, d1, a3, 94, 24, 2, 1, 37, 3, 27, 13, 95, 6f, 66, d8, 9a, 37, 3b, 11, 18, 26, 4, be, dc, ae, 2c, 26, 5, cd, ed, 7a, 3f, 37, 6, 27, 15, a2, 59, ac, 9, 8f, 39, fd, 1, 27, 11, c0, d0, be, 2c, fb, ee, ff, 1, 18, 24, 7, 1, 24, 8, 1, 30, 9, 41, 4, 81, 61, 23, 65, bb, 6f, d9, ef, 8e, fc, 1f, c9, 1a, dd, 30, 59, 6a, 7c, 24, 2, 8c, e4, 74, 83, 15, d1, 99, d7, 8c, c0, 63, 8f, 56, 79, 55, f5, fb, 32, 2a, 7, b7, 87, 73, 24, 4, b8, 4f, f1, 66, fc, 2d, 78, b6, 82, a, 58, c1, 8a, 94, cf, 5c, c1, 6d, d, 37, a, 35, 1, 28, 1, 18, 30, 5, 14, 65, f8, 60, b3, 58, a5, 80, e1, f7, e6, 6f, e3, b9, c4, 9d, 9b, 58, cf, fb, 70, 30, 4, 14, 3f, b3, b6, 7e, 3, dd, e3, 1c, 1d, e0, 49, 80, 96, d2, e1, 4b, 84, fe, 4e, 5, 24, 2, 1, 36, 3, 4, 2, 4, 1, 18, 18, 30, b, 40, b8, 5e, 9a, 3e, 55, 88, 76, d9, a, 40, 9b, 96, e4, 49, 22, fa, 7f, 6, 9f, d3, c4, ae, dd, 48, 12, 6, cc, 80, 35, 4f, 79, bc, b6, b6, b1, b4, 36, e0, 24, 25, 1, b6, b7, e, 4a, 96, 52, 60, f, 39, 94, 6c, 82, c0, 19, dd, b3, 33, db, 9b, 3d, c2, 30, 54, 18] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 1: len[264][15, 30, 1, 11, 0, c9, d, ba, 5b, 15, 7d, 1d, cb, 2e, fc, 47, 27, 7d, 3f, 35, f7, 24, 2, 1, 37, 3, 27, 14, d8, 94, 47, 7f, d1, 55, 36, 1, 18, 26, 4, e3, ab, 51, 2c, 26, 5, 73, 4, 89, 48, 37, 6, 27, 13, 95, 6f, 66, d8, 9a, 37, 3b, 11, 18, 24, 7, 1, 24, 8, 1, 30, 9, 41, 4, 5c, 74, 12, 4, 9d, 73, 66, 67, 65, 93, 34, ac, 8e, cd, 17, c0, fd, e0, 86, 8f, 98, 3, b0, 1a, 96, cf, fc, 6f, 45, 6d, 2f, 87, dd, 78, 1c, e3, 47, cd, dd, dd, fa, c4, 91, cb, 2a, 40, d2, 6e, 5c, 1, 7e, 54, 3b, 2d, ea, 6c, 89, 39, ff, 9e, 2, 2c, b7, 9f, 37, a, 35, 1, 29, 1, 24, 2, 0, 18, 30, 5, 14, c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59, 30, 4, 14, 65, f8, 60, b3, 58, a5, 80, e1, f7, e6, 6f, e3, b9, c4, 9d, 9b, 58, cf, fb, 70, 24, 2, 61, 18, 30, b, 40, 75, a3, 67, c1, 8a, fc, 1f, 1d, 92, 35, 98, c, 5d, 58, a9, 41, fa, 39, 56, 79, 28, 7a, 2d, b2, b, 66, 68, 1, f4, 70, 5d, 53, 32, a6, c0, 1b, 33, 9e, 9e, 58, 3, 4b, ca, b7, d1, ce, 88, b0, 4, 47, 82, 8b, cc, 7f, b4, 8, 59, d2, 3c, ff, be, f, b4, 9e, 18] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 2: len[16][e0, 51, f4, 17, bb, 5c, 34, 5c, c7, 8a, 9b, 1c, 72, e, aa, 7a] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 3: U64(18446744060825763882) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 4: U16(4631) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] 255: U8(1) [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:58Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:58Z INFO rs_matter::data_model::sdm::noc] Handling command AddNOC [2023-10-03T15:07:58Z INFO rs_matter::data_model::sdm::noc] Received NOC as: Version: [0] [2] Serial Num: [0, 94, 92, cd, c, 43, d7, 1f, 61, ea, 96, ad, 94, 7e, d1, a3, 94] Signature Algorithm: ECDSA with SHA256 Issuer: Chip ICA Id: "113B379AD8666F95" Validity: Not Before: 2023-10-03 14:07:58.0 +00:00:00 Not After: 2033-09-30 15:07:57.0 +00:00:00 Subject: Chip Fabric Id: "01FD398F09AC59A2" Chip Node Id: "01FFEEFB2CBED0C0" Public Key Algorithm ECPubKey Prime256v1 Public-Key: [4, 81, 61, 23, 65, bb, 6f, d9, ef, 8e, fc, 1f, c9, 1a, dd, 30, 59, 6a, 7c, 24, 2, 8c, e4, 74, 83, 15, d1, 99, d7, 8c, c0, 63, 8f, 56, 79, 55, f5, fb, 32, 2a, 7, b7, 87, 73, 24, 4, b8, 4f, f1, 66, fc, 2d, 78, b6, 82, a, 58, c1, 8a, 94, cf, 5c, c1, 6d, d] X509v3 extensions: [3] X509v3 Basic Constraints critical: true value: Auth Key ID value: [0][65, f8, 60, b3, 58, a5, 80, e1, f7, e6, 6f, e3, b9, c4, 9d, 9b, 58, cf, fb, 70] Signature: [b8, 5e, 9a, 3e, 55, 88, 76, d9, a, 40, 9b, 96, e4, 49, 22, fa, 7f, 6, 9f, d3, c4, ae, dd, 48, 12, 6, cc, 80, 35, 4f, 79, bc, b6, b6, b1, b4, 36, e0, 24, 25, 1, b6, b7, e, 4a, 96, 52, 60, f, 39, 94, 6c, 82, c0, 19, dd, b3, 33, db, 9b, 3d, c2, 30, 54] [2023-10-03T15:07:58Z INFO rs_matter::data_model::sdm::noc] Received ICAC as: Version: [0] [2] Serial Num: [0, c9, d, ba, 5b, 15, 7d, 1d, cb, 2e, fc, 47, 27, 7d, 3f, 35, f7] Signature Algorithm: ECDSA with SHA256 Issuer: Chip Root CA Id: "013655D17F4794D8" Validity: Not Before: 2023-07-24 21:38:43.0 +00:00:00 Not After: 2038-07-24 22:38:43.0 +00:00:00 Subject: Chip ICA Id: "113B379AD8666F95" Public Key Algorithm ECPubKey Prime256v1 Public-Key: [4, 5c, 74, 12, 4, 9d, 73, 66, 67, 65, 93, 34, ac, 8e, cd, 17, c0, fd, e0, 86, 8f, 98, 3, b0, 1a, 96, cf, fc, 6f, 45, 6d, 2f, 87, dd, 78, 1c, e3, 47, cd, dd, dd, fa, c4, 91, cb, 2a, 40, d2, 6e, 5c, 1, 7e, 54, 3b, 2d, ea, 6c, 89, 39, ff, 9e, 2, 2c, b7, 9f] X509v3 extensions: [3] X509v3 Basic Constraints critical: true value: CA: true Path Len Constraint [0] Auth Key ID value: [0][c6, e6, 75, 21, 9d, 6a, 1e, bb, 8e, 66, 4f, 86, d8, f, e1, 1c, 37, 58, b8, 59] Signature: [75, a3, 67, c1, 8a, fc, 1f, 1d, 92, 35, 98, c, 5d, 58, a9, 41, fa, 39, 56, 79, 28, 7a, 2d, b2, b, 66, 68, 1, f4, 70, 5d, 53, 32, a6, c0, 1b, 33, 9e, 9e, 58, 3, 4b, ca, b7, d1, ce, 88, b0, 4, 47, 82, 8b, cc, 7f, b4, 8, 59, d2, 3c, ff, be, f, b4, 9e] [2023-10-03T15:07:58Z INFO rs_matter::fabric] MDNS Service Name: F43B1AC0CF534F57-01FFEEFB2CBED0C0 [2023-10-03T15:07:58Z INFO rs_matter::mdns::astro] Registering mDNS service F43B1AC0CF534F57-01FFEEFB2CBED0C0/Commissioned [2023-10-03T15:07:59Z INFO astro_dnssd::os::apple::register] Got reply [2023-10-03T15:07:59Z INFO astro_dnssd::os::apple::register] Reply info sent [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] About to send packet IM:InvokeResponse: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 0: False [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: [ [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 0: { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 0: [ [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: U8(62) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 2: U8(8) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 0: U8(0) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: U8(1) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 2: len[0]"" [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] ] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48055, Proto: 1, Opcode: 9, Flags: R|A| [2023-10-03T15:07:59Z INFO rs_matter::persist::fileio] Key acls: stored 28 bytes [22, 21, 36, 1, 5, 36, 2, 2, 54, 3, 7, 42, 0, 17, 0, 253, 255, 255, 255, 24, 54, 4, 24, 36, 254, 1, 24, 24] [2023-10-03T15:07:59Z INFO rs_matter::persist::fileio] Key fabrics: stored 1059 bytes [22, 21, 39, 0, 192, 208, 190, 44, 251, 238, 255, 1, 39, 1, 162, 89, 172, 9, 143, 57, 253, 1, 37, 2, 23, 18, 54, 3, 16, 65, 4, 129, 97, 35, 101, 187, 111, 217, 239, 142, 252, 31, 201, 26, 221, 48, 89, 106, 124, 36, 2, 140, 228, 116, 131, 21, 209, 153, 215, 140, 192, 99, 143, 86, 121, 85, 245, 251, 50, 42, 7, 183, 135, 115, 36, 4, 184, 79, 241, 102, 252, 45, 120, 182, 130, 10, 88, 193, 138, 148, 207, 92, 193, 109, 13, 16, 32, 30, 89, 170, 146, 201, 238, 198, 135, 226, 84, 35, 64, 176, 154, 85, 219, 163, 109, 22, 200, 57, 0, 222, 188, 130, 203, 167, 168, 86, 230, 222, 104, 24, 49, 4, 5, 1, 21, 48, 1, 17, 0, 143, 126, 137, 216, 214, 123, 10, 236, 222, 174, 109, 33, 45, 234, 163, 183, 36, 2, 1, 55, 3, 39, 20, 216, 148, 71, 127, 209, 85, 54, 1, 24, 38, 4, 83, 122, 199, 42, 38, 5, 227, 120, 205, 89, 55, 6, 39, 20, 216, 148, 71, 127, 209, 85, 54, 1, 24, 36, 7, 1, 36, 8, 1, 48, 9, 65, 4, 4, 115, 2, 216, 193, 196, 41, 4, 144, 85, 52, 106, 49, 207, 44, 126, 98, 215, 38, 106, 61, 222, 182, 10, 133, 72, 16, 217, 168, 211, 237, 80, 162, 233, 11, 181, 241, 250, 213, 27, 41, 183, 39, 193, 83, 148, 107, 100, 113, 138, 226, 66, 46, 161, 13, 252, 155, 125, 176, 203, 196, 129, 100, 212, 55, 10, 53, 1, 41, 1, 24, 48, 4, 20, 198, 230, 117, 33, 157, 106, 30, 187, 142, 102, 79, 134, 216, 15, 225, 28, 55, 88, 184, 89, 36, 2, 97, 48, 5, 20, 198, 230, 117, 33, 157, 106, 30, 187, 142, 102, 79, 134, 216, 15, 225, 28, 55, 88, 184, 89, 24, 48, 11, 64, 9, 42, 88, 106, 206, 48, 1, 138, 95, 166, 20, 204, 162, 194, 178, 60, 88, 171, 108, 117, 220, 142, 117, 159, 149, 167, 186, 19, 219, 151, 216, 64, 67, 181, 50, 80, 94, 12, 246, 28, 24, 125, 41, 192, 15, 86, 249, 10, 73, 82, 223, 177, 149, 22, 61, 73, 187, 177, 245, 64, 46, 82, 173, 152, 24, 49, 5, 8, 1, 21, 48, 1, 17, 0, 201, 13, 186, 91, 21, 125, 29, 203, 46, 252, 71, 39, 125, 63, 53, 247, 36, 2, 1, 55, 3, 39, 20, 216, 148, 71, 127, 209, 85, 54, 1, 24, 38, 4, 227, 171, 81, 44, 38, 5, 115, 4, 137, 72, 55, 6, 39, 19, 149, 111, 102, 216, 154, 55, 59, 17, 24, 36, 7, 1, 36, 8, 1, 48, 9, 65, 4, 92, 116, 18, 4, 157, 115, 102, 103, 101, 147, 52, 172, 142, 205, 23, 192, 253, 224, 134, 143, 152, 3, 176, 26, 150, 207, 252, 111, 69, 109, 47, 135, 221, 120, 28, 227, 71, 205, 221, 221, 250, 196, 145, 203, 42, 64, 210, 110, 92, 1, 126, 84, 59, 45, 234, 108, 137, 57, 255, 158, 2, 44, 183, 159, 55, 10, 53, 1, 41, 1, 36, 2, 0, 24, 48, 5, 20, 198, 230, 117, 33, 157, 106, 30, 187, 142, 102, 79, 134, 216, 15, 225, 28, 55, 88, 184, 89, 48, 4, 20, 101, 248, 96, 179, 88, 165, 128, 225, 247, 230, 111, 227, 185, 196, 157, 155, 88, 207, 251, 112, 36, 2, 97, 24, 48, 11, 64, 117, 163, 103, 193, 138, 252, 31, 29, 146, 53, 152, 12, 93, 88, 169, 65, 250, 57, 86, 121, 40, 122, 45, 178, 11, 102, 104, 1, 244, 112, 93, 83, 50, 166, 192, 27, 51, 158, 158, 88, 3, 75, 202, 183, 209, 206, 136, 176, 4, 71, 130, 139, 204, 127, 180, 8, 89, 210, 60, 255, 190, 15, 180, 158, 24, 49, 6, 22, 1, 21, 48, 1, 17, 0, 148, 146, 205, 12, 67, 215, 31, 97, 234, 150, 173, 148, 126, 209, 163, 148, 36, 2, 1, 55, 3, 39, 19, 149, 111, 102, 216, 154, 55, 59, 17, 24, 38, 4, 190, 220, 174, 44, 38, 5, 205, 237, 122, 63, 55, 6, 39, 21, 162, 89, 172, 9, 143, 57, 253, 1, 39, 17, 192, 208, 190, 44, 251, 238, 255, 1, 24, 36, 7, 1, 36, 8, 1, 48, 9, 65, 4, 129, 97, 35, 101, 187, 111, 217, 239, 142, 252, 31, 201, 26, 221, 48, 89, 106, 124, 36, 2, 140, 228, 116, 131, 21, 209, 153, 215, 140, 192, 99, 143, 86, 121, 85, 245, 251, 50, 42, 7, 183, 135, 115, 36, 4, 184, 79, 241, 102, 252, 45, 120, 182, 130, 10, 88, 193, 138, 148, 207, 92, 193, 109, 13, 55, 10, 53, 1, 40, 1, 24, 48, 5, 20, 101, 248, 96, 179, 88, 165, 128, 225, 247, 230, 111, 227, 185, 196, 157, 155, 88, 207, 251, 112, 48, 4, 20, 63, 179, 182, 126, 3, 221, 227, 28, 29, 224, 73, 128, 150, 210, 225, 75, 132, 254, 78, 5, 36, 2, 1, 54, 3, 4, 2, 4, 1, 24, 24, 48, 11, 64, 184, 94, 154, 62, 85, 136, 118, 217, 10, 64, 155, 150, 228, 73, 34, 250, 127, 6, 159, 211, 196, 174, 221, 72, 18, 6, 204, 128, 53, 79, 121, 188, 182, 182, 177, 180, 54, 224, 36, 37, 1, 182, 183, 14, 74, 150, 82, 96, 15, 57, 148, 108, 130, 192, 25, 221, 179, 51, 219, 155, 61, 194, 48, 84, 24, 53, 7, 54, 0, 4, 224, 4, 81, 4, 244, 4, 23, 4, 187, 4, 92, 4, 52, 4, 92, 4, 199, 4, 138, 4, 155, 4, 28, 4, 114, 4, 14, 4, 170, 4, 122, 24, 54, 1, 4, 208, 4, 29, 4, 87, 4, 160, 4, 95, 4, 126, 4, 35, 4, 195, 4, 57, 4, 148, 4, 99, 4, 233, 4, 192, 4, 182, 4, 29, 4, 77, 24, 24, 44, 8, 0, 44, 9, 33, 70, 52, 51, 66, 49, 65, 67, 48, 67, 70, 53, 51, 52, 70, 53, 55, 45, 48, 49, 70, 70, 69, 69, 70, 66, 50, 67, 66, 69, 68, 48, 67, 48, 24, 24] [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114301 [2023-10-03T15:07:59Z INFO rs_matter::transport::session] Dropping duplicate packet [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: (empty), session type: Encrypted, sess_id: 1, ctr: 212114302 [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48055, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Handler 0: Exchange completed [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193296 [2023-10-03T15:07:59Z INFO rs_matter::transport::session] Creating new session [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48056, Proto: 0, Opcode: 48, Flags: R|I| [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Creating new exchange: ExchangeId { id: 48056, session_id: SessionId { id: 0, peer_addr: [::ffff:192.168.178.81]:5541 , peer_nodeid: Some(9627751652375229098), is_encrypted: false } } [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] Got packet SC:CASESigma1: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: len[32][42, 2d, aa, 9f, e4, e4, 4d, 42, da, 7a, 6e, 35, ba, d6, 81, 62, a6, a4, cb, 64, c6, 65, f7, 95, c9, 96, 6d, ff, 21, 7c, 70, c0] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 2: U16(26984) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 3: len[32][78, 4b, f8, 15, 86, 1, 67, c4, 75, e6, ee, c9, ac, ee, d1, d9, ce, 7, ba, 84, 59, f1, 9d, 11, ef, ae, 78, 96, b2, ad, df, 5f] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 4: len[65][4, 1e, 93, 1b, 55, 88, a6, 23, 83, b8, f5, 42, 32, e1, 6b, a2, 9f, bc, 64, 7f, 8, 23, 4e, f1, bb, c8, f5, e7, a7, 1, 31, 6, c2, 74, 37, 26, bd, d, 4b, ea, c6, c4, 46, d1, 7a, b2, 78, 5d, dd, 19, 70, b1, 46, e2, 28, 93, ba, 2d, 6f, 60, a9, 80, a0, 7f, 9] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 5: { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: U16(5000) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 2: U16(300) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: got new exchange: ExchangeId { id: 48056, session_id: SessionId { id: 0, peer_addr: [::ffff:192.168.178.81]:5541 , peer_nodeid: Some(9627751652375229098), is_encrypted: false } } [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: exchange sent [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Handler 0: Got exchange ExchangeId { id: 48056, session_id: SessionId { id: 0, peer_addr: [::ffff:192.168.178.81]:5541 , peer_nodeid: Some(9627751652375229098), is_encrypted: false } } [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: exchange started [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] About to send packet SC:CASESigma2: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: len[32][28, 92, 2d, 1b, 41, 57, 55, 1a, 8b, d8, eb, 59, 85, 34, 68, 46, 97, 4d, 2f, 7a, 75, 68, aa, dc, 76, b8, 18, 97, b8, e8, 13, 2a] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 2: U8(2) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 3: len[65][4, 93, 9a, bd, f4, f2, e9, 50, b2, 4b, 9b, cf, 80, a9, 14, 66, aa, 6f, 14, 5, 2a, 1c, 1e, 22, 98, e9, 78, 8, 10, 8d, 30, a4, 57, 9b, 66, 80, 37, 3, 26, 6d, ea, 9d, cc, 83, 6e, 50, 30, ba, 46, 64, a9, 87, 91, 99, e9, 49, 1a, ab, 55, 6, f8, b4, 64, 8a, 47] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 4: len[654][63, e9, c8, b8, 50, ed, 10, ce, 9, 2e, a1, a6, 72, cd, d6, 3, af, c0, 40, 4a, ff, 42, 28, 5e, 92, 34, c0, 8c, bd, 18, 60, 59, ea, 5e, 19, e0, 61, b3, 53, 80, 1e, f4, 3e, a0, 88, e7, d4, ae, 2b, bc, 4a, f9, bd, b8, f6, 98, ef, 29, 12, df, f8, d8, 7a, c6, 14, eb, 95, 21, 9, 67, 60, 67, 76, 43, 6f, 2, 76, d7, 25, fd, 53, 75, 4a, 31, f8, aa, 7b, 52, 76, 41, 0, b2, 8e, 2d, a3, 88, d8, ff, dc, 7, 5d, f2, 9a, 1f, 60, 15, f2, f1, 38, 35, 45, bd, 34, b, ba, 83, a6, b5, e5, 99, 1, 3a, 46, 99, 74, aa, ea, c7, 56, f9, ef, 4d, 67, dd, 59, bd, db, b7, 26, 66, ee, 3, 5f, 72, cc, f5, d3, 2d, 3, fd, 8a, 2a, 38, 35, b3, ad, e9, 8a, 44, 10, aa, 2, 41, 85, 97, d8, 13, cd, 83, 3e, 79, d5, b0, 26, 5c, 40, 54, ef, c, 7a, b5, 65, b7, 1b, 54, 79, 7e, 26, 7e, 98, d7, 1e, 44, b6, 2d, 80, f9, fd, e8, 8d, 41, 7d, f6, 3c, 4e, eb, 5e, b7, 31, c1, 74, 79, e0, b3, 6d, 6f, 2f, 17, e2, 5f, ff, 20, 17, 54, 35, 47, 77, d1, 8b, 3b, 33, c6, 8a, 85, 17, 21, 76, f7, 16, 98, d9, 85, 8f, 41, 7c, 15, 18, d3, d0, f3, 71, bc, c2, b6, 22, 29, 68, 5e, 39, 6c, 31, c2, 57, b9, 8b, f6, 9d, ce, 91, 92, 1d, ec, d1, f6, bd, 7, 55, f6, b, ce, a7, 27, 70, 4c, d7, ca, 2f, e3, ee, 92, 47, 42, 9e, ea, 51, ba, cc, 2a, 72, 86, 2f, 55, 17, b5, 57, 11, 16, 66, 83, 76, 42, 49, 1f, bc, 39, 5e, 8a, 5a, 61, 9b, 33, 41, 56, 45, 81, 5f, c8, fd, f9, fc, 7e, cf, 3e, 12, f5, 67, f6, 89, de, 25, 91, 97, 53, 59, 89, af, 49, 75, 3e, d3, 89, 7b, bb, cf, 27, 80, df, c5, da, 42, 64, 69, 5c, 59, a6, 4a, 8e, c2, 8, 6c, 5b, 73, 58, 10, d5, f1, 81, eb, 37, 10, 2d, 57, 3c, 34, 11, 63, 94, 5c, 99, 8a, b3, 9, aa, 72, 18, 91, ae, 7d, 7b, f2, 24, 18, 46, b6, 72, 9c, 69, f6, 8a, c0, 8a, dc, 85, d1, bc, 2e, 64, cf, af, 9a, 2b, e3, 9a, 9f, 80, 2a, 4b, af, 1c, 89, 2b, f9, b2, 95, 28, b4, 93, a7, 23, 1, bc, 8e, 7a, 5d, bc, 1, 8b, a6, be, 7e, a5, ca, 2b, 9a, a1, f6, 9c, 36, f1, be, 50, b4, c1, e, f6, 16, d2, 83, 83, d0, 43, bb, 5c, 54, 5b, e6, e4, 65, e3, 24, c1, 51, f8, 5e, e5, 75, 92, f0, 49, aa, b4, 6d, 11, 57, 3d, 10, 9d, 21, 92, e8, 46, ca, 41, 6f, 78, 89, fc, 2a, 88, 8b, 20, fb, af, a1, ff, b5, 76, 34, ed, b9, 95, 97, d1, 9d, b3, 6c, 8a, fa, 7, f3, b4, 5d, 14, a1, 23, 15, 19, 9b, bf, 1e, f5, 82, f2, e0, 25, ad, bd, 96, 9, a0, 92, 93, fc, ea, e5, 19, 2e, 9a, e7, af, 8d, ac, f9, 41, e1, 49, 52, d2, 89, b5, 1e, 61, 70, 65, 90, ff, 87, 9d, f4, b, c3, c9, b9, 2e, c9, d1, 11, 4f, 8f, 5e, f0, 4f, 11, cf, 3a, c8, be, ae, c, 89, ee, e3, a3, 4a, 27, 47, 5c, 31, f6, d7, 34, cd, 19, a4, 9d, 81, 5e, a9, 6f, 1f, 4b, e1, 3e, e3, b, 2, 8c, 31, d2, 2c, 6, de, d7, ae, 70, 1e, b3, e0, b9, 33, 91, 24, 4a, 79, c4, 96, 6d, 23, b5, c9, 91, c3, 55, 5f] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48056, Proto: 0, Opcode: 49, Flags: R|A| [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193297 [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48056, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193298 [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48056, Proto: 0, Opcode: 50, Flags: R|A|I| [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] Got packet SC:CASESigma3: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] { [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] 1: len[641][20, 1e, 90, a5, be, a9, 40, a6, d7, 69, f3, a, 7b, eb, 7c, 59, 3b, ec, a1, 63, ba, 4d, 8b, 8f, 7, a2, b4, 6c, 7, 89, ed, af, cb, 31, 89, 71, 92, e8, 81, e5, 34, eb, 58, ae, f2, 9c, 4b, a9, 2, ee, 90, 6, 40, fe, e7, 90, a7, be, bd, 33, a2, 4, 39, 18, 50, af, ba, ff, 57, b5, ad, 49, 2d, 42, 63, 62, 6, 85, c8, a2, 1b, ec, d5, 6b, 8b, ab, 83, fe, 3b, db, bc, 72, 7a, dc, c3, cf, 8f, 27, 7d, a3, 3f, 60, 40, 7f, 47, 42, 50, 7c, fc, cc, 59, 23, bb, f0, 2f, 53, 59, e2, 4d, a9, 6f, c5, d3, 2c, 86, ff, 39, 9e, c5, 46, 2a, c9, 11, 39, e2, f1, 39, 4, 87, 6b, d0, a0, 3, e2, c4, c9, 7c, 78, f7, a6, 91, 6b, 19, a0, cc, 9a, 96, 36, 39, 51, b2, f1, b5, ce, b4, 56, dd, 40, 7f, 51, 47, 60, fd, 80, 5a, 2, 99, 6e, 44, bc, 98, 7f, c8, d2, 66, c9, 87, d4, b6, 65, ec, f0, 59, 1c, ff, 8, 9f, 93, 61, 71, 30, 64, af, e0, 66, 95, f9, f1, 33, e3, 10, 38, be, 10, d6, 36, f4, 4e, 4f, 79, f1, 45, e, 54, d4, bf, 2f, b9, f1, b6, 41, e6, e4, 33, cf, fc, 89, e9, 73, 10, 76, 42, 32, 82, 33, c3, dc, 84, 52, c9, ed, 52, be, dc, d8, 44, fd, d1, 43, 23, 57, b, 81, be, ec, f7, 4f, 4c, 63, f4, dd, 2a, 45, 7e, 2d, a5, 99, 2c, c9, d7, cd, 4, 70, a3, 83, c3, b6, 9e, 82, 83, 27, 29, bd, 73, 16, 63, 32, dd, 6a, f5, 7a, b, 52, 29, 94, 7f, 6e, 31, f4, bf, d0, 95, e8, 65, 2e, 45, 30, dc, b2, 79, f, c9, 88, f7, 94, 45, 7, c, df, d9, 5c, 32, 5, 68, d7, 67, a8, e8, 17, d, f2, 76, 16, a4, 31, d7, 13, c6, 44, ed, 12, e0, bd, 1c, b1, 91, 22, ce, 6c, ca, 31, b6, 78, cc, af, 48, e2, 5f, 82, ae, 64, 3, 2e, b7, de, 6c, dc, a2, e4, 95, 17, 26, 6e, 1d, 54, 6a, 6c, 3b, 44, b, 17, 51, 7b, f6, 9d, a6, a2, d9, 3d, 5, d9, 97, 15, 8e, 88, 3c, 60, ff, 78, 47, 2c, e9, ff, 3f, db, 6, a6, a5, 8b, 75, 6b, 57, bc, f3, 24, 4c, 6, f1, 45, 23, 70, 28, dd, 96, f0, 54, 51, d8, 5b, e5, 11, be, 8d, c0, 24, b9, c2, 63, 10, 83, 53, a4, 81, c7, ff, cb, 29, 18, a6, c6, 19, ce, c9, 3e, 65, 7a, eb, e3, 42, 8c, bb, 3d, 40, 4, b5, 84, 3b, 7b, 5e, 63, 45, f0, c8, e6, 4a, 5, 90, d0, 37, 54, b3, f9, aa, df, a0, e9, 4b, 68, e9, 4f, 12, e4, 77, 7c, b6, 35, d3, f8, 66, 42, 86, 25, ac, 9a, c6, a4, 4f, b7, cf, bc, 88, 61, b5, c0, c7, 73, 73, bf, 67, 21, 1c, 95, c, e3, 60, 92, d6, 6e, d8, b2, e7, 1e, ca, 7b, 75, b5, e3, 43, 2e, 8e, 57, c, 0, d5, 1d, dd, b5, 3a, db, f6, 68, 9, 33, c, f9, e0, 75, cb, dc, 3c, 6a, 79, 5c, b, d9, c2, 85, 23, d1, 62, b8, d9, 8c, 64, 5f, 8f, 69, b2, 54, 14, cc, d, cb, e1, 2b, 9c, 77, f1, fc, 7a, 77, 6, 5b, 3b, a2, e0, 91, 16, 7b, c, a0, f5, ad, 26, 55, d5, 78, e9, d1, de, 28, 7e, 9, ae, 39, 29, 77, 4e, 4d, 4, bb, 79, 2b, 84, 45, 52, 1b, 2, d7, 2d, e3] [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] } [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z ERROR rs_matter::secure_channel::case] Certificate Chain doesn't match: Invalid: [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] About to send packet SC:StatusReport: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] S16(0) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] S8(0) [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] S8(2) [2023-10-03T15:07:59Z ERROR rs_matter::tlv::parser] Invalid value found: 0 self TLVListIterator { buf: [1, 0, 0, 0, 0, 0, 2, 0], current: 8 } size 1 [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [encode] ExId: 48056, Proto: 0, Opcode: 64, Flags: R|A| [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Transport: waiting for incoming packets [2023-10-03T15:07:59Z INFO rs_matter::transport::plain_hdr] [decode] flags: SRC_ADDR_PRESENT, session type: None, sess_id: 0, ctr: 26193299 [2023-10-03T15:07:59Z INFO rs_matter::transport::proto_hdr] [decode] ExId: 48056, Proto: 0, Opcode: 16, Flags: A|I| [2023-10-03T15:07:59Z INFO rs_matter::transport::packet] Got packet SC:MRPStandAloneAck: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] TLV list: [2023-10-03T15:07:59Z INFO rs_matter::tlv::parser] --------- [2023-10-03T15:07:59Z INFO rs_matter::transport::core] Handler 0: Exchange completed ```

ivmarkov commented 11 months ago

OK the culprit is this one: [2023-10-03T15:07:59Z ERROR rs_matter::secure_channel::case] Certificate Chain doesn't match: Invalid:

... which means it fails just before it can establish a valid CASE session, and this is very different from the problem I fixed in my branch

Can you open a new issue for this? Something like "Provisioning with Alexa/Echo Hub fails with Invalid Cert error during the Case3 step".

I still don't have an Echo device, but I'll order soon. Perhaps other folks can shed some light (@kedars?).

Zercerium commented 11 months ago

yeah, opened an issue for it. #109

project-chip / rs-matter

Error: environment variable not found #106