search

Luligu / matterbridge

Matterbridge plugin manager for Matter
https://github.com/Luligu/matterbridge/blob/main/README.md
Apache License 2.0
207 stars 24 forks source link

Trying to Pair With Google after Apple Home #11

Closed nitaybz closed 6 months ago

nitaybz commented 6 months ago

Hi, I'm trying to pair the bridge with Google Home after I got it paired with Apple Home. I've turned on pairing mode in Apple Home and when trying to pair from Google I see the following error in the logs:

2024-04-11 15:23:26.393 ERROR  InteractionMessenger RetransmissionLimitReachedError
    at MessageExchange.retransmitMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/MessageExchange.js:274:36)
    at TimerNode.callback (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/MessageExchange.js:294:20)
    at Timeout._onTimeout (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter-node.js/dist/esm/time/TimeNode.js:51:12)
    at listOnTimeout (node:internal/timers:573:17)
    at process.processTimers (node:internal/timers:514:7)
2024-04-11 15:23:26.395 ERROR  ExchangeManager      The previous message has not been acked yet, cannot send a new message.
    at MessageExchange.send (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/MessageExchange.js:182:13)
    at InteractionServerMessenger.send (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/interaction/InteractionMessenger.js:61:26)
    at InteractionServerMessenger.sendStatus (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/interaction/InteractionMessenger.js:64:17)
    at InteractionServerMessenger.handleRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/interaction/InteractionMessenger.js:158:20)
    at async InteractionServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/interaction/InteractionServer.js:72:5)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)

In the app it says that I might need to restart the bridge.

nitaybz commented 6 months ago

I actually have the same issue after removing it from Apple Home and directly trying to pair it with Google Home

nitaybz commented 6 months ago

I also see a lot of this:

2024-04-11 15:32:27.603 ERROR  PaseServer           An error occurred during the PASE commissioning. Pase server: Pairing already in progress (PASE session exists), ignoring new exchange.
    at PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:61:13)
    at PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:18)
    at SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:39)
    at ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:31)
2024-04-11 15:32:27.612 ERROR  PaseServer           An error occurred during the PASE commissioning. Pase server: Pairing already in progress (PASE session exists), ignoring new exchange.
    at PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:61:13)
    at PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:18)
    at SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:39)
    at ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:31)
2024-04-11 15:32:27.613 INFO   SessionManager       End insecure session insecure/17394044724347053390
2024-04-11 15:32:27.619 ERROR  PaseServer           An error occurred during the PASE commissioning. Pase server: Pairing already in progress (PASE session exists), ignoring new exchange.
    at PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:61:13)
    at PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:18)
    at SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:39)
    at ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:31)
2024-04-11 15:32:27.620 INFO   SessionManager       End insecure session insecure/10453949202559219205
2024-04-11 15:32:27.629 INFO   SessionManager       End insecure session insecure/2706298955337054352
2024-04-11 15:32:27.630 ERROR  PaseServer           An error occurred during the PASE commissioning. Pase server: Pairing already in progress (PASE session exists), ignoring new exchange.
    at PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:61:13)
    at PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:18)
    at SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:39)
    at ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:31)
2024-04-11 15:32:27.638 ERROR  PaseServer           An error occurred during the PASE commissioning. Pase server: Pairing already in progress (PASE session exists), ignoring new exchange.
    at PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:61:13)
    at PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:18)
    at SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:39)
    at ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:31)
2024-04-11 15:32:27.640 INFO   SessionManager       End insecure session insecure/10546733312629297252
2024-04-11 15:32:27.745 INFO   SessionManager       End insecure session insecure/397132435925300513
2024-04-11 15:32:52.241 INFO   FailsafeContext      Failsafe timer expired, Reset fabric builder.
2024-04-11 15:32:52.242 INFO   SecureSession        Register Session secure/12816 to send a close when exchange is ended.
2024-04-11 15:32:57.792 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-11 15:32:57.958 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:32:57.959 INFO   SessionManager       End insecure session insecure/7102287235908134970
2024-04-11 15:32:57.959 INFO   PaseServer           Received pairing request from udp://fd01:fecf:925b:bd44:43e:564:3260:b882:59408.
2024-04-11 15:32:58.145 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:32:58.146 INFO   SessionManager       End insecure session insecure/359739372456370485
2024-04-11 15:32:58.148 INFO   PaseServer           Received pairing request from udp://fd01:fecf:925b:bd44:43e:564:3260:b882:59408.
2024-04-11 15:32:58.319 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:32:58.320 INFO   SessionManager       End insecure session insecure/4178568792887568782
2024-04-11 15:32:58.325 INFO   PaseServer           Received pairing request from udp://fd01:fecf:925b:bd44:43e:564:3260:b882:59408.
2024-04-11 15:32:58.492 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:32:58.493 INFO   SessionManager       End insecure session insecure/4723738934847395066
2024-04-11 15:32:58.494 INFO   PaseServer           Received pairing request from udp://10.0.0.23:57519.
2024-04-11 15:32:58.671 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:32:58.672 INFO   SessionManager       End insecure session insecure/10367394914459512464
2024-04-11 15:33:27.929 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-11 15:33:28.101 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:33:28.101 INFO   SessionManager       End insecure session insecure/18388598249039254288
2024-04-11 15:33:28.102 INFO   PaseServer           Received pairing request from udp://fd01:fecf:925b:bd44:43e:564:3260:b882:59408.
2024-04-11 15:33:28.276 ERROR  PaseServer           An error occurred during the PASE commissioning. (1/2) Received general error status for protocol 0
    at PaseServerMessenger.throwIfErrorStatusReport (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:81:13)
    at PaseServerMessenger.nextMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:28:10)
    at async PaseServerMessenger.nextMessageDecoded (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelMessenger.js:36:27)
    at async PaseServer.handlePairingRequest (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:97:26)
    at async PaseServer.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/session/pase/PaseServer.js:44:7)
    at async SecureChannelProtocol.onNewExchange (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/securechannel/SecureChannelProtocol.js:94:11)
    at async ExchangeManager.onMessage (file:///usr/lib/node_modules/matterbridge/node_modules/@project-chip/matter.js/dist/esm/protocol/ExchangeManager.js:173:9)
2024-04-11 15:33:28.278 INFO   SessionManager       End insecure session insecure/16981438782290704136
nitaybz commented 6 months ago

I succeeded pairing with Alexa app. This is what I get when trying to pair with Google, it seems like it's working, but the app is failing:

4-04-11 15:56:29.949 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-11 15:56:30.003 INFO   PaseServer           Session 23243 created with udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-11 15:56:30.024 INFO   SessionManager       End insecure session insecure/13424002532189725176
2024-04-11 15:56:31.611 INFO   MatterDevice         Announce done fabric: 1 fabricIndex: 1
2024-04-11 15:56:31.611 INFO   Operationa~alsServer addNoc success, adminVendorId 4996, caseAdminSubject 3801501750998556451
2024-04-11 15:56:31.876 INFO   CaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408
2024-04-11 15:56:31.900 INFO   CaseServer           session 23244 created with udp://fe80::c2:be55:adab:4e02%br0:59408 for Fabric 00000000FF35B12A(index 1) and PeerNode 34C1A5B3894B5F23
2024-04-11 15:56:31.912 INFO   MatterDevice         All announcements expired
2024-04-11 15:56:31.912 INFO   SecureSession        Register Session secure/23243 to send a close when exchange is ended.
2024-04-11 15:56:31.913 INFO   GeneralCom~erHandler Commissioning completed on fabric #1 as node #4281708842.
2024-04-11 15:56:31.915 INFO   SessionManager       End insecure session insecure/17683811884512136333
2024-04-11 15:56:32.320 INFO   InteractionServer    Error 0x1/0x4 while invoking command: (1) No commissioning window is opened that could be revoked.
2024-04-11 15:56:32.606 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:63628.
2024-04-11 15:56:32.670 INFO   PaseServer           Session 23245 created with udp://fe80::c2:be55:adab:4e02%br0:63628.
2024-04-11 15:56:32.682 INFO   SessionManager       End insecure session insecure/1501639142601015700
2024-04-11 15:56:39.526 INFO   SessionManager       End PASE session secure/23243

in the app I get this: image

tammeryousef1006 commented 6 months ago

try to use all plug-ins except matterbridge-example-accessory-platform if its in use

nitaybz commented 6 months ago

I'm only using matterbridge-zigbee2mqtt. but I also tried to pair it with Google when there was no plugins at all

tammeryousef1006 commented 6 months ago

i had success to pair all plug-ins including zigbee2mqtt with google , they were looking offline all but without errors while pairing , also i had success with smartthings too , ewelink matter only accepted switches devices and ignored the rest , tuya accepted the pairing only and showed one device as light . if you face problems during pairing steps try to force close app at mobile and make airplane mode on and off , then try to connect again , thats helped me alot while facing errors

nitaybz commented 6 months ago

Thanks, I will try that. I successfuly connected it with Alexa and Home Assistant, Alexa shows all accessories but not the switches.

nitaybz commented 6 months ago

Killing Google app (on iPhone) didn't help, neither setting airplane mode. I have the same issue for some reason.

tammeryousef1006 commented 6 months ago

oh im using android not iphone , home assistant already pair with all devices even with new one added to zigbee2mqtt

Luligu commented 6 months ago

Hi, thanks you so much for the test. I would also try to directly pair Matterbridge without turning on pairing mode in Apple Home. To do so just reset Matterbridge: mattebridge -reset so it can pair with some other controller directly. Sometimes the sharing from one controller can make things more complicated.

nitaybz commented 6 months ago

Unfortunately that didn't work either. I tried to remove all pairings and pair directly to Google. Even tried from a different iPhone but it didn't work. Apple Home, Alexa, Home Assistant were flawless, connected every time. It's just Google that is not succeeding.

By the way, I also tried Tuya/SmartLife and it didn't work, but the error was different, maybe for another issue.

tammeryousef1006 commented 6 months ago

which zigbee devices are you trying ? can you try one different device each time ? in my case i was trying zigbee plug which worked well but i got problems with motion sensor and contact sensor , as i understand that some product may work well and others may fail , so trying one by one will help you to limit and address the issue i guess .

nitaybz commented 6 months ago

I even tried without any plugins, just the bridge itself🤷‍♂️

nitaybz commented 6 months ago

I tried again, factory reset on matterbridge, no plugins no nothing. tried to pair with Google directly (from iPhone) for the first time and got this:

2024-04-12 19:30:28.508 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-12 19:30:28.709 INFO   PaseServer           Session 5918 created with udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-12 19:30:28.715 INFO   SessionManager       End insecure session insecure/7666787256739068681
2024-04-12 19:30:30.546 INFO   MatterDevice         Announce done fabric: 1 fabricIndex: 1
2024-04-12 19:30:30.551 INFO   Operationa~alsServer addNoc success, adminVendorId 4996, caseAdminSubject 3801501750998556451
2024-04-12 19:30:30.809 INFO   CaseServer           Received pairing request from udp://10.0.0.23:57519
2024-04-12 19:30:30.836 INFO   CaseServer           session 5919 created with udp://10.0.0.23:57519 for Fabric 000000003B94D160(index 1) and PeerNode 34C1A5B3894B5F23
2024-04-12 19:30:30.849 INFO   MatterDevice         All announcements expired
2024-04-12 19:30:30.851 INFO   SecureSession        Register Session secure/5918 to send a close when exchange is ended.
2024-04-12 19:30:30.854 INFO   GeneralCom~erHandler Commissioning completed on fabric #1 as node #999608672.
2024-04-12 19:30:30.858 INFO   SessionManager       End insecure session insecure/14683259489374658335
2024-04-12 19:30:31.189 INFO   InteractionServer    Error 0x1/0x4 while invoking command: (1) No commissioning window is opened that could be revoked.
2024-04-12 19:30:31.525 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:50634.
2024-04-12 19:30:31.576 INFO   PaseServer           Session 5920 created with udp://fe80::c2:be55:adab:4e02%br0:50634.
2024-04-12 19:30:31.585 INFO   SessionManager       End insecure session insecure/6954613380324891413
2024-04-12 19:30:38.228 INFO   SessionManager       End PASE session secure/5918

I also tried pairing with either Tuya Smart app or SmartLife app (on iPhone) after factory reset and I'm getting this:

2024-04-12 19:37:58.201 INFO   PaseServer           Received pairing request from udp://10.0.0.23:54227.
2024-04-12 19:37:58.287 INFO   PaseServer           Session 19848 created with udp://10.0.0.23:54227.
2024-04-12 19:37:58.293 INFO   SessionManager       End insecure session insecure/3409232073326961245

which looks ok but the app show immediate error response.

I'll try to get a hold on an android phone and see if it acts differently

tammeryousef1006 commented 6 months ago

i had an issue with factory reset before because i was messing up alot with installing different packages , im using proxmox which easier to run containers & virtual machine , i stopped working on the container that had much messed up and started new one from scratches , after i done that the factory reset worked flawlessly and the bridge become faster to lunch , so if you can try to make new container and restest it then this might solve your issue , and make sure all packages are up to date .

nitaybz commented 6 months ago

but it's not running in a container, I followed the installations process mentioned in the README

tammeryousef1006 commented 6 months ago

after you pair with google , does the process end successfully ? or you got an error something went wrong ? im talking about the mobile app .

nitaybz commented 6 months ago

I get an error, see in the logs above. first it pairs with Apple's Keychain which is vendorId: 4996. than it fails when trying to pair with Google: InteractionServer Error 0x1/0x4 while invoking command: (1) No commissioning window is opened that could be revoked.

nitaybz commented 6 months ago

I activated debug logs and started the process again after factory reset, maybe it can shed some more light:

[19:58:12.989] [Matterbridge] The frontend sent * /settings 
[19:58:13.039] [Matterbridge] The frontend sent /api/login  
[19:58:13.058] [Matterbridge] The frontend sent /api/matterbridge-info 
2024-04-12 19:58:35.217 DEBUG  MessageExchange      New exchange protocol: 0 id: 1586 session: insecure/15983171676618488393 peerSessionId: 0 active threshold ms: 4000 active interval ms: 500 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.219 DEBUG  MessageExchange      Message « id: 0/1586/143012560 type: 0/32 acked: undefined reqAck: true duplicate: false payload: 15300120d3bccb80abceb2f487ae4fcfeef9699dc7c8952ff6e932d1082c2ba3eb7804f925021cf22403002804350525012c0125022c012503a00f24041124050b2606000003012407011818
2024-04-12 19:58:35.220 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-12 19:58:35.223 DEBUG  ExchangeManager      Message » id: 0/1586/6880036 type: 0/33 acked: 143012560 reqAck: true duplicate: false payload: 15300120d3bccb80abceb2f487ae4fcfeef9699dc7c8952ff6e932d1082c2ba3eb7804f9300220dd0b509a6fcffe4761bfd66bb0225d368ad1c5283d5279e506b09fee349df1c42503d38a35042501e803300220de98ad6fe147f00bd7be7556c097ac04a0c217dde1a4fa8bda603b24aa0f21ca18350525012c0125022c012503a00f1818
2024-04-12 19:58:35.239 DEBUG  MessageExchange      Message « id: 0/1586/143012561 type: 0/34 acked: 6880036 reqAck: true duplicate: false payload: 153001410455fc7803ae14834131a06e5a5921a0c896dccd3675793ebbce1b3313041a688906e46a0e3ba87ff1ec80cad41a3a8880c64798bddfe2d83e7bde237ba33184bb18
2024-04-12 19:58:35.279 DEBUG  ExchangeManager      Message » id: 0/1586/6880037 type: 0/35 acked: 143012561 reqAck: true duplicate: false payload: 1530014104200dd047d4c7677902cb1a16c3781659d41254dffe7fc1600a28c435e42cca3e720080f6a409843b4d74ad3a7ff30a038a10d0b532c0cb158b0eb3b4e98eb57730022068e35804a715e1d14d7ee8151f01613f3110362d65479a88716933b0fe99ede418
2024-04-12 19:58:35.349 DEBUG  MessageExchange      Message « id: 0/1586/143012562 type: 0/36 acked: 6880037 reqAck: true duplicate: false payload: 1530012082a672cb107d3cd8a34c24eac4fe53005954cf3f85a81db071252001f2e8572a18
2024-04-12 19:58:35.351 DEBUG  SecureSession        Created secure PASE session for fabric index undefined secure/35539 idleIntervalMs: 300 activeIntervalMs: 300 activeThresholdMs: 4000
2024-04-12 19:58:35.351 INFO   PaseServer           Session 35539 created with udp://fe80::c2:be55:adab:4e02%br0:59408.
2024-04-12 19:58:35.352 DEBUG  ExchangeManager      Message » id: 0/1586/6880038 type: 0/64 acked: 143012562 reqAck: true duplicate: false payload: 0000000000000000
2024-04-12 19:58:35.359 DEBUG  MessageExchange      Message « id: 0/1586/143012563 type: 0/16 acked: 6880038 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.359 INFO   SessionManager       End insecure session insecure/15983171676618488393
2024-04-12 19:58:35.360 DEBUG  MessageExchange      New exchange protocol: 1 id: 1587 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.361 DEBUG  MessageExchange      Message « id: 35539/1587/248908302 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024031d2404031818290324ff0b18
2024-04-12 19:58:35.362 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3), events:none isFabricFiltered=true
2024-04-12 19:58:35.363 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3)=[1] (version=994450493)
2024-04-12 19:58:35.365 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 38 bytes
2024-04-12 19:58:35.365 DEBUG  ExchangeManager      Message » id: 35539/1587/198150306 type: 1/5 acked: 248908302 reqAck: true duplicate: false payload: 15360115350126003d1c463b370124020024031d240403183602040118181818280424ff0b18
2024-04-12 19:58:35.394 DEBUG  MessageExchange      Message « id: 35539/1587/248908303 type: 1/1 acked: 198150306 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.395 DEBUG  ExchangeManager      Message » id: 35539/1587/198150307 type: 0/16 acked: 248908303 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.396 DEBUG  MessageExchange      New exchange protocol: 1 id: 1588 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.396 DEBUG  MessageExchange      Message « id: 35539/1588/248908304 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024031d2404011818290324ff0b18
2024-04-12 19:58:35.396 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-rootdevice(0x0)/Descriptor(0x1d)/serverList(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:35.397 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/Descriptor(0x1d)/serverList(0x1)=[29,31,40,48,51,60,62,63] (version=994450493)
2024-04-12 19:58:35.397 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 52 bytes
2024-04-12 19:58:35.397 DEBUG  ExchangeManager      Message » id: 35539/1588/198150308 type: 1/5 acked: 248908304 reqAck: true duplicate: false payload: 15360115350126003d1c463b370124020024031d240401183602041d041f042804300433043c043e043f18181818280424ff0b18
2024-04-12 19:58:35.407 DEBUG  MessageExchange      Message « id: 35539/1588/248908305 type: 1/1 acked: 198150308 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.408 DEBUG  ExchangeManager      Message » id: 35539/1588/198150309 type: 0/16 acked: 248908305 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.409 DEBUG  MessageExchange      New exchange protocol: 1 id: 1589 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.409 DEBUG  MessageExchange      Message « id: 35539/1589/248908306 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020124031d2404011818290324ff0b18
2024-04-12 19:58:35.410 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-aggregator(0x1)/Descriptor(0x1d)/serverList(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:35.410 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-aggregator(0x1)/Descriptor(0x1d)/serverList(0x1)=[29,40] (version=2776645312)
2024-04-12 19:58:35.410 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 40 bytes
2024-04-12 19:58:35.410 DEBUG  ExchangeManager      Message » id: 35539/1589/198150310 type: 1/5 acked: 248908306 reqAck: true duplicate: false payload: 1536011535012600c03e80a5370124020124031d240401183602041d042818181818280424ff0b18
2024-04-12 19:58:35.416 DEBUG  MessageExchange      Message « id: 35539/1589/248908307 type: 1/1 acked: 198150310 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.416 DEBUG  ExchangeManager      Message » id: 35539/1589/198150311 type: 0/16 acked: 248908307 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.549 DEBUG  MessageExchange      New exchange protocol: 1 id: 1590 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.550 DEBUG  MessageExchange      Message « id: 35539/1590/248908308 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024031d2404031818290324ff0b18
2024-04-12 19:58:35.551 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3), events:none isFabricFiltered=true
2024-04-12 19:58:35.551 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3)=[1] (version=994450493)
2024-04-12 19:58:35.552 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 38 bytes
2024-04-12 19:58:35.553 DEBUG  ExchangeManager      Message » id: 35539/1590/198150312 type: 1/5 acked: 248908308 reqAck: true duplicate: false payload: 15360115350126003d1c463b370124020024031d240403183602040118181818280424ff0b18
2024-04-12 19:58:35.562 DEBUG  MessageExchange      Message « id: 35539/1590/248908309 type: 1/1 acked: 198150312 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.564 DEBUG  ExchangeManager      Message » id: 35539/1590/198150313 type: 0/16 acked: 248908309 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.567 DEBUG  MessageExchange      New exchange protocol: 1 id: 1591 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.568 DEBUG  MessageExchange      Message « id: 35539/1591/248908310 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024031d2404011818290324ff0b18
2024-04-12 19:58:35.569 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-rootdevice(0x0)/Descriptor(0x1d)/serverList(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:35.570 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/Descriptor(0x1d)/serverList(0x1)=[29,31,40,48,51,60,62,63] (version=994450493)
2024-04-12 19:58:35.571 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 52 bytes
2024-04-12 19:58:35.571 DEBUG  ExchangeManager      Message » id: 35539/1591/198150314 type: 1/5 acked: 248908310 reqAck: true duplicate: false payload: 15360115350126003d1c463b370124020024031d240401183602041d041f042804300433043c043e043f18181818280424ff0b18
2024-04-12 19:58:35.579 DEBUG  MessageExchange      Message « id: 35539/1591/248908311 type: 1/1 acked: 198150314 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.580 DEBUG  ExchangeManager      Message » id: 35539/1591/198150315 type: 0/16 acked: 248908311 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.583 DEBUG  MessageExchange      New exchange protocol: 1 id: 1592 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.584 DEBUG  MessageExchange      Message « id: 35539/1592/248908312 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020124031d2404011818290324ff0b18
2024-04-12 19:58:35.585 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-aggregator(0x1)/Descriptor(0x1d)/serverList(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:35.585 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-aggregator(0x1)/Descriptor(0x1d)/serverList(0x1)=[29,40] (version=2776645312)
2024-04-12 19:58:35.586 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 40 bytes
2024-04-12 19:58:35.587 DEBUG  ExchangeManager      Message » id: 35539/1592/198150316 type: 1/5 acked: 248908312 reqAck: true duplicate: false payload: 1536011535012600c03e80a5370124020124031d240401183602041d042818181818280424ff0b18
2024-04-12 19:58:35.593 DEBUG  MessageExchange      Message « id: 35539/1592/248908313 type: 1/1 acked: 198150316 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.594 DEBUG  ExchangeManager      Message » id: 35539/1592/198150317 type: 0/16 acked: 248908313 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.598 DEBUG  MessageExchange      New exchange protocol: 1 id: 1593 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.598 DEBUG  MessageExchange      Message « id: 35539/1593/248908314 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 153600172403312504fcff181724020024033024040018172402002403302404011817240200240330240402181724020024033024040318172402002403282404021817240200240328240404181724033124040318172402002403381818280324ff0b18
2024-04-12 19:58:35.600 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:*/0x31/0xfffc, MA-rootdevice(0x0)/GeneralCommissioning(0x30)/breadcrumb(0x0), MA-rootdevice(0x0)/GeneralCommissioning(0x30)/basicCommissioningInfo(0x1), MA-rootdevice(0x0)/GeneralCommissioning(0x30)/regulatoryConfig(0x2), MA-rootdevice(0x0)/GeneralCommissioning(0x30)/locationCapability(0x3), MA-rootdevice(0x0)/BasicInformation(0x28)/vendorId(0x2), MA-rootdevice(0x0)/BasicInformation(0x28)/productId(0x4), */0x31/0x3, MA-rootdevice(0x0)/unknown(0x38)/*, events:none isFabricFiltered=false
2024-04-12 19:58:35.601 DEBUG  InteractionServer    Read from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: */0x31/0xfffc: */0x31/0xfffc: ignore non-existing attribute
2024-04-12 19:58:35.601 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/breadcrumb(0x0)="0" (version=272694503)
2024-04-12 19:58:35.602 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/basicCommissioningInfo(0x1)={"failSafeExpiryLengthSeconds":60,"maxCumulativeFailsafeSeconds":900} (version=272694503)
2024-04-12 19:58:35.602 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/regulatoryConfig(0x2)=1 (version=272694503)
2024-04-12 19:58:35.603 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/locationCapability(0x3)=2 (version=272694503)
2024-04-12 19:58:35.603 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/BasicInformation(0x28)/vendorId(0x2)=65521 (version=2944407904)
2024-04-12 19:58:35.604 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/BasicInformation(0x28)/productId(0x4)=32768 (version=2944407904)
2024-04-12 19:58:35.604 DEBUG  InteractionServer    Read from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: */0x31/0x3: */0x31/0x3: ignore non-existing attribute
2024-04-12 19:58:35.604 DEBUG  InteractionServer    Read from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/unknown(0x38)/*: MA-rootdevice(0x0)/unknown(0x38)/*: ignore non-existing attribute
2024-04-12 19:58:35.605 DEBUG  InteractionMessenger Sending DataReport chunk with 6 attributes and 0 events: 175 bytes
2024-04-12 19:58:35.606 DEBUG  ExchangeManager      Message » id: 35539/1593/198150318 type: 1/5 acked: 248908314 reqAck: true duplicate: false payload: 1536011535012600e7fc401037012402002403302404001824020018181535012600e7fc4010370124020024033024040118350224003c250184031818181535012600e7fc401037012402002403302404021824020118181535012600e7fc401037012402002403302404031824020218181535012600601980af3701240200240328240402182502f1ff18181535012600601980af37012402002403282404041825020080181818280424ff0b18
2024-04-12 19:58:35.612 DEBUG  MessageExchange      Message « id: 35539/1593/248908315 type: 1/1 acked: 198150318 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.612 DEBUG  ExchangeManager      Message » id: 35539/1593/198150319 type: 0/16 acked: 248908315 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.614 DEBUG  MessageExchange      New exchange protocol: 1 id: 1594 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.615 DEBUG  MessageExchange      Message « id: 35539/1594/248908316 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 15360017240200240330240404181724020024034624040618172402002403462404071818280324ff0b18
2024-04-12 19:58:35.616 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: attributes:MA-rootdevice(0x0)/GeneralCommissioning(0x30)/supportsConcurrentConnection(0x4), MA-rootdevice(0x0)/unknown(0x46)/0x6, MA-rootdevice(0x0)/unknown(0x46)/0x7, events:none isFabricFiltered=false
2024-04-12 19:58:35.617 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/supportsConcurrentConnection(0x4)=true (version=272694503)
2024-04-12 19:58:35.617 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/unknown(0x46)/0x6: unsupported path: Status=195
2024-04-12 19:58:35.618 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/unknown(0x46)/0x7: unsupported path: Status=195
2024-04-12 19:58:35.619 DEBUG  InteractionMessenger Sending DataReport chunk with 3 attributes and 0 events: 81 bytes
2024-04-12 19:58:35.619 DEBUG  ExchangeManager      Message » id: 35539/1594/198150320 type: 1/5 acked: 248908316 reqAck: true duplicate: false payload: 1536011535012600e7fc40103701240200240330240404182902181815350037002402002403462404061835012400c318181815350037002402002403462404071835012400c318181818280424ff0b18
2024-04-12 19:58:35.626 DEBUG  MessageExchange      Message « id: 35539/1594/248908317 type: 1/1 acked: 198150320 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:35.628 DEBUG  ExchangeManager      Message » id: 35539/1594/198150321 type: 0/16 acked: 248908317 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.631 DEBUG  MessageExchange      New exchange protocol: 1 id: 1595 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.631 DEBUG  MessageExchange      Message « id: 35539/1595/248908318 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013024020018350124003c24010418181824ff0b18
2024-04-12 19:58:35.634 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/armFailSafe(0x0), suppressResponse=false
2024-04-12 19:58:35.637 DEBUG  CommandServer        Invoke armFailSafe with data {"expiryLengthSeconds":60,"breadcrumb":4}
2024-04-12 19:58:35.641 DEBUG  FailsafeContext      Arm failSafe timer for 60s.
2024-04-12 19:58:35.642 DEBUG  CommissioningServer  Storing attribute breadcrumb in cluster GeneralCommissioning (48)
2024-04-12 19:58:35.642 DEBUG  CommandServer        Invoke armFailSafe response : {"errorCode":0,"debugText":""}
2024-04-12 19:58:35.643 DEBUG  ExchangeManager      Message » id: 35539/1595/198150322 type: 1/9 acked: 248908318 reqAck: true duplicate: false payload: 152800360115350037002400002401302402011835012400002c01001818181824ff0b18
2024-04-12 19:58:35.651 DEBUG  MessageExchange      New exchange protocol: 1 id: 1596 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.651 DEBUG  MessageExchange      Message « id: 35539/1596/248908319 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 152800280136021537002400002401302402021835012400012c01024c4224020518181824ff0b18
2024-04-12 19:58:35.653 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/setRegulatoryConfig(0x2), suppressResponse=false
2024-04-12 19:58:35.654 DEBUG  CommandServer        Invoke setRegulatoryConfig with data {"newRegulatoryConfig":1,"countryCode":"LB","breadcrumb":5}
2024-04-12 19:58:35.655 DEBUG  CommissioningServer  Storing attribute location in cluster BasicInformation (40)
2024-04-12 19:58:35.655 DEBUG  CommissioningServer  Storing attribute breadcrumb in cluster GeneralCommissioning (48)
2024-04-12 19:58:35.656 DEBUG  CommandServer        Invoke setRegulatoryConfig response : {"errorCode":0,"debugText":""}
2024-04-12 19:58:35.657 DEBUG  ExchangeManager      Message » id: 35539/1596/198150323 type: 1/9 acked: 248908319 reqAck: true duplicate: false payload: 152800360115350037002400002401302402031835012400002c01001818181824ff0b18
2024-04-12 19:58:35.658 DEBUG  MessageExchange      Message « id: 35539/1595/248908320 type: 0/16 acked: 198150322 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.665 DEBUG  MessageExchange      New exchange protocol: 1 id: 1597 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.666 DEBUG  MessageExchange      Message « id: 35539/1597/248908321 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e24020218350124000218181824ff0b18
2024-04-12 19:58:35.667 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/certificateChainRequest(0x2), suppressResponse=false
2024-04-12 19:58:35.667 DEBUG  CommandServer        Invoke certificateChainRequest with data {"certificateType":2}
2024-04-12 19:58:35.669 DEBUG  CommandServer        Invoke certificateChainRequest response : {"certificate":"308201b130820157a003020102020101300a06082a8648ce3d040302301a3118301606035504030c0f4d6174746572205465737420504141301e170d3233303431333136353734395a170d3334303431303136353734395a3042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c04464646313059301306072a8648ce3d020106082a8648ce3d03010703420004fd541c15527e0f19bc1baf26206d8ea23189fb6b02cba341c67d8b0d9b832328aa6bfd540a9df0b1132bcfcf3ca3eb501f4190fee765db3aae17693081c7e749a366306430120603551d130101ff040830060101ff020100300e0603551d0f0101ff040403020106301d0603551d0e041604148d48c7af9a46f9a5fad142a9607059ddab0a89b9301f0603551d23041830168014785ce705b86b8f4e6fc793aa60cb43ea696882d5300a06082a8648ce3d040302034800304502203b2f118efdb5b02af539925a9466714874be8767e5e9e19c6227cee2deaf8f92022100f28a686ebb8b3cc2ade78c477a7aa670ef24bdaa3d5d44ce8d946b0ca10d47f3"}
2024-04-12 19:58:35.670 DEBUG  ExchangeManager      Message » id: 35539/1597/198150324 type: 1/9 acked: 248908321 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402031835013100b501308201b130820157a003020102020101300a06082a8648ce3d040302301a3118301606035504030c0f4d6174746572205465737420504141301e170d3233303431333136353734395a170d3334303431303136353734395a3042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c04464646313059301306072a8648ce3d020106082a8648ce3d03010703420004fd541c15527e0f19bc1baf26206d8ea23189fb6b02cba341c67d8b0d9b832328aa6bfd540a9df0b1132bcfcf3ca3eb501f4190fee765db3aae17693081c7e749a366306430120603551d130101ff040830060101ff020100300e0603551d0f0101ff040403020106301d0603551d0e041604148d48c7af9a46f9a5fad142a9607059ddab0a89b9301f0603551d23041830168014785ce705b86b8f4e6fc793aa60cb43ea696882d5300a06082a8648ce3d040302034800304502203b2f118efdb5b02af539925a9466714874be8767e5e9e19c6227cee2deaf8f92022100f28a686ebb8b3cc2ade78c477a7aa670ef24bdaa3d5d44ce8d946b0ca10d47f31818181824ff0b18
2024-04-12 19:58:35.672 DEBUG  MessageExchange      Message « id: 35539/1596/248908322 type: 0/16 acked: 198150323 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.676 DEBUG  MessageExchange      New exchange protocol: 1 id: 1598 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.677 DEBUG  MessageExchange      Message « id: 35539/1598/248908323 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e24020218350124000118181824ff0b18
2024-04-12 19:58:35.679 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/certificateChainRequest(0x2), suppressResponse=false
2024-04-12 19:58:35.679 DEBUG  CommandServer        Invoke certificateChainRequest with data {"certificateType":1}
2024-04-12 19:58:35.680 DEBUG  CommandServer        Invoke certificateChainRequest response : {"certificate":"308201ec30820192a003020102020102300a06082a8648ce3d0403023042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c0446464631301e170d3233303431333136353734395a170d3334303431303136353734395a3058312a302806035504030c216e6f64652d6d61747465722044657620444143203078464646312f30783830303031143012060a2b0601040182a27c02010c044646463131143012060a2b0601040182a27c02020c04383030303059301306072a8648ce3d020106082a8648ce3d03010703420004cfd5b003d0513cab1f4e416ef9cc9b8e1ce20ae54f56c03f43021bc203724f2c33c32dff9547e205acbcf9897ec354b3d5e9dfb566c42f1c2b7243f1187ca1cca3633061300f0603551d130101ff04053003010100300e0603551d0f0101ff040403020780301d0603551d0e0416041449a64afe1bbd22c3072f49bef9a72451b3ec1b78301f0603551d230418301680148d48c7af9a46f9a5fad142a9607059ddab0a89b9300a06082a8648ce3d0403020348003045022100ad87a3d0d56f9178d1726ee2ae65e9d4da3b467e27b216cf66c434371688dc9c0220784fe60bd3261ce422af0172d2cf83509cd94aaa1f8232608c50353364ceb072"}
2024-04-12 19:58:35.681 DEBUG  ExchangeManager      Message » id: 35539/1598/198150325 type: 1/9 acked: 248908323 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402031835013100f001308201ec30820192a003020102020102300a06082a8648ce3d0403023042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c0446464631301e170d3233303431333136353734395a170d3334303431303136353734395a3058312a302806035504030c216e6f64652d6d61747465722044657620444143203078464646312f30783830303031143012060a2b0601040182a27c02010c044646463131143012060a2b0601040182a27c02020c04383030303059301306072a8648ce3d020106082a8648ce3d03010703420004cfd5b003d0513cab1f4e416ef9cc9b8e1ce20ae54f56c03f43021bc203724f2c33c32dff9547e205acbcf9897ec354b3d5e9dfb566c42f1c2b7243f1187ca1cca3633061300f0603551d130101ff04053003010100300e0603551d0f0101ff040403020780301d0603551d0e0416041449a64afe1bbd22c3072f49bef9a72451b3ec1b78301f0603551d230418301680148d48c7af9a46f9a5fad142a9607059ddab0a89b9300a06082a8648ce3d0403020348003045022100ad87a3d0d56f9178d1726ee2ae65e9d4da3b467e27b216cf66c434371688dc9c0220784fe60bd3261ce422af0172d2cf83509cd94aaa1f8232608c50353364ceb0721818181824ff0b18
2024-04-12 19:58:35.682 DEBUG  MessageExchange      Message « id: 35539/1597/248908324 type: 0/16 acked: 198150324 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.688 DEBUG  MessageExchange      New exchange protocol: 1 id: 1599 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.689 DEBUG  MessageExchange      Message « id: 35539/1599/248908325 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e2402001835013000203b05e315dff567cec075643482b46d6064770b7827ed9d2a39f769837144d01d18181824ff0b18
2024-04-12 19:58:35.690 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/attestationRequest(0x0), suppressResponse=false
2024-04-12 19:58:35.691 DEBUG  CommandServer        Invoke attestationRequest with data {"attestationNonce":"3b05e315dff567cec075643482b46d6064770b7827ed9d2a39f769837144d01d"}
2024-04-12 19:58:35.695 DEBUG  CommandServer        Invoke attestationRequest response : {"attestationElements":"153001ea3081e706092a864886f70d010702a081d93081d6020103310d300b0609608648016503040201304306092a864886f70d010701a0360434152400012501f1ff3602050080182403162c0413435341303030303053574330303030302d303024050024060024070124080018317d307b020103801462fa823359acfaa9963e1cfa140addf504f37160300b0609608648016503040201300a06082a8648ce3d0403020447304502201ce6e1edddadec0a26f9ba969cc832f3900badae33106aa4a128ce8ab6caf1e9022100a7cb0122e1f8e61f6a93ddd3f76ec3fe59011b0a636925bdb3b679fd5e244a4e3002203b05e315dff567cec075643482b46d6064770b7827ed9d2a39f769837144d01d24030018","attestationSignature":"a6024be92e1887da8c1bf0805b4c5188b3aa6179e64a3af781aca827e439db7de84dc04207355b0720fc4bcb161702692a48f8b3fc55da2cc8f0af92818c7213"}
2024-04-12 19:58:35.697 DEBUG  ExchangeManager      Message » id: 35539/1599/198150326 type: 1/9 acked: 248908325 reqAck: true duplicate: false payload: 1528003601153500370024000024013e24020118350131001501153001ea3081e706092a864886f70d010702a081d93081d6020103310d300b0609608648016503040201304306092a864886f70d010701a0360434152400012501f1ff3602050080182403162c0413435341303030303053574330303030302d303024050024060024070124080018317d307b020103801462fa823359acfaa9963e1cfa140addf504f37160300b0609608648016503040201300a06082a8648ce3d0403020447304502201ce6e1edddadec0a26f9ba969cc832f3900badae33106aa4a128ce8ab6caf1e9022100a7cb0122e1f8e61f6a93ddd3f76ec3fe59011b0a636925bdb3b679fd5e244a4e3002203b05e315dff567cec075643482b46d6064770b7827ed9d2a39f769837144d01d24030018300140a6024be92e1887da8c1bf0805b4c5188b3aa6179e64a3af781aca827e439db7de84dc04207355b0720fc4bcb161702692a48f8b3fc55da2cc8f0af92818c72131818181824ff0b18
2024-04-12 19:58:35.699 DEBUG  MessageExchange      Message « id: 35539/1598/248908326 type: 0/16 acked: 198150325 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.713 DEBUG  MessageExchange      New exchange protocol: 1 id: 1600 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:35.714 DEBUG  MessageExchange      Message « id: 35539/1600/248908327 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013024020018350124003c24010d18181824ff0b18
2024-04-12 19:58:35.715 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/armFailSafe(0x0), suppressResponse=false
2024-04-12 19:58:35.715 DEBUG  CommandServer        Invoke armFailSafe with data {"expiryLengthSeconds":60,"breadcrumb":13}
2024-04-12 19:58:35.717 DEBUG  FailsafeContext      Extend failSafe timer for 60s.
2024-04-12 19:58:35.717 DEBUG  CommissioningServer  Storing attribute breadcrumb in cluster GeneralCommissioning (48)
2024-04-12 19:58:35.718 DEBUG  CommandServer        Invoke armFailSafe response : {"errorCode":0,"debugText":""}
2024-04-12 19:58:35.719 DEBUG  ExchangeManager      Message » id: 35539/1600/198150327 type: 1/9 acked: 248908327 reqAck: true duplicate: false payload: 152800360115350037002400002401302402011835012400002c01001818181824ff0b18
2024-04-12 19:58:35.721 DEBUG  MessageExchange      Message « id: 35539/1599/248908328 type: 0/16 acked: 198150326 reqAck: false duplicate: false payload: 
2024-04-12 19:58:35.773 DEBUG  MessageExchange      Message « id: 35539/1600/248908329 type: 0/16 acked: 198150327 reqAck: false duplicate: false payload: 
2024-04-12 19:58:36.937 DEBUG  MessageExchange      New exchange protocol: 1 id: 1601 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:36.937 DEBUG  MessageExchange      Message « id: 35539/1601/248908330 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e240204183501300020f3e009cce952d906fb527fb3dce16f1e6f3830b429cd7e70551c4d41152542da18181824ff0b18
2024-04-12 19:58:36.937 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/csrRequest(0x4), suppressResponse=false
2024-04-12 19:58:36.937 DEBUG  CommandServer        Invoke csrRequest with data {"csrNonce":"f3e009cce952d906fb527fb3dce16f1e6f3830b429cd7e70551c4d41152542da"}
2024-04-12 19:58:36.939 DEBUG  CommandServer        Invoke csrRequest response : {"nocsrElements":"153001cc3081c93070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004ce4e1bdf0a662c5e84c0f533ec364a3f18e6d2cba1bdfd6006dee0b1b44dc0b1f42205e0eeff11c92d06ca2b967c98a0d37d344dec17bcc5ba72e201d6332b9da000300a06082a8648ce3d04030203490030460221008fd5883ea9d319bf6c663f1bfcf14bf2256c7b91a089d366ec0867fef99cf8db022100b5f2a222828922fbf9fbec1577c3177a85a40f7ae8c9fce27f5d10011d74fe4e300220f3e009cce952d906fb527fb3dce16f1e6f3830b429cd7e70551c4d41152542da18","attestationSignature":"8954861deb5bd435a776fa0836b964853410af7f6cc3ea1bd91d946fe6ae69ae54ec2b2efd36801f77625bf7ddda62c30da18bf77b06a4ad78692d8a4b0485ea"}
2024-04-12 19:58:36.940 DEBUG  ExchangeManager      Message » id: 35539/1601/198150328 type: 1/9 acked: 248908330 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402051835013000f4153001cc3081c93070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004ce4e1bdf0a662c5e84c0f533ec364a3f18e6d2cba1bdfd6006dee0b1b44dc0b1f42205e0eeff11c92d06ca2b967c98a0d37d344dec17bcc5ba72e201d6332b9da000300a06082a8648ce3d04030203490030460221008fd5883ea9d319bf6c663f1bfcf14bf2256c7b91a089d366ec0867fef99cf8db022100b5f2a222828922fbf9fbec1577c3177a85a40f7ae8c9fce27f5d10011d74fe4e300220f3e009cce952d906fb527fb3dce16f1e6f3830b429cd7e70551c4d41152542da183001408954861deb5bd435a776fa0836b964853410af7f6cc3ea1bd91d946fe6ae69ae54ec2b2efd36801f77625bf7ddda62c30da18bf77b06a4ad78692d8a4b0485ea1818181824ff0b18
2024-04-12 19:58:36.946 DEBUG  MessageExchange      Message « id: 35539/1601/248908331 type: 0/16 acked: 198150328 reqAck: false duplicate: false payload: 
2024-04-12 19:58:36.948 DEBUG  MessageExchange      New exchange protocol: 1 id: 1602 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:36.949 DEBUG  MessageExchange      Message « id: 35539/1602/248908332 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e24020b1835013000f8153001010024020137032714b3101ca3701ebd44241501182604dc2c6d2c24050037062714b3101ca3701ebd442415011824070124080130094104ad7b049ebcacd27b6b3140f370a9dc3b5bbfc5da10296f03117158e81df6b89f8490d3c58b97e2c87557c03e0486dac4391bdd1d74ad448d6e3e551db1b12756370a3501290118240260300414aa6e3d8574d5cf19126c0dcb7defbd7f64fe7975300514aa6e3d8574d5cf19126c0dcb7defbd7f64fe797518300b4063ff6fc9ff9c5c52cb57e58e54c572de23b26625692cdec986f3566ab8d4904555ff3dd209c222db47879d1165993cfc4043cdeebcf12efbbf4a37e20ea0cde61818181824ff0b18
2024-04-12 19:58:36.949 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/addTrustedRootCertificate(0xb), suppressResponse=false
2024-04-12 19:58:36.949 DEBUG  CommandServer        Invoke addTrustedRootCertificate with data {"rootCaCertificate":"153001010024020137032714b3101ca3701ebd44241501182604dc2c6d2c24050037062714b3101ca3701ebd442415011824070124080130094104ad7b049ebcacd27b6b3140f370a9dc3b5bbfc5da10296f03117158e81df6b89f8490d3c58b97e2c87557c03e0486dac4391bdd1d74ad448d6e3e551db1b12756370a3501290118240260300414aa6e3d8574d5cf19126c0dcb7defbd7f64fe7975300514aa6e3d8574d5cf19126c0dcb7defbd7f64fe797518300b4063ff6fc9ff9c5c52cb57e58e54c572de23b26625692cdec986f3566ab8d4904555ff3dd209c222db47879d1165993cfc4043cdeebcf12efbbf4a37e20ea0cde618"}
2024-04-12 19:58:36.950 DEBUG  CommandServer        Invoke addTrustedRootCertificate response : "undefined"
2024-04-12 19:58:36.950 DEBUG  ExchangeManager      Message » id: 35539/1602/198150329 type: 1/9 acked: 248908332 reqAck: true duplicate: false payload: 1528003601153501370024000024013e24020b1835012400001818181824ff0b18
2024-04-12 19:58:36.959 DEBUG  MessageExchange      New exchange protocol: 1 id: 1603 session: secure/35539 peerSessionId: 35539 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:36.960 DEBUG  MessageExchange      Message « id: 35539/1603/248908333 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e2402061835013000fb153001010124020137032714b3101ca3701ebd44241501182604bc24ac2d24050037062415012611bc1f53861824070124080130094104ce4e1bdf0a662c5e84c0f533ec364a3f18e6d2cba1bdfd6006dee0b1b44dc0b1f42205e0eeff11c92d06ca2b967c98a0d37d344dec17bcc5ba72e201d6332b9d370a35012801182402013603040204011830041471ea1becd235dbc50c008e528e7a628909c740d6300514aa6e3d8574d5cf19126c0dcb7defbd7f64fe797518300b40a35fc32433a10f9fbd0e5cebd41bb9d7920c144df170c76d64f78f80f270c29964e8e2f32ffa5b783d8cba061ec3e3abb10a60f44c6fba689971647aa77702b218300100300210576d1bddd61aa34d7753cf365cff53dd2703235f4b89b3a5c1342504841318181824ff0b18
2024-04-12 19:58:36.960 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/addNoc(0x6), suppressResponse=false
2024-04-12 19:58:36.961 DEBUG  CommandServer        Invoke addNoc with data {"nocValue":"153001010124020137032714b3101ca3701ebd44241501182604bc24ac2d24050037062415012611bc1f53861824070124080130094104ce4e1bdf0a662c5e84c0f533ec364a3f18e6d2cba1bdfd6006dee0b1b44dc0b1f42205e0eeff11c92d06ca2b967c98a0d37d344dec17bcc5ba72e201d6332b9d370a35012801182402013603040204011830041471ea1becd235dbc50c008e528e7a628909c740d6300514aa6e3d8574d5cf19126c0dcb7defbd7f64fe797518300b40a35fc32433a10f9fbd0e5cebd41bb9d7920c144df170c76d64f78f80f270c29964e8e2f32ffa5b783d8cba061ec3e3abb10a60f44c6fba689971647aa77702b218","icacValue":"","ipkValue":"576d1bddd61aa34d7753cf365cff53dd","caseAdminSubject":"3801501750998556451","adminVendorId":4996}
2024-04-12 19:58:36.962 DEBUG  Fabric               FabricBuilder setOperationalCert: nodeId=2253594556, fabricId=1
2024-04-12 19:58:36.964 DEBUG  MessageExchange      Message « id: 35539/1602/248908334 type: 0/16 acked: 198150329 reqAck: false duplicate: false payload: 
[19:58:36.966] [Matterbridge] Commissioning changed on fabric 1 for Matterbridge [ { fabricIndex: 1, fabricId: 1, nodeId: 2253594556, rootNodeId: 3801501750998556451, rootVendorId: 4996, label: '' } ] 
2024-04-12 19:58:36.967 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: enx5091e3c45c06
2024-04-12 19:58:36.969 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: br0
2024-04-12 19:58:36.971 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: br-4a8df92a8885
2024-04-12 19:58:36.976 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: vnet0
2024-04-12 19:58:36.979 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: veth068d03e
2024-04-12 19:58:36.981 DEBUG  MdnsServer           Expiring records instanceName: 5B769E7E0690FD5E._matterc._udp.local port: 5540 netInterface: lo
2024-04-12 19:58:36.984 INFO   MatterDevice         Announce done fabric: 1 fabricIndex: 1
2024-04-12 19:58:36.985 DEBUG  Operationa~alsServer Add Fabric with index 1 to PASE session secure/35539.
2024-04-12 19:58:36.987 INFO   Operationa~alsServer addNoc success, adminVendorId 4996, caseAdminSubject 3801501750998556451
2024-04-12 19:58:36.987 DEBUG  CommandServer        Invoke addNoc response : {"statusCode":0,"fabricIndex":1}
2024-04-12 19:58:36.988 DEBUG  ExchangeManager      Message » id: 35539/1603/198150330 type: 1/9 acked: 248908333 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402081835012400002401011818181824ff0b18
2024-04-12 19:58:36.989 DEBUG  UdpChannelNode       Socket created and bound remoteAddress: undefined:5353 localAddress: 0.0.0.0:5353
2024-04-12 19:58:36.989 DEBUG  UdpChannelNode       Socket created and bound remoteAddress: undefined:5353 localAddress: :::5353
2024-04-12 19:58:36.992 DEBUG  UdpChannelNode       Initialize multicast address: 127.0.0.1:5353 interface: lo type: udp4
2024-04-12 19:58:36.993 DEBUG  UdpChannelNode       Initialize multicast address: ::%lo:5353 interface: lo type: udp6
2024-04-12 19:58:36.996 INFO   UdpMulticastServer   lo: send ENETUNREACH ff02::fb:5353
2024-04-12 19:58:36.998 DEBUG  MdnsScanner          Removing commissionable device 5B769E7E0690FD5E._matterc._udp.local from cache (interface enx5091e3c45c06) because of ttl=0
2024-04-12 19:58:37.010 DEBUG  MessageExchange      Message « id: 35539/1603/248908335 type: 0/16 acked: 198150330 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.087 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: enx5091e3c45c06
2024-04-12 19:58:37.091 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: br0
2024-04-12 19:58:37.094 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: br-4a8df92a8885
2024-04-12 19:58:37.096 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: vnet0
2024-04-12 19:58:37.098 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: veth068d03e
2024-04-12 19:58:37.106 DEBUG  MdnsScanner          Adding operational device 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local in cache (interface enx5091e3c45c06) with TXT data: SII: 500 SAI: 300 SAT: 4000 T: 0 DT: undefined PH: undefined ICD: 0 VP: undefined DN: undefined RI: undefined PI: undefined
2024-04-12 19:58:37.205 DEBUG  MessageExchange      New exchange protocol: 0 id: 1604 session: insecure/17311294601229980798 peerSessionId: 0 active threshold ms: 4000 active interval ms: 500 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.205 DEBUG  MessageExchange      Message « id: 0/1604/143012564 type: 0/48 acked: undefined reqAck: true duplicate: false payload: 153001208434b115eb0c31626cebcd0822b10e0da5af3e2527d49cae36745a74fcd22ea725021df23003201e0a65ea6fd9e6deb22a32c5c6a5a44af7b4c8f6d2f76d2db6824100340e4dea300441041100cf3cb92d99eb6a72991a826b631f0ad370028f2e75b1121e3c5eeff0cd4521345d9cc0f97310173e415d938298a09b72466436050955c21485ae9c901521350525012c0125022c012503a00f24041124050b2606000003012407011818
2024-04-12 19:58:37.208 INFO   CaseServer           Received pairing request from udp://10.0.0.23:57519
2024-04-12 19:58:37.222 DEBUG  ExchangeManager      Message » id: 0/1604/6880039 type: 0/49 acked: 143012564 reqAck: true duplicate: false payload: 153001205c956260c0ed2a245fe371362fe55b16c93810f0576944a166b7f0ba857696702502d48a30034104803041519ef8ecc2fe98516f594bcec6c51c830da8b59af3aa9a88d14eba908e8918a7872de5a0e604e6d09793d7dc018d71a9ae4c411cbb0f50b0579e158f9a31046601dd2d349a094a917c792f55400419901d17fda8718b9e6e22accfb6c0b257e4229eb14f8889d68b1371a5849c472c76861b851c5d66b71e7df41ba019cdc8c134162811404e5384d2e04bc341cbcc70b6ad2c53a6dbb9a11cbf4367286351e83aa86c6f9e72b213e9ea7ced63d397d4f7014d5b257c6385a290e195f85a20431f4fc3dbaa7990d7a1e9bdb984c80aa0af245ba58878f80987ab0620aaabd913de9cfbe2c25184448672666f4555ae40014067cf95d438a865887e16761925bf706ba8f0af1125002e471d997772f91ffcb311acdaa8ef67036540f781cdfce106518a8d24f84d000ef4c9882f2e084cbcabba6c8113cbff6ff87ab2ee45b48c61c5c36e90f1b25a8302b4d6ea9a6e1a394e6e8981e9a4ddab1b49fb432611e3e3b7804737b3b3ef7509b0f7becd9c9d1940b71acc95bb2075cde6dbc4d6b52d7ad3c76ba6102181655978f54be38ef0d6870c214e6a867c6953f0a90d26bfdbb1d13912775dd8350525012c0125022c012503a00f1818
2024-04-12 19:58:37.244 DEBUG  MessageExchange      Message « id: 0/1604/143012565 type: 0/50 acked: 6880039 reqAck: true duplicate: false payload: 15310157018b5325774b0a28502fdab0dde5530fa317bf44d87781d43f728e8823ad2a8d0ca84c43e6a10d52aec058960f56299687874771f94d77c8202379ee14501861faf222db386364b5d0fb765ae27df5e51e2bd881ffeada4b19cf2db6cb36f693c736e8b5ff250ea83c0f5a477097f0ea7e3dec632074162aef5d349247a799ff391693bb5ca4c4ca66b0e1053cf9a1875448055fa1b502829ca8d72179b1d5e015ef5bae0141cf4fe343c6f5cb902d3053f724a8838281d168e06c02694e8767a22f67b747dbbae4034608e8adbf7d7f18fc25928109a17518e1b7a1c2b513b024469e5ce98bd26ca96a905da7a7cf585031f9348d53648bee3bab5afdb1694658b9b95e90d233230cd7cca708176b86f1c8a2e6668b00bf4e643faeb104c7e273e1507bc91ee71a30057f23164ba4c826452ff9dcaa74aa859864366bbb4640afca1d3767eb931b1279ac158d3d61d5df7ceee7645a363018
2024-04-12 19:58:37.253 DEBUG  SecureSession        Created secure CASE session for fabric index 1 secure/35540 idleIntervalMs: 300 activeIntervalMs: 300 activeThresholdMs: 4000
[19:58:37.255] [Matterbridge] Active session changed on fabric 1 4996/ for Matterbridge { name: 'secure/35540', nodeId: 2253594556, peerNodeId: 3801501750998556451, fabric: { fabricIndex: 1, fabricId: 1, nodeId: 2253594556, rootNodeId: 3801501750998556451, rootVendorId: 4996, label: '' }, isPeerActive: true, secure: true, lastInteractionTimestamp: 1712941117252, lastActiveTimestamp: 1712941117252, numberOfActiveSubscriptions: 0 } 
2024-04-12 19:58:37.255 INFO   CaseServer           session 35540 created with udp://10.0.0.23:57519 for Fabric 0000000086531FBC(index 1) and PeerNode 34C1A5B3894B5F23
2024-04-12 19:58:37.256 DEBUG  ExchangeManager      Message » id: 0/1604/6880040 type: 0/64 acked: 143012565 reqAck: true duplicate: false payload: 0000000000000000
2024-04-12 19:58:37.263 DEBUG  MessageExchange      New exchange protocol: 1 id: 1605 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.263 DEBUG  MessageExchange      Message « id: 35540/1605/216981699 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013024020418350118181824ff0b18
2024-04-12 19:58:37.264 DEBUG  InteractionServer    Received invoke request from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/commissioningComplete(0x4), suppressResponse=false
2024-04-12 19:58:37.265 DEBUG  CommandServer        Invoke commissioningComplete with data "undefined"
2024-04-12 19:58:37.267 DEBUG  MatterDevice         Commissioning mode ended, stop announcements.
2024-04-12 19:58:37.268 INFO   MatterDevice         All announcements expired
2024-04-12 19:58:37.269 INFO   SecureSession        Register Session secure/35539 to send a close when exchange is ended.
2024-04-12 19:58:37.271 DEBUG  CommissioningServer  Storing attribute breadcrumb in cluster GeneralCommissioning (48)
2024-04-12 19:58:37.271 INFO   GeneralCom~erHandler Commissioning completed on fabric #1 as node #2253594556.
2024-04-12 19:58:37.271 DEBUG  CommandServer        Invoke commissioningComplete response : {"errorCode":0,"debugText":""}
2024-04-12 19:58:37.272 DEBUG  ExchangeManager      Message » id: 35540/1605/89848001 type: 1/9 acked: 216981699 reqAck: true duplicate: false payload: 152800360115350037002400002401302402051835012400002c01001818181824ff0b18
2024-04-12 19:58:37.273 DEBUG  MessageExchange      Message « id: 0/1604/143012566 type: 0/16 acked: 6880040 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.275 INFO   SessionManager       End insecure session insecure/17311294601229980798
2024-04-12 19:58:37.280 DEBUG  MessageExchange      Message « id: 35540/1605/216981700 type: 0/16 acked: 89848001 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.308 DEBUG  MessageExchange      New exchange protocol: 1 id: 1606 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.309 DEBUG  MessageExchange      Message « id: 35540/1606/216981701 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024032824040f1818290324ff0b18
2024-04-12 19:58:37.310 DEBUG  InteractionServer    Received read request from udp://10.0.0.23:57519 on session secure/35540: attributes:MA-rootdevice(0x0)/BasicInformation(0x28)/serialNumber(0xf), events:none isFabricFiltered=true
2024-04-12 19:58:37.310 DEBUG  InteractionServer    Read attribute from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/BasicInformation(0x28)/serialNumber(0xf)="CS39335d3e47abd71c" (version=2944407905)
2024-04-12 19:58:37.311 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 54 bytes
2024-04-12 19:58:37.312 DEBUG  ExchangeManager      Message » id: 35540/1606/89848002 type: 1/5 acked: 216981701 reqAck: true duplicate: false payload: 1536011535012600611980af370124020024032824040f182c0212435333393333356433653437616264373163181818280424ff0b18
2024-04-12 19:58:37.320 DEBUG  MessageExchange      Message « id: 35540/1606/216981702 type: 1/1 acked: 89848002 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.321 DEBUG  ExchangeManager      Message » id: 35540/1606/89848003 type: 0/16 acked: 216981702 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.323 DEBUG  MessageExchange      New exchange protocol: 1 id: 1607 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.324 DEBUG  MessageExchange      Message « id: 35540/1607/216981703 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024033e2404011818290324ff0b18
2024-04-12 19:58:37.325 DEBUG  InteractionServer    Received read request from udp://10.0.0.23:57519 on session secure/35540: attributes:MA-rootdevice(0x0)/OperationalCredentials(0x3e)/fabrics(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:37.325 DEBUG  InteractionServer    Read attribute from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/fabrics(0x1)=[{"fabricId":"1","label":"","nodeId":"2253594556","rootPublicKey":"04ad7b049ebcacd27b6b3140f370a9dc3b5bbfc5da10296f03117158e81df6b89f8490d3c58b97e2c87557c03e0486dac4391bdd1d74ad448d6e3e551db1b12756","vendorId":4996,"fabricIndex":1}] (version=2092250860)
2024-04-12 19:58:37.326 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 125 bytes
2024-04-12 19:58:37.327 DEBUG  ExchangeManager      Message » id: 35540/1607/89848004 type: 1/5 acked: 216981703 reqAck: true duplicate: false payload: 1536011535012600ec36b57c370124020024033e2404011836021530014104ad7b049ebcacd27b6b3140f370a9dc3b5bbfc5da10296f03117158e81df6b89f8490d3c58b97e2c87557c03e0486dac4391bdd1d74ad448d6e3e551db1b12756250284132403012604bc1f53862c050024fe011818181818280424ff0b18
2024-04-12 19:58:37.335 DEBUG  MessageExchange      Message « id: 35540/1607/216981704 type: 1/1 acked: 89848004 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.336 DEBUG  ExchangeManager      Message » id: 35540/1607/89848005 type: 0/16 acked: 216981704 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.366 DEBUG  MessageExchange      New exchange protocol: 1 id: 1608 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.366 DEBUG  MessageExchange      Message « id: 35540/1608/216981705 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020024031d2404031818290324ff0b18
2024-04-12 19:58:37.367 DEBUG  InteractionServer    Received read request from udp://10.0.0.23:57519 on session secure/35540: attributes:MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3), events:none isFabricFiltered=true
2024-04-12 19:58:37.368 DEBUG  InteractionServer    Read attribute from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/Descriptor(0x1d)/partsList(0x3)=[1] (version=994450493)
2024-04-12 19:58:37.369 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 38 bytes
2024-04-12 19:58:37.369 DEBUG  ExchangeManager      Message » id: 35540/1608/89848006 type: 1/5 acked: 216981705 reqAck: true duplicate: false payload: 15360115350126003d1c463b370124020024031d240403183602040118181818280424ff0b18
2024-04-12 19:58:37.376 DEBUG  MessageExchange      Message « id: 35540/1608/216981706 type: 1/1 acked: 89848006 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.378 DEBUG  ExchangeManager      Message » id: 35540/1608/89848007 type: 0/16 acked: 216981706 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.379 DEBUG  MessageExchange      New exchange protocol: 1 id: 1609 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.380 DEBUG  MessageExchange      Message « id: 35540/1609/216981707 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 1536001724020124031d2404001818290324ff0b18
2024-04-12 19:58:37.381 DEBUG  InteractionServer    Received read request from udp://10.0.0.23:57519 on session secure/35540: attributes:MA-aggregator(0x1)/Descriptor(0x1d)/deviceTypeList(0x0), events:none isFabricFiltered=true
2024-04-12 19:58:37.381 DEBUG  InteractionServer    Read attribute from udp://10.0.0.23:57519 on session secure/35540: MA-aggregator(0x1)/Descriptor(0x1d)/deviceTypeList(0x0)=[{"deviceType":14,"revision":1}] (version=2776645312)
2024-04-12 19:58:37.382 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 44 bytes
2024-04-12 19:58:37.383 DEBUG  ExchangeManager      Message » id: 35540/1609/89848008 type: 1/5 acked: 216981707 reqAck: true duplicate: false payload: 1536011535012600c03e80a5370124020124031d2404001836021524000e2401011818181818280424ff0b18
2024-04-12 19:58:37.411 DEBUG  MessageExchange      Message « id: 35540/1609/216981708 type: 1/1 acked: 89848008 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.412 DEBUG  ExchangeManager      Message » id: 35540/1609/89848009 type: 0/16 acked: 216981708 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.746 DEBUG  MessageExchange      New exchange protocol: 1 id: 1610 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.747 DEBUG  MessageExchange      Message « id: 35540/1610/216981709 type: 1/10 acked: undefined reqAck: true duplicate: false payload: 1525000f2724ff0b18
2024-04-12 19:58:37.748 DEBUG  InteractionServer    Received timed request (9999ms) from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.749 DEBUG  MessageExchange      Starting timed interaction with Transaction ID 1610 for 9999ms from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.750 DEBUG  ExchangeManager      Message » id: 35540/1610/89848010 type: 1/1 acked: 216981709 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.756 DEBUG  MessageExchange      Message « id: 35540/1610/216981710 type: 1/8 acked: 89848010 reqAck: true duplicate: false payload: 1528002901360215370024000024013c24020218350118181824ff0b18
2024-04-12 19:58:37.758 DEBUG  InteractionServer    Received invoke request from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/AdministratorCommissioning(0x3c)/revokeCommissioning(0x2), suppressResponse=false
2024-04-12 19:58:37.758 DEBUG  InteractionServer    Invoke request from udp://10.0.0.23:57519 on session secure/35540 received while timed interaction is running.
2024-04-12 19:58:37.759 DEBUG  MessageExchange      Clearing timed interaction with Transaction ID 1610 from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.760 DEBUG  CommandServer        Invoke revokeCommissioning with data "undefined"
2024-04-12 19:58:37.762 INFO   InteractionServer    Error 0x1/0x4 while invoking command: (1) No commissioning window is opened that could be revoked.
2024-04-12 19:58:37.764 DEBUG  ExchangeManager      Message » id: 35540/1610/89848011 type: 1/9 acked: 216981710 reqAck: true duplicate: false payload: 1528003601153501370024000024013c2402021835012400012401041818181824ff0b18
2024-04-12 19:58:37.770 DEBUG  MessageExchange      Message « id: 35540/1610/216981711 type: 0/16 acked: 89848011 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.774 DEBUG  MessageExchange      New exchange protocol: 1 id: 1611 session: secure/35540 peerSessionId: 35540 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:37.774 DEBUG  MessageExchange      Message « id: 35540/1611/216981712 type: 1/10 acked: undefined reqAck: true duplicate: false payload: 152500102724ff0b18
2024-04-12 19:58:37.775 DEBUG  InteractionServer    Received timed request (10000ms) from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.775 DEBUG  MessageExchange      Starting timed interaction with Transaction ID 1611 for 10000ms from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.776 DEBUG  ExchangeManager      Message » id: 35540/1611/89848012 type: 1/1 acked: 216981712 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:37.784 DEBUG  MessageExchange      Message « id: 35540/1611/216981713 type: 1/8 acked: 89848012 reqAck: true duplicate: false payload: 1528002901360215370024000024013c24020018350125002c01300161eb61a39d4140e6e713e931253b4787321fe02927d4401022bbe98d4c2ce86555046e49152ce975078b495dec71758c234780b5a7fd103378e39f23bf271bf10538ccec069255a48f686b8e6b5628e07b312786e76c3596aa868d17b3f82697ae9b2502c60d2503e803300420e7482361b339b5f91a00528c55516ce4ec7238bf38ffeb72944b1687b2452be018181824ff0b18
2024-04-12 19:58:37.785 DEBUG  InteractionServer    Received invoke request from udp://10.0.0.23:57519 on session secure/35540: MA-rootdevice(0x0)/AdministratorCommissioning(0x3c)/openCommissioningWindow(0x0), suppressResponse=false
2024-04-12 19:58:37.785 DEBUG  InteractionServer    Invoke request from udp://10.0.0.23:57519 on session secure/35540 received while timed interaction is running.
2024-04-12 19:58:37.786 DEBUG  MessageExchange      Clearing timed interaction with Transaction ID 1611 from udp://10.0.0.23:57519 on session secure/35540
2024-04-12 19:58:37.787 DEBUG  CommandServer        Invoke openCommissioningWindow with data {"commissioningTimeout":300,"pakePasscodeVerifier":"eb61a39d4140e6e713e931253b4787321fe02927d4401022bbe98d4c2ce86555046e49152ce975078b495dec71758c234780b5a7fd103378e39f23bf271bf10538ccec069255a48f686b8e6b5628e07b312786e76c3596aa868d17b3f82697ae9b","discriminator":3526,"iterations":1000,"salt":"e7482361b339b5f91a00528c55516ce4ec7238bf38ffeb72944b1687b2452be0"}
2024-04-12 19:58:37.788 DEBUG  Administra~ingServer Commissioning window timer started for 300 seconds for secure/35540.
2024-04-12 19:58:37.790 DEBUG  MdnsBroadcaster      Announce commissioning mode 2 Matterbridge 14 65521 32768 3526 5540
2024-04-12 19:58:37.790 DEBUG  CommandServer        Invoke openCommissioningWindow response : "undefined"
2024-04-12 19:58:37.792 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: enx5091e3c45c06
2024-04-12 19:58:37.793 DEBUG  MdnsBroadcaster      Announcement Generator: Commission mode mode: 2 qname: 0A609183A1A344DE._matterc._udp.local port: 5540 interface: enx5091e3c45c06
2024-04-12 19:58:37.796 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: br0
2024-04-12 19:58:37.797 DEBUG  MdnsBroadcaster      Announcement Generator: Commission mode mode: 2 qname: 0A609183A1A344DE._matterc._udp.local port: 5540 interface: br0
2024-04-12 19:58:37.801 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: br-4a8df92a8885
2024-04-12 19:58:37.802 DEBUG  MdnsBroadcaster      Announcement Generator: Commission mode mode: 2 qname: 0A609183A1A344DE._matterc._udp.local port: 5540 interface: br-4a8df92a8885
2024-04-12 19:58:37.805 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: vnet0
2024-04-12 19:58:37.807 DEBUG  MdnsBroadcaster      Announcement Generator: Commission mode mode: 2 qname: 0A609183A1A344DE._matterc._udp.local port: 5540 interface: vnet0
2024-04-12 19:58:37.810 DEBUG  MdnsBroadcaster      Announcement Generator: Fabric id: 256ed4c05eaaa6c9/2253594556 qname: 256ED4C05EAAA6C9-0000000086531FBC._matter._tcp.local port: 5540 interface: veth068d03e
2024-04-12 19:58:37.811 DEBUG  MdnsBroadcaster      Announcement Generator: Commission mode mode: 2 qname: 0A609183A1A344DE._matterc._udp.local port: 5540 interface: veth068d03e
2024-04-12 19:58:37.814 DEBUG  ExchangeManager      Message » id: 35540/1611/89848013 type: 1/9 acked: 216981713 reqAck: true duplicate: false payload: 1528003601153501370024000024013c2402001835012400001818181824ff0b18
2024-04-12 19:58:37.832 DEBUG  MessageExchange      Message « id: 35540/1611/216981714 type: 0/16 acked: 89848013 reqAck: false duplicate: false payload: 
2024-04-12 19:58:37.861 DEBUG  MdnsScanner          Found commissionable device 0A609183A1A344DE._matterc._udp.local with data: SII: 500 SAI: 300 SAT: 4000 T: 0 DT: 14 PH: 33 ICD: 0 VP: 65521+32768 DN: Matterbridge RI: undefined PI: 
2024-04-12 19:58:38.033 DEBUG  MessageExchange      New exchange protocol: 0 id: 18377 session: insecure/957214530005671611 peerSessionId: 0 active threshold ms: 4000 active interval ms: 500 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.033 DEBUG  MessageExchange      Message « id: 0/18377/144615474 type: 0/32 acked: undefined reqAck: true duplicate: false payload: 15300120c7997dbfc874840976cb5967f59f0c4b395c4ffeaa0dbf6ebe03c24a135e82d4250264922403002804350525012c0125022c012503a00f24041124050b2606000003012407011818
2024-04-12 19:58:38.033 INFO   PaseServer           Received pairing request from udp://fe80::c2:be55:adab:4e02%br0:57486.
2024-04-12 19:58:38.034 DEBUG  ExchangeManager      Message » id: 0/18377/6880041 type: 0/33 acked: 144615474 reqAck: true duplicate: false payload: 15300120c7997dbfc874840976cb5967f59f0c4b395c4ffeaa0dbf6ebe03c24a135e82d4300220faf3858123b4f860b50b69544e6a5d08f5c4a921ff83a923663058bbc047d8622503d58a35042501e803300220e7482361b339b5f91a00528c55516ce4ec7238bf38ffeb72944b1687b2452be018350525012c0125022c012503a00f1818
2024-04-12 19:58:38.068 DEBUG  MessageExchange      Message « id: 0/18377/144615475 type: 0/34 acked: 6880041 reqAck: true duplicate: false payload: 15300141042816d288ecc3b57cd59c19a69214a31714f2c57b431f28befa734548e334835025bb8e59adc0ef01bd2ca31dab896849e09132d22ab6dcb000974f140fd019e318
2024-04-12 19:58:38.097 DEBUG  ExchangeManager      Message » id: 0/18377/6880042 type: 0/35 acked: 144615475 reqAck: true duplicate: false payload: 153001410456868f8fb16b0f5631f9c2b0c98dca8178a9652b562be6427c08e5131cf3caef9d86161e91fe731dd1b0e1c1619316e1dc455c0247c4204f03f30a7f88a9082230022020d9e1d5f79f24022da760e5321cdadfe71686b1f57a6729eb359ac55e63a5b018
2024-04-12 19:58:38.110 DEBUG  MessageExchange      Message « id: 0/18377/144615476 type: 0/36 acked: 6880042 reqAck: true duplicate: false payload: 15300120f4512fa1e0e0d8b4d296a7cebb92de7a4d0b143814cca56ed338cc99a0664e8118
2024-04-12 19:58:38.111 DEBUG  SecureSession        Created secure PASE session for fabric index undefined secure/35541 idleIntervalMs: 300 activeIntervalMs: 300 activeThresholdMs: 4000
2024-04-12 19:58:38.112 INFO   PaseServer           Session 35541 created with udp://fe80::c2:be55:adab:4e02%br0:57486.
2024-04-12 19:58:38.112 DEBUG  ExchangeManager      Message » id: 0/18377/6880043 type: 0/64 acked: 144615476 reqAck: true duplicate: false payload: 0000000000000000
2024-04-12 19:58:38.118 DEBUG  MessageExchange      Message « id: 0/18377/144615477 type: 0/16 acked: 6880043 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.119 INFO   SessionManager       End insecure session insecure/957214530005671611
2024-04-12 19:58:38.122 DEBUG  MessageExchange      New exchange protocol: 1 id: 18378 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.122 DEBUG  MessageExchange      Message « id: 35541/18378/181012849 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 153600172402002403302404011818290324ff0b18
2024-04-12 19:58:38.123 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: attributes:MA-rootdevice(0x0)/GeneralCommissioning(0x30)/basicCommissioningInfo(0x1), events:none isFabricFiltered=true
2024-04-12 19:58:38.123 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/basicCommissioningInfo(0x1)={"failSafeExpiryLengthSeconds":60,"maxCumulativeFailsafeSeconds":900} (version=272694507)
2024-04-12 19:58:38.124 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 43 bytes
2024-04-12 19:58:38.124 DEBUG  ExchangeManager      Message » id: 35541/18378/93950675 type: 1/5 acked: 181012849 reqAck: true duplicate: false payload: 1536011535012600ebfc4010370124020024033024040118350224003c2501840318181818280424ff0b18
2024-04-12 19:58:38.132 DEBUG  MessageExchange      Message « id: 35541/18378/181012850 type: 1/1 acked: 93950675 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:38.133 DEBUG  ExchangeManager      Message » id: 35541/18378/93950676 type: 0/16 acked: 181012850 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.134 DEBUG  MessageExchange      New exchange protocol: 1 id: 18379 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.134 DEBUG  MessageExchange      Message « id: 35541/18379/181012851 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013024020018350124007824010018181824ff0b18
2024-04-12 19:58:38.135 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/GeneralCommissioning(0x30)/armFailSafe(0x0), suppressResponse=false
2024-04-12 19:58:38.135 DEBUG  CommandServer        Invoke armFailSafe with data {"expiryLengthSeconds":120,"breadcrumb":0}
2024-04-12 19:58:38.136 DEBUG  FailsafeContext      Arm failSafe timer for 120s.
2024-04-12 19:58:38.137 DEBUG  CommissioningServer  Storing attribute breadcrumb in cluster GeneralCommissioning (48)
2024-04-12 19:58:38.137 DEBUG  CommandServer        Invoke armFailSafe response : {"errorCode":0,"debugText":""}
2024-04-12 19:58:38.138 DEBUG  ExchangeManager      Message » id: 35541/18379/93950677 type: 1/9 acked: 181012851 reqAck: true duplicate: false payload: 152800360115350037002400002401302402011835012400002c01001818181824ff0b18
2024-04-12 19:58:38.144 DEBUG  MessageExchange      Message « id: 35541/18379/181012852 type: 0/16 acked: 93950677 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.900 DEBUG  MessageExchange      New exchange protocol: 1 id: 18380 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.901 DEBUG  MessageExchange      Message « id: 35541/18380/181012853 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e240200183501300020f4b2cedc7ea3fb760000018ed33f355301d5f634bbdcd11d32b237ef7c37bc0718181824ff0b18
2024-04-12 19:58:38.901 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/attestationRequest(0x0), suppressResponse=false
2024-04-12 19:58:38.902 DEBUG  CommandServer        Invoke attestationRequest with data {"attestationNonce":"f4b2cedc7ea3fb760000018ed33f355301d5f634bbdcd11d32b237ef7c37bc07"}
2024-04-12 19:58:38.904 DEBUG  CommandServer        Invoke attestationRequest response : {"attestationElements":"153001ea3081e706092a864886f70d010702a081d93081d6020103310d300b0609608648016503040201304306092a864886f70d010701a0360434152400012501f1ff3602050080182403162c0413435341303030303053574330303030302d303024050024060024070124080018317d307b020103801462fa823359acfaa9963e1cfa140addf504f37160300b0609608648016503040201300a06082a8648ce3d0403020447304502201ce6e1edddadec0a26f9ba969cc832f3900badae33106aa4a128ce8ab6caf1e9022100a7cb0122e1f8e61f6a93ddd3f76ec3fe59011b0a636925bdb3b679fd5e244a4e300220f4b2cedc7ea3fb760000018ed33f355301d5f634bbdcd11d32b237ef7c37bc0724030018","attestationSignature":"43fdb05345f09ca6e7781d60eb692c1c72dee91f625410367f7bd0cc4c9b1363ccc8a03d68aad64bb413a6088c0afa041c71c63f520b266e80b54551f275835c"}
2024-04-12 19:58:38.905 DEBUG  ExchangeManager      Message » id: 35541/18380/93950678 type: 1/9 acked: 181012853 reqAck: true duplicate: false payload: 1528003601153500370024000024013e24020118350131001501153001ea3081e706092a864886f70d010702a081d93081d6020103310d300b0609608648016503040201304306092a864886f70d010701a0360434152400012501f1ff3602050080182403162c0413435341303030303053574330303030302d303024050024060024070124080018317d307b020103801462fa823359acfaa9963e1cfa140addf504f37160300b0609608648016503040201300a06082a8648ce3d0403020447304502201ce6e1edddadec0a26f9ba969cc832f3900badae33106aa4a128ce8ab6caf1e9022100a7cb0122e1f8e61f6a93ddd3f76ec3fe59011b0a636925bdb3b679fd5e244a4e300220f4b2cedc7ea3fb760000018ed33f355301d5f634bbdcd11d32b237ef7c37bc072403001830014043fdb05345f09ca6e7781d60eb692c1c72dee91f625410367f7bd0cc4c9b1363ccc8a03d68aad64bb413a6088c0afa041c71c63f520b266e80b54551f275835c1818181824ff0b18
2024-04-12 19:58:38.910 DEBUG  MessageExchange      Message « id: 35541/18380/181012854 type: 0/16 acked: 93950678 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.911 DEBUG  MessageExchange      New exchange protocol: 1 id: 18381 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.911 DEBUG  MessageExchange      Message « id: 35541/18381/181012855 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e24020218350124000118181824ff0b18
2024-04-12 19:58:38.912 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/certificateChainRequest(0x2), suppressResponse=false
2024-04-12 19:58:38.912 DEBUG  CommandServer        Invoke certificateChainRequest with data {"certificateType":1}
2024-04-12 19:58:38.913 DEBUG  CommandServer        Invoke certificateChainRequest response : {"certificate":"308201ec30820192a003020102020102300a06082a8648ce3d0403023042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c0446464631301e170d3233303431333136353734395a170d3334303431303136353734395a3058312a302806035504030c216e6f64652d6d61747465722044657620444143203078464646312f30783830303031143012060a2b0601040182a27c02010c044646463131143012060a2b0601040182a27c02020c04383030303059301306072a8648ce3d020106082a8648ce3d03010703420004cfd5b003d0513cab1f4e416ef9cc9b8e1ce20ae54f56c03f43021bc203724f2c33c32dff9547e205acbcf9897ec354b3d5e9dfb566c42f1c2b7243f1187ca1cca3633061300f0603551d130101ff04053003010100300e0603551d0f0101ff040403020780301d0603551d0e0416041449a64afe1bbd22c3072f49bef9a72451b3ec1b78301f0603551d230418301680148d48c7af9a46f9a5fad142a9607059ddab0a89b9300a06082a8648ce3d0403020348003045022100ad87a3d0d56f9178d1726ee2ae65e9d4da3b467e27b216cf66c434371688dc9c0220784fe60bd3261ce422af0172d2cf83509cd94aaa1f8232608c50353364ceb072"}
2024-04-12 19:58:38.914 DEBUG  ExchangeManager      Message » id: 35541/18381/93950679 type: 1/9 acked: 181012855 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402031835013100f001308201ec30820192a003020102020102300a06082a8648ce3d0403023042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c0446464631301e170d3233303431333136353734395a170d3334303431303136353734395a3058312a302806035504030c216e6f64652d6d61747465722044657620444143203078464646312f30783830303031143012060a2b0601040182a27c02010c044646463131143012060a2b0601040182a27c02020c04383030303059301306072a8648ce3d020106082a8648ce3d03010703420004cfd5b003d0513cab1f4e416ef9cc9b8e1ce20ae54f56c03f43021bc203724f2c33c32dff9547e205acbcf9897ec354b3d5e9dfb566c42f1c2b7243f1187ca1cca3633061300f0603551d130101ff04053003010100300e0603551d0f0101ff040403020780301d0603551d0e0416041449a64afe1bbd22c3072f49bef9a72451b3ec1b78301f0603551d230418301680148d48c7af9a46f9a5fad142a9607059ddab0a89b9300a06082a8648ce3d0403020348003045022100ad87a3d0d56f9178d1726ee2ae65e9d4da3b467e27b216cf66c434371688dc9c0220784fe60bd3261ce422af0172d2cf83509cd94aaa1f8232608c50353364ceb0721818181824ff0b18
2024-04-12 19:58:38.922 DEBUG  MessageExchange      Message « id: 35541/18381/181012856 type: 0/16 acked: 93950679 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.923 DEBUG  MessageExchange      New exchange protocol: 1 id: 18382 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.923 DEBUG  MessageExchange      Message « id: 35541/18382/181012857 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e24020218350124000218181824ff0b18
2024-04-12 19:58:38.924 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/certificateChainRequest(0x2), suppressResponse=false
2024-04-12 19:58:38.924 DEBUG  CommandServer        Invoke certificateChainRequest with data {"certificateType":2}
2024-04-12 19:58:38.925 DEBUG  CommandServer        Invoke certificateChainRequest response : {"certificate":"308201b130820157a003020102020101300a06082a8648ce3d040302301a3118301606035504030c0f4d6174746572205465737420504141301e170d3233303431333136353734395a170d3334303431303136353734395a3042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c04464646313059301306072a8648ce3d020106082a8648ce3d03010703420004fd541c15527e0f19bc1baf26206d8ea23189fb6b02cba341c67d8b0d9b832328aa6bfd540a9df0b1132bcfcf3ca3eb501f4190fee765db3aae17693081c7e749a366306430120603551d130101ff040830060101ff020100300e0603551d0f0101ff040403020106301d0603551d0e041604148d48c7af9a46f9a5fad142a9607059ddab0a89b9301f0603551d23041830168014785ce705b86b8f4e6fc793aa60cb43ea696882d5300a06082a8648ce3d040302034800304502203b2f118efdb5b02af539925a9466714874be8767e5e9e19c6227cee2deaf8f92022100f28a686ebb8b3cc2ade78c477a7aa670ef24bdaa3d5d44ce8d946b0ca10d47f3"}
2024-04-12 19:58:38.925 DEBUG  ExchangeManager      Message » id: 35541/18382/93950680 type: 1/9 acked: 181012857 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402031835013100b501308201b130820157a003020102020101300a06082a8648ce3d040302301a3118301606035504030c0f4d6174746572205465737420504141301e170d3233303431333136353734395a170d3334303431303136353734395a3042312a302806035504030c216e6f64652d6d6174746572204465762050414920307846464631206e6f2050494431143012060a2b0601040182a27c02010c04464646313059301306072a8648ce3d020106082a8648ce3d03010703420004fd541c15527e0f19bc1baf26206d8ea23189fb6b02cba341c67d8b0d9b832328aa6bfd540a9df0b1132bcfcf3ca3eb501f4190fee765db3aae17693081c7e749a366306430120603551d130101ff040830060101ff020100300e0603551d0f0101ff040403020106301d0603551d0e041604148d48c7af9a46f9a5fad142a9607059ddab0a89b9301f0603551d23041830168014785ce705b86b8f4e6fc793aa60cb43ea696882d5300a06082a8648ce3d040302034800304502203b2f118efdb5b02af539925a9466714874be8767e5e9e19c6227cee2deaf8f92022100f28a686ebb8b3cc2ade78c477a7aa670ef24bdaa3d5d44ce8d946b0ca10d47f31818181824ff0b18
2024-04-12 19:58:38.930 DEBUG  MessageExchange      Message « id: 35541/18382/181012858 type: 0/16 acked: 93950680 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.931 DEBUG  MessageExchange      New exchange protocol: 1 id: 18383 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.931 DEBUG  MessageExchange      Message « id: 35541/18383/181012859 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 153600172402002403282404021818290324ff0b18
2024-04-12 19:58:38.931 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: attributes:MA-rootdevice(0x0)/BasicInformation(0x28)/vendorId(0x2), events:none isFabricFiltered=true
2024-04-12 19:58:38.931 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/BasicInformation(0x28)/vendorId(0x2)=65521 (version=2944407905)
2024-04-12 19:58:38.932 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 37 bytes
2024-04-12 19:58:38.932 DEBUG  ExchangeManager      Message » id: 35541/18383/93950681 type: 1/5 acked: 181012859 reqAck: true duplicate: false payload: 1536011535012600611980af3701240200240328240402182502f1ff181818280424ff0b18
2024-04-12 19:58:38.937 DEBUG  MessageExchange      Message « id: 35541/18383/181012860 type: 1/1 acked: 93950681 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:38.937 DEBUG  ExchangeManager      Message » id: 35541/18383/93950682 type: 0/16 acked: 181012860 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.938 DEBUG  MessageExchange      New exchange protocol: 1 id: 18384 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.938 DEBUG  MessageExchange      Message « id: 35541/18384/181012861 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 153600172402002403282404041818290324ff0b18
2024-04-12 19:58:38.938 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: attributes:MA-rootdevice(0x0)/BasicInformation(0x28)/productId(0x4), events:none isFabricFiltered=true
2024-04-12 19:58:38.939 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/BasicInformation(0x28)/productId(0x4)=32768 (version=2944407905)
2024-04-12 19:58:38.939 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 37 bytes
2024-04-12 19:58:38.939 DEBUG  ExchangeManager      Message » id: 35541/18384/93950683 type: 1/5 acked: 181012861 reqAck: true duplicate: false payload: 1536011535012600611980af37012402002403282404041825020080181818280424ff0b18
2024-04-12 19:58:38.943 DEBUG  MessageExchange      Message « id: 35541/18384/181012862 type: 1/1 acked: 93950683 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:38.944 DEBUG  ExchangeManager      Message » id: 35541/18384/93950684 type: 0/16 acked: 181012862 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.945 DEBUG  MessageExchange      New exchange protocol: 1 id: 18385 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.946 DEBUG  MessageExchange      Message « id: 35541/18385/181012863 type: 1/2 acked: undefined reqAck: true duplicate: false payload: 153600172402002403282404031818290324ff0b18
2024-04-12 19:58:38.946 DEBUG  InteractionServer    Received read request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: attributes:MA-rootdevice(0x0)/BasicInformation(0x28)/productName(0x3), events:none isFabricFiltered=true
2024-04-12 19:58:38.946 DEBUG  InteractionServer    Read attribute from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/BasicInformation(0x28)/productName(0x3)="Matterbridge Aggregator" (version=2944407905)
2024-04-12 19:58:38.946 DEBUG  InteractionMessenger Sending DataReport chunk with 1 attributes and 0 events: 59 bytes
2024-04-12 19:58:38.946 DEBUG  ExchangeManager      Message » id: 35541/18385/93950685 type: 1/5 acked: 181012863 reqAck: true duplicate: false payload: 1536011535012600611980af3701240200240328240403182c02174d61747465726272696467652041676772656761746f72181818280424ff0b18
2024-04-12 19:58:38.952 DEBUG  MessageExchange      Message « id: 35541/18385/181012864 type: 1/1 acked: 93950685 reqAck: true duplicate: false payload: 1524000024ff0b18
2024-04-12 19:58:38.952 DEBUG  ExchangeManager      Message » id: 35541/18385/93950686 type: 0/16 acked: 181012864 reqAck: false duplicate: false payload: 
2024-04-12 19:58:38.954 DEBUG  MessageExchange      New exchange protocol: 1 id: 18386 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:38.955 DEBUG  MessageExchange      Message « id: 35541/18386/181012865 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e240204183501300020d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18181824ff0b18
2024-04-12 19:58:38.955 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/csrRequest(0x4), suppressResponse=false
2024-04-12 19:58:38.955 DEBUG  CommandServer        Invoke csrRequest with data {"csrNonce":"d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae"}
2024-04-12 19:58:38.956 DEBUG  CommandServer        Invoke csrRequest response : {"nocsrElements":"153001ca3081c73070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d040302034700304402202ac76abde5f7d3fe2196afbb4cfbabe170252c84cdcd43c2f032653a6e8fc1b6022042b4f703945693d8f73e12b8104a345bd78b3c616d6dfef1922d3e349997224f300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18","attestationSignature":"904a54e28836ab06243c5b7c3626c705a0874f4174807a17cf9d9882d9a7673510a636abff0003839989b095afe6176ac4ad25f5c2a39d6e411633d0269b9de4"}
2024-04-12 19:58:38.957 DEBUG  ExchangeManager      Message » id: 35541/18386/93950687 type: 1/9 acked: 181012865 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402051835013000f2153001ca3081c73070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d040302034700304402202ac76abde5f7d3fe2196afbb4cfbabe170252c84cdcd43c2f032653a6e8fc1b6022042b4f703945693d8f73e12b8104a345bd78b3c616d6dfef1922d3e349997224f300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18300140904a54e28836ab06243c5b7c3626c705a0874f4174807a17cf9d9882d9a7673510a636abff0003839989b095afe6176ac4ad25f5c2a39d6e411633d0269b9de41818181824ff0b18
2024-04-12 19:58:38.964 DEBUG  MessageExchange      Message « id: 35541/18386/181012866 type: 0/16 acked: 93950687 reqAck: false duplicate: false payload: 
2024-04-12 19:58:41.031 DEBUG  MessageExchange      New exchange protocol: 1 id: 18387 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:41.032 DEBUG  MessageExchange      Message « id: 35541/18387/181012867 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e240204183501300020d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18181824ff0b18
2024-04-12 19:58:41.033 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/csrRequest(0x4), suppressResponse=false
2024-04-12 19:58:41.034 DEBUG  CommandServer        Invoke csrRequest with data {"csrNonce":"d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae"}
2024-04-12 19:58:41.038 DEBUG  CommandServer        Invoke csrRequest response : {"nocsrElements":"153001cb3081c83070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d040302034800304502202197ff2c21b848b2ee139a650a74083d52f8c82aacfe8577e9187f58e31edf3e022100e891f41ea4fd657b0bf0d1245a0cb9d29cfe8885166c0b80bf9fe334d2b639cb300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18","attestationSignature":"7b80f78de9076fcf11009b83f2c948eaa4bc32677a24dc20cf0451e1b6a0ae59207025138230d12094d57f49d7f781b55b851b535287df115d6b01452f7bdd09"}
2024-04-12 19:58:41.039 DEBUG  ExchangeManager      Message » id: 35541/18387/93950688 type: 1/9 acked: 181012867 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402051835013000f3153001cb3081c83070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d040302034800304502202197ff2c21b848b2ee139a650a74083d52f8c82aacfe8577e9187f58e31edf3e022100e891f41ea4fd657b0bf0d1245a0cb9d29cfe8885166c0b80bf9fe334d2b639cb300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae183001407b80f78de9076fcf11009b83f2c948eaa4bc32677a24dc20cf0451e1b6a0ae59207025138230d12094d57f49d7f781b55b851b535287df115d6b01452f7bdd091818181824ff0b18
2024-04-12 19:58:41.048 DEBUG  MessageExchange      Message « id: 35541/18387/181012868 type: 0/16 acked: 93950688 reqAck: false duplicate: false payload: 
2024-04-12 19:58:43.085 DEBUG  MessageExchange      New exchange protocol: 1 id: 18388 session: secure/35541 peerSessionId: 35541 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:43.086 DEBUG  MessageExchange      Message « id: 35541/18388/181012869 type: 1/8 acked: undefined reqAck: true duplicate: false payload: 1528002801360215370024000024013e240204183501300020d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18181824ff0b18
2024-04-12 19:58:43.086 DEBUG  InteractionServer    Received invoke request from udp://fe80::c2:be55:adab:4e02%br0:57486 on session secure/35541: MA-rootdevice(0x0)/OperationalCredentials(0x3e)/csrRequest(0x4), suppressResponse=false
2024-04-12 19:58:43.086 DEBUG  CommandServer        Invoke csrRequest with data {"csrNonce":"d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae"}
2024-04-12 19:58:43.089 DEBUG  CommandServer        Invoke csrRequest response : {"nocsrElements":"153001cc3081c93070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d0403020349003046022100cf4bbbdb96e5d59bb3a6716ca05098df6ee3a2763643d0a93c09fb4db6bbd03f022100a56dcf9f9e6927a5e06aeb1c5619c0f75bda92d6bda81a7eb50707ee27f268fd300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae18","attestationSignature":"1f0eb922f0e2fe01eb67455793eac29ff9fe8a0fee3e5b736477a05305a5f973954c1e685d56041757b03037befc0d0a4edf361b4411520c5a341ebff35b073e"}
2024-04-12 19:58:43.090 DEBUG  ExchangeManager      Message » id: 35541/18388/93950689 type: 1/9 acked: 181012869 reqAck: true duplicate: false payload: 1528003601153500370024000024013e2402051835013000f4153001cc3081c93070020100300e310c300a060355040a0c034353523059301306072a8648ce3d020106082a8648ce3d03010703420004d767ded723da390229a95a1f59451c9b2bb3bf98aa9af790d1eb102dc13b670a4207c6bcb5bc8db4159f6c9ef573bba0b079775d60c6cb372bdf197f54f92fdca000300a06082a8648ce3d0403020349003046022100cf4bbbdb96e5d59bb3a6716ca05098df6ee3a2763643d0a93c09fb4db6bbd03f022100a56dcf9f9e6927a5e06aeb1c5619c0f75bda92d6bda81a7eb50707ee27f268fd300220d3c69b8fe2b8a83f0000018ed33f355401d5f634bbe1c500b536e68c63a8d8ae183001401f0eb922f0e2fe01eb67455793eac29ff9fe8a0fee3e5b736477a05305a5f973954c1e685d56041757b03037befc0d0a4edf361b4411520c5a341ebff35b073e1818181824ff0b18
2024-04-12 19:58:43.097 DEBUG  MessageExchange      Message « id: 35541/18388/181012870 type: 0/16 acked: 93950689 reqAck: false duplicate: false payload: 
2024-04-12 19:58:44.890 DEBUG  ExchangeManager      Exchange index 1587 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.891 DEBUG  ExchangeManager      Exchange index 1587 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.892 DEBUG  ExchangeManager      Exchange index 1588 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.893 DEBUG  ExchangeManager      Exchange index 1589 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.893 DEBUG  ExchangeManager      Exchange index 1590 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.893 DEBUG  ExchangeManager      Exchange index 1591 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.894 DEBUG  ExchangeManager      Exchange index 1592 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.894 DEBUG  ExchangeManager      Exchange index 1593 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.894 DEBUG  ExchangeManager      Exchange index 1594 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.895 DEBUG  ExchangeManager      Exchange index 1595 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.895 DEBUG  ExchangeManager      Exchange index 1596 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.895 DEBUG  ExchangeManager      Exchange index 1597 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.896 DEBUG  ExchangeManager      Exchange index 1598 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.896 DEBUG  ExchangeManager      Exchange index 1599 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.897 DEBUG  ExchangeManager      Exchange index 1600 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.897 DEBUG  ExchangeManager      Exchange index 1601 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.897 DEBUG  ExchangeManager      Exchange index 1602 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.897 DEBUG  ExchangeManager      Exchange index 1603 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.898 DEBUG  ExchangeManager      Channel for session secure/35539 is udp://fe80::c2:be55:adab:4e02%br0:59408 on session secure/35539
2024-04-12 19:58:44.899 DEBUG  MessageExchange      New exchange protocol: 0 id: 44180 session: secure/35539 peerSessionId: 61980 active threshold ms: 4000 active interval ms: 300 idle interval ms: 300 retries: 5
2024-04-12 19:58:44.899 DEBUG  ExchangeManager      Initiated exchange true to close session secure/35539
2024-04-12 19:58:44.899 DEBUG  ExchangeManager      Message » id: 61980/44180/198150331 type: 0/64 acked: undefined reqAck: false duplicate: false payload: 0000000000000300
2024-04-12 19:58:44.900 DEBUG  ExchangeManager      Exchange index 109716 Session secure/35539 is already marked for closure. Close session now.
2024-04-12 19:58:44.900 INFO   SessionManager       End PASE session secure/35539
[19:58:44.900] [Matterbridge] Active session changed on fabric 1 4996/ for Matterbridge { name: 'secure/35540', nodeId: 2253594556, peerNodeId: 3801501750998556451, fabric: { fabricIndex: 1, fabricId: 1, nodeId: 2253594556, rootNodeId: 3801501750998556451, rootVendorId: 4996, label: '' }, isPeerActive: false, secure: true, lastInteractionTimestamp: 1712941117832, lastActiveTimestamp: 1712941117832, numberOfActiveSubscriptions: 0 } 
2
tammeryousef1006 commented 6 months ago

if you got experince with containers , i suggest you to do a new installation and then try to pair with google home directly , skip apple home because its worked before with you.

nitaybz commented 6 months ago

I have some experience with docker but mostly it was already a ready made docker installation when I used it. I'm not sure how to setup a container with installation that is not ready for it. maybe you can give me a hint I'll go figure it out

tammeryousef1006 commented 6 months ago

which operating system are you using , windows ? linux ? mac ?

nitaybz commented 6 months ago

Linux - ubuntu. I have docker installed and docker compose

Luligu commented 6 months ago

Guys you are great!!! Thank you so much

Luligu commented 6 months ago

Docker will come soon still in beta... problems are the ports to map

Luligu commented 6 months ago

Next release will have also the manual pairing code: you can use it much easier with other controller. Matterbridge is already on the network like an ethernet node. So no need to use Ble and apps to pair. Will be easier to just put the pairing code. With Apple was not usefull so I didn't put it on frontend.

Luligu commented 6 months ago

We could move there so your experience will help everyone... https://github.com/Luligu/matterbridge/discussions/12

nitaybz commented 6 months ago

pairing with the code did not change anything for me, I'm still unable to pair to Google.