Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Error: environment variable not found #106

Closed
Zercerium opened this issue Oct 1, 2023 · 14 comments
Closed

Error: environment variable not found #106

Zercerium opened this issue Oct 1, 2023 · 14 comments

Comments

@Zercerium
Copy link

  1. generated a new project with cargo generate esp-rs/esp-idf-template cargo
  2. added rs-matter as a dependency
  3. copied the onoff_light example code into it
  4. 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
Copy link
Contributor

ivmarkov commented Oct 2, 2023

  • 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
Copy link
Author

Zercerium commented Oct 2, 2023

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

@ivmarkov
Copy link
Contributor

ivmarkov commented Oct 2, 2023

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
Copy link
Author

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
Copy link
Contributor

ivmarkov commented Oct 2, 2023

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

@ivmarkov
Copy link
Contributor

ivmarkov commented Oct 2, 2023

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
Copy link
Author

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
Copy link
Contributor

ivmarkov commented Oct 2, 2023

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

@ivmarkov
Copy link
Contributor

ivmarkov commented Oct 2, 2023

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

@Zercerium
Copy link
Author

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
Copy link
Contributor

ivmarkov commented Oct 3, 2023

@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
Copy link
Author

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
Copy link
Contributor

ivmarkov commented Oct 3, 2023

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
Copy link
Author

yeah, opened an issue for it. #109

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants