What's the matter? #19008

Derek-K · 2023-07-01T01:10:57Z

Derek-K
Jul 1, 2023

I am very excited about the Matter integration, especially with ESP32 it acts as a bridge to older ESP8266 Tasmota devices (provided upgraded to 13.0.0)

However, I am unsure if I have misunderstood how it works or if something is missing. After following the instructions here I was able to add my ESP32 Tasmota device under Google Home, it has knowledge about my other ESP8266 Tasmota devices. But that's where it ends. Other than the Matter bridge, everything shows as offline.

Also, is it possible to name my "bulbs" and "outlets"?

The two "outlets" are the relays on Sonoff Dual R3.
The other two "bulbs" are each ESP8266 Sonoff.

Derek-K · 2023-07-01T01:23:52Z

Derek-K
Jul 1, 2023
Author

Furthermore, when I try to connect via the GHSAFM-TC Android app (found under one of the Google developer doc), the app just hang and report "Something went wrong"

From Tasmota console...

02:15:46.796 RSL: RESULT = {"Matter":{"Commissioning":1,"PairingCode":"XXXXXX","QRCode":"XXXXXX"}}
02:15:54.331 MTR: New Commissioning (PASE id=XXXXXX) from [XXXXXX]:XXXXXX
02:16:01.102 RSL: RESULT = {"Matter":{"Commissioning":0}}
02:16:03.496 MTR: New Connection (CASE id=XXXXXX) from [XXXXXX]:XXXXXX
02:16:05.289 MTR: --- Commissioning complete for Fabric 'XXXXXX' (Vendor Google LLC) ---
02:16:11.954 MTR: New Commissioning (PASE id=XXXXXX) from [XXXXXX]:XXXXXX
02:16:19.842 MTR: New Connection (CASE id=XXXXXX) from [XXXXXX]:XXXXXX
02:16:20.549 stack traceback:
02:16:20.551 	<native>: in native function
02:16:20.553 	<unknown source>: in function `_anonymous_`
02:16:20.555 	<unknown source>: in function `save_fabrics`
02:16:20.557 	<unknown source>: in function `save`
02:16:20.559 	<unknown source>: in function `parse_Sigma3`
02:16:20.561 	<unknown source>: in function `process_incoming`
02:16:20.564 	<unknown source>: in function `msg_received`
02:16:20.566 	<unknown source>: in function `msg_received`
02:16:20.568 	<unknown source>: in function `<lambda>`
02:16:20.570 	<unknown source>: in function `every_50ms`
02:16:20.572 	<unknown source>: in function `event`
02:16:20.574 BRY: stack[1] = type='instance' (Tasmota)
02:16:20.575 BRY: stack[2] = type='function' ()
02:16:20.577 BRY: stack[3] = type='instance' (Tasmota)
02:16:20.578 BRY: stack[4] = type='string' (every_50ms)
02:16:20.579 BRY: stack[5] = type='string' ()
02:16:20.580 BRY: stack[6] = type='int' ()
02:16:20.581 BRY: stack[7] = type='string' ()
02:16:21.068 stack traceback:
02:16:21.070 	<native>: in native function
02:16:21.071 	<unknown source>: in function `_anonymous_`
02:16:21.073 	<unknown source>: in function `save_fabrics`
02:16:21.074 	<unknown source>: in function `save`
02:16:21.075 	<unknown source>: in function `invoke_request`
02:16:21.077 	<unknown source>: in function `invoke_request`
02:16:21.079 	<unknown source>: in function `process_invoke_request`
02:16:21.081 	<unknown source>: in function `process_incoming`
02:16:21.083 	<unknown source>: in function `msg_received`
02:16:21.086 	<unknown source>: in function `msg_received`
02:16:21.088 	<unknown source>: in function `<lambda>`
02:16:21.090 	<unknown source>: in function `every_50ms`
02:16:21.092 	<unknown source>: in function `event`
02:16:21.093 BRY: stack[1] = type='instance' (Tasmota)
02:16:21.095 BRY: stack[2] = type='function' ()
02:16:21.097 BRY: stack[3] = type='instance' (Tasmota)
02:16:21.098 BRY: stack[4] = type='string' (every_50ms)
02:16:21.100 BRY: stack[5] = type='string' ()
02:16:21.101 BRY: stack[6] = type='int' ()
02:16:21.103 BRY: stack[7] = type='string' ()

12 replies

Derek-K Jul 8, 2023
Author

That is already on the latest version 13.0.0.1

s-hadinger Jul 8, 2023
Collaborator

Yes, but in between I added more logs to the code. Can tou try with the latest version from the development branch? Although it's still 13.0.0.1, it has some changes.

Derek-K Jul 18, 2023
Author

I tested with 13.0.0.2 (Timestamp 20230718 09:19) development build. The result is still pretty much the same. On Google home, it only shows the bridge device is online, and everything else are offline.

as for the logs, I filtered out just the MTR messages

17:25:48.898 MTR: New Connection (CASE id=56308) from [xxxx::xxxx:xxxx:xxxx:xxxx]:55296
17:25:49.658 MTR: =Saved     0 fabric(s) and 0 session(s)
17:25:58.567 MTR: =Saved     1 fabric(s) and 1 session(s)
17:25:58.634 MTR: --- Commissioning complete for Fabric '212Exxxxxxxx119E' (Vendor Google LLC) ---
17:26:14.046 MTR: New Commissioning (PASE id=38308) from [xxxx::xxxx:xxxx:xxxx:xxxx]:5541
17:26:21.271 MTR: New Connection (CASE id=40306) from [xxx.xxx.xxx.xxx]:5541
17:26:21.962 MTR: =Saved     1 fabric(s) and 1 session(s)
17:26:22.385 MTR: =Saved     2 fabric(s) and 2 session(s)
17:26:22.453 MTR: --- Commissioning complete for Fabric '9B5BxxxxxxxxFAA7' (Vendor 0x110A) ---

p.s. I am still not familiar with all the terms used by matter. That was from a single "Add" from google home, but why there are two "fabric" being "commissioned"? And who/what is Vendor 0x110A?

s-hadinger Jul 18, 2023
Collaborator

At least the exception is not happening anymore. Can you please use weblog 3 or seriallog 3 and pair again.

It's normal to have 2 fabrics, this is the way Google and Apple proceed.

Btw you don't need to mask the Fabric ID in the logs, it's a random number that contains no useful information.

Derek-K Jul 21, 2023
Author

Here are all the MTR messages with weblog 3

02:10:49.461 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:50.985 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:10:51.003 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:51.599 MTR: HTTP async-resp in 589 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:10:51.624 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:10:52.472 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:10:52.720 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:54.284 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:54.460 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:10:54.466 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:10:54.728 MTR: HTTP async-resp in 434 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:10:55.731 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:55.986 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:10:56.939 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:10:57.487 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:10:57.505 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:10:57.695 MTR: HTTP async-resp in 181 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:10:58.762 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:00.496 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:00.503 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:00.711 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:00.962 MTR: HTTP async-resp in 242 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:00.987 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:02.147 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:02.241 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:02.494 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:03.714 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:03.963 MTR: HTTP async-resp in 242 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:04.208 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:04.214 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:05.241 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:06.005 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:06.715 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:06.964 MTR: HTTP async-resp in 242 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:07.510 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:07.714 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:08.478 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:09.723 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:10.060 MTR: HTTP async-resp in 329 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:10.201 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:10.208 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:11.230 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:11.478 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:12.729 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:12.748 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:12.987 MTR: HTTP async-resp in 232 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:13.013 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:14.513 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:15.974 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:16.238 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:16.570 MTR: HTTP async-resp in 587 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:17.740 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:17.997 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:18.245 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:19.023 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:19.241 MTR: HTTP async-resp in 210 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:19.986 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:19.992 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:21.007 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:21.461 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:22.109 MTR: >Received  (     0) PBKDFParamRequest rid=59261831 exch=60156 from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:22.122 MTR: +Session   ( 30938) from '[xxxx::xxxx:xxxx:xxxx:xxxx]:46839'
02:11:22.145 MTR: <Replied   (     0) PBKDFParamResponse
02:11:22.251 MTR: >Received  (     0) PASE_Pake1 rid=59261833 exch=60156 from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:22.902 MTR: <Replied   (     0) PASE_Pake2
02:11:22.907 MTR: New Commissioning (PASE id=30938) from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:22.959 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:22.976 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:23.044 MTR: >Received  (     0) PASE_Pake3 rid=59261835 exch=60156 from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:23.057 MTR: <Replied   (     0) StatusReport
02:11:23.227 MTR: >Read_Attr ( 30938) [00]0028/0002 (VendorID) - 65521U
02:11:23.291 MTR: HTTP async-resp in 309 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:23.319 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:23.457 MTR: >Read_Attr ( 30938) [00]0028/0004 (ProductID) - 32768U
02:11:23.655 MTR: >Read_Attr ( 30938) [00]003E/0002 (SupportedFabrics) - 5U
02:11:23.900 MTR: >Read_Attr ( 30938) [00]003E/0003 (CommissionedFabrics) - 0U
02:11:24.112 MTR: >Read_Attr ( 30938) [00]003E/0001 (Fabrics) - []
02:11:24.388 MTR: >Read_Attr ( 30938) [00]001D/0001 (ServerList) - [48U, 49U, 50U, 51U, 52U, 40U, 63U, 56U, 57U, 60U, 43U, 44U, 29U, 62U, 31U]
02:11:24.470 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:24.754 MTR: >Read_Attr ( 30938) [00]0031/FFFC (FeatureMap) - 4U
02:11:25.516 MTR: >Command   ( 30938) TimedRequest=10000
02:11:25.604 MTR: >Command   ( 30938) [00]0030/0000 ArmFailSafe
02:11:25.610 MTR: <Replied   ( 30938) [00]0030/0001 ArmFailSafeResponse
02:11:25.970 MTR: >Read_Attr ( 30938) [00]0030/0003 (LocationCapability) - 2U
02:11:26.026 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:26.167 MTR: >Command   ( 30938) TimedRequest=10000
02:11:26.257 MTR: >Command   ( 30938) [00]0030/0002 SetRegulatoryConfig
02:11:26.262 MTR: <Replied   ( 30938) [00]0030/0003 SetRegulatoryConfigResponse
02:11:26.317 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:26.324 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:26.456 MTR: >Command   ( 30938) [00]003E/0002 CertificateChainRequest
02:11:26.462 MTR: <Replied   ( 30938) [00]003E/0003 CertificateChainResponse
02:11:26.538 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:26.714 MTR: >Command   ( 30938) [00]003E/0002 CertificateChainRequest
02:11:26.720 MTR: <Replied   ( 30938) [00]003E/0003 CertificateChainResponse
02:11:26.804 MTR: HTTP async-resp in 766 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:27.025 MTR: >Command   ( 30938) [00]003E/0000 AttestationRequest
02:11:27.029 MTR: <Replied   ( 30938) [00]003E/0001 AttestationResponse
02:11:27.541 MTR: >Command   ( 30938) [00]003E/0004 CSRRequest
02:11:27.546 MTR: <Replied   ( 30938) [00]003E/0005 CSRResponse
02:11:27.622 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:27.966 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:28.471 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:28.892 MTR: >Command   ( 30938) [00]003E/000B AddTrustedRootCertificate
02:11:28.896 MTR: <Replied   ( 30938) OK exch=60171
02:11:29.279 MTR: +Fabric    fab='212E7C7BFC27119E' vendorid=Google LLC
02:11:29.284 MTR: >Command   ( 30938) [00]003E/0006 AddNOC
02:11:29.289 MTR: <Replied   ( 30938) [00]003E/0008 NOCResponse
02:11:29.361 MTR: calling mdns.remove_service(_matterc, _udp, C55FF6E30ABE9EE5, FCF5C400F388)
02:11:29.364 MTR: remove mDNS on wifi 'C55FF6E30ABE9EE5'
02:11:29.368 MTR: Operational Discovery node = 559EC2CD2E921EEB-00000000A8048B01
02:11:29.376 MTR: adding mDNS on wifi '559EC2CD2E921EEB-00000000A8048B01' ptr to `FCF5C400F388.local`
02:11:29.389 MTR: adding subtype: _I559EC2CD2E921EEB
02:11:29.413 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:29.489 MTR: .          Duplicate encrypted message = 231117906 counter=231117906
02:11:29.755 MTR: HTTP async-resp in 335 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:30.193 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:30.199 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:30.660 MTR: >Received  (     0) CASE_Sigma1 rid=59261836 exch=60173 from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:30.716 MTR: +Session   ( 18659) from '[xxxx::xxxx:xxxx:xxxx:xxxx]:46839'
02:11:31.106 MTR: <Replied   (     0) CASE_Sigma2
02:11:31.114 MTR: New Connection (CASE id=18659) from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:31.159 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:31.356 MTR: >Received  (     0) CASE_Sigma3 rid=59261838 exch=60173 from [xxxx::xxxx:xxxx:xxxx:xxxx]:46839
02:11:31.676 MTR: Sigma3 verified, computing new keys
02:11:31.686 MTR: <Replied   (     0) StatusReport
02:11:31.753 MTR: =Saved     0 fabric(s) and 0 session(s)
02:11:31.867 MTR: >Read_Attr ( 18659) [00]003C/0000 (WindowStatus) - 0U
02:11:31.926 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:32.463 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:32.815 MTR: HTTP async-resp in 339 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:33.023 MTR: >Read_Attr ( 18659) [00]0028/000F (SerialNumber) - "FC:F5:C4:00:F3:88"
02:11:33.084 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:33.771 MTR: >Read_Attr ( 18659) [00]001D/0003 (PartsList) - [65280U, 1U, 2U, 3U, 4U]
02:11:33.835 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:33.979 MTR: >Read_Attr ( 18659) [00]001D/0000 (DeviceTypeList) - [{0 = 22U, 1 = 1U}]
02:11:34.220 MTR: >Read_Attr ( 18659) [00]001D/0001 (ServerList) - [48U, 49U, 50U, 51U, 52U, 40U, 63U, 56U, 57U, 60U, 43U, 44U, 29U, 62U, 31U]
02:11:34.299 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:34.592 MTR: >Read_Attr ( 18659) [00]001D/0002 (ClientList) - []
02:11:34.839 MTR: >Read_Attr ( 18659) [FF00]001D/0003 (PartsList) - [1U, 2U, 3U, 4U]
02:11:35.249 MTR: >Read_Attr ( 18659) [FF00]001D/0000 (DeviceTypeList) - [{0 = 14U, 1 = 1U}]
02:11:35.456 MTR: >Read_Attr ( 18659) [FF00]001D/0001 (ServerList) - [57U, 29U]
02:11:35.628 MTR: >Read_Attr ( 18659) [FF00]001D/0002 (ClientList) - []
02:11:35.694 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:35.847 MTR: >Read_Attr ( 18659) [01]001D/0003 (PartsList) - []
02:11:36.085 MTR: >Read_Attr ( 18659) [01]001D/0000 (DeviceTypeList) - [{0 = 266U, 1 = 2U}, {0 = 19U, 1 = 1U}]
02:11:36.145 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:36.152 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:36.316 MTR: >Read_Attr ( 18659) [01]001D/0001 (ServerList) - [6U, 29U, 3U, 57U, 4U, 5U]
02:11:36.440 MTR: HTTP async-resp in 741 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:36.552 MTR: >Read_Attr ( 18659) [01]001D/0002 (ClientList) - []
02:11:36.739 MTR: >Read_Attr ( 18659) [02]001D/0003 (PartsList) - []
02:11:37.004 MTR: >Read_Attr ( 18659) [02]001D/0000 (DeviceTypeList) - [{0 = 266U, 1 = 2U}, {0 = 19U, 1 = 1U}]
02:11:37.088 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:37.291 MTR: >Read_Attr ( 18659) [02]001D/0001 (ServerList) - [6U, 29U, 3U, 57U, 4U, 5U]
02:11:37.373 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:37.664 MTR: >Read_Attr ( 18659) [02]001D/0002 (ClientList) - []
02:11:37.914 MTR: >Read_Attr ( 18659) [03]001D/0003 (PartsList) - []
02:11:38.143 MTR: >Read_Attr ( 18659) [03]001D/0000 (DeviceTypeList) - [{0 = 256U, 1 = 2U}, {0 = 19U, 1 = 1U}]
02:11:38.226 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:38.473 MTR: >Read_Attr ( 18659) [03]001D/0001 (ServerList) - [6U, 29U, 3U, 57U, 4U, 5U]
02:11:38.721 MTR: >Read_Attr ( 18659) [03]001D/0002 (ClientList) - []
02:11:38.803 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:39.043 MTR: >Read_Attr ( 18659) [04]001D/0003 (PartsList) - []
02:11:39.172 MTR: HTTP async-resp in 362 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:39.198 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:39.365 MTR: >Read_Attr ( 18659) [04]001D/0000 (DeviceTypeList) - [{0 = 256U, 1 = 2U}, {0 = 19U, 1 = 1U}]
02:11:39.580 MTR: >Read_Attr ( 18659) [04]001D/0001 (ServerList) - [6U, 29U, 3U, 57U, 4U, 5U]
02:11:39.840 MTR: >Read_Attr ( 18659) [04]001D/0002 (ClientList) - []
02:11:40.456 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:42.065 MTR: =Saved     1 fabric(s) and 1 session(s)
02:11:42.074 MTR: >Command   ( 18659) [00]0030/0004 CommissioningComplete
02:11:42.080 MTR: <Replied   ( 18659) [00]0030/0005 CommissioningCompleteResponse
02:11:42.128 MTR: --- Commissioning complete for Fabric '212E7C7BFC27119E' (Vendor Google LLC) ---
02:11:42.156 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:42.175 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:42.352 MTR: >Read_Attr ( 18659) [00]003C/0000 (WindowStatus) - 0U
02:11:42.596 MTR: >Read_Attr ( 18659) [00]0028/0002 (VendorID) - 65521U
02:11:42.683 MTR: HTTP async-resp in 502 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:42.825 MTR: >Read_Attr ( 18659) [00]0028/0004 (ProductID) - 32768U
02:11:43.012 MTR: >Command   ( 18659) TimedRequest=60000
02:11:43.164 MTR: starting mDNS on wifi '212E7DDBDA3B7E1A' ptr to `FCF5C400F388.local`
02:11:43.166 MTR: adding subtype: _L969
02:11:43.169 MTR: adding subtype: _S3
02:11:43.170 MTR: adding subtype: _V65521
02:11:43.172 MTR: adding subtype: _CM1
02:11:43.175 MTR: >Command   ( 18659) [00]003C/0000 OpenCommissioningWindow
02:11:43.179 MTR: <Replied   ( 18659) OK exch=60204
02:11:43.248 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:43.490 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:44.258 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:45.231 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:45.630 MTR: HTTP async-resp in 392 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:46.016 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:46.023 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:46.735 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:47.280 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:48.233 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:48.487 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:48.631 MTR: HTTP async-resp in 390 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:49.255 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:49.965 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:51.250 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:51.398 MTR: HTTP async-resp in 139 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:51.688 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:51.694 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:52.459 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:53.217 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:53.723 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:54.477 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:11:54.494 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:54.920 MTR: HTTP async-resp in 418 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:56.225 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:56.231 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:11:56.252 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:57.502 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:11:57.520 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:57.761 MTR: >Received  (     0) PBKDFParamRequest rid=164585330 exch=44145 from [xxxx::xxxx:xxxx:xxxx:xxxx]:5541
02:11:57.778 MTR: +Session   ( 57086) from '[xxxx::xxxx:xxxx:xxxx:xxxx]:5541'
02:11:57.797 MTR: <Replied   (     0) PBKDFParamResponse
02:11:57.917 MTR: HTTP async-resp in 389 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:11:58.056 MTR: >Received  (     0) PASE_Pake1 rid=164585332 exch=44145 from [xxxx::xxxx:xxxx:xxxx:xxxx]:5541
02:11:58.706 MTR: <Replied   (     0) PASE_Pake2
02:11:58.711 MTR: New Commissioning (PASE id=57086) from [xxxx::xxxx:xxxx:xxxx:xxxx]:5541
02:11:58.762 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:11:58.821 MTR: >Received  (     0) PASE_Pake3 rid=164585334 exch=44145 from [xxxx::xxxx:xxxx:xxxx:xxxx]:5541
02:11:58.834 MTR: <Replied   (     0) StatusReport
02:11:59.006 MTR: >Command   ( 57086) TimedRequest=10000
02:11:59.308 MTR: >Command   ( 57086) [00]0030/0000 ArmFailSafe
02:11:59.313 MTR: <Replied   ( 57086) [00]0030/0001 ArmFailSafeResponse
02:11:59.386 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:11:59.525 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:00.029 MTR: >Command   ( 57086) [00]003E/0004 CSRRequest
02:12:00.035 MTR: <Replied   ( 57086) [00]003E/0005 CSRResponse
02:12:00.541 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:00.943 MTR: HTTP async-resp in 395 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:01.237 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:01.244 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:02.556 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:02.763 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:03.018 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:03.569 MTR: HTTP async-resp in 544 ms from xxx.xxx.xxx.xxx: [191] '{"StatusFWR":{"Version":"13.0....'
02:12:03.773 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:03.790 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:03.855 MTR: >Command   ( 57086) [00]003E/000B AddTrustedRootCertificate
02:12:03.860 MTR: <Replied   ( 57086) OK exch=44148
02:12:04.250 MTR: +Fabric    fab='9B5B2BAA3832FAA7' vendorid=0x110A
02:12:04.254 MTR: >Command   ( 57086) [00]003E/0006 AddNOC
02:12:04.261 MTR: <Replied   ( 57086) [00]003E/0008 NOCResponse
02:12:04.311 MTR: calling mdns.remove_service(_matterc, _udp, 212E7DDBDA3B7E1A, FCF5C400F388)
02:12:04.321 MTR: remove mDNS on wifi '212E7DDBDA3B7E1A'
02:12:04.325 MTR: Operational Discovery node = 40F33EB7B509FB27-F78A6BF69865DA15
02:12:04.332 MTR: adding mDNS on wifi '40F33EB7B509FB27-F78A6BF69865DA15' ptr to `FCF5C400F388.local`
02:12:04.346 MTR: adding subtype: _I40F33EB7B509FB27
02:12:04.376 MTR: HTTP async-resp in 578 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:04.553 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:05.603 MTR: >Received  (     0) CASE_Sigma1 rid=164585335 exch=44150 from [xxx.xxx.xxx.xxx]:5541
02:12:05.694 MTR: +Session   ( 47548) from '[xxx.xxx.xxx.xxx]:5541'
02:12:06.084 MTR: <Replied   (     0) CASE_Sigma2
02:12:06.092 MTR: New Connection (CASE id=47548) from [xxx.xxx.xxx.xxx]:5541
02:12:06.132 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:06.387 MTR: >Received  (     0) CASE_Sigma3 rid=164585337 exch=44150 from [xxx.xxx.xxx.xxx]:5541
02:12:06.696 MTR: Sigma3 verified, computing new keys
02:12:06.708 MTR: <Replied   (     0) StatusReport
02:12:06.899 MTR: =Saved     1 fabric(s) and 1 session(s)
02:12:06.959 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:07.022 MTR: >Command   ( 47548) TimedRequest=10000
02:12:07.142 MTR: .          Duplicate encrypted message = 227348683 counter=227348683
02:12:07.454 MTR: =Saved     2 fabric(s) and 2 session(s)
02:12:07.465 MTR: >Command   ( 47548) [00]0030/0004 CommissioningComplete
02:12:07.471 MTR: <Replied   ( 47548) [00]0030/0005 CommissioningCompleteResponse
02:12:07.520 MTR: --- Commissioning complete for Fabric '9B5B2BAA3832FAA7' (Vendor 0x110A) ---
02:12:07.549 MTR: HTTP async-resp in 582 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:07.577 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:08.813 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:09.287 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:09.791 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:10.042 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:10.463 MTR: HTTP async-resp in 414 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:11.509 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:11.516 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:12.528 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:12.787 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:13.295 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:13.747 MTR: HTTP async-resp in 442 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:14.054 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:14.816 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:15.774 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:16.530 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:16.937 MTR: HTTP async-resp in 399 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:17.562 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:17.569 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:17.811 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:18.777 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:19.051 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:19.550 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:19.707 MTR: HTTP async-resp in 149 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:20.017 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:21.727 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:21.733 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:21.791 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:22.809 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:23.053 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:23.253 MTR: HTTP async-resp in 438 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:24.268 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:25.031 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:25.284 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:26.044 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:26.497 MTR: HTTP async-resp in 446 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:27.039 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:27.046 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:28.266 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:28.522 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:29.044 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:29.242 MTR: HTTP async-resp in 187 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:29.544 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:30.524 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:31.530 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:32.321 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:32.508 MTR: HTTP async-resp in 180 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:33.253 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:33.260 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:33.282 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:34.535 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:34.847 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:35.537 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:35.555 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:35.749 MTR: HTTP async-resp in 187 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:37.250 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:37.256 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:37.774 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:38.528 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:38.784 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:39.256 MTR: HTTP async-resp in 465 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:40.025 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:40.781 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:41.035 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:41.794 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:42.380 MTR: HTTP async-resp in 579 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:42.757 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:42.764 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:43.779 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:44.036 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:44.795 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:45.026 MTR: HTTP async-resp in 223 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:45.054 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:46.054 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:47.263 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:48.024 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:48.274 MTR: HTTP async-resp in 243 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:48.784 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:50.055 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:50.304 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:51.060 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:51.078 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:51.573 MTR: HTTP async-resp in 488 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:52.774 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:52.780 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:52.981 MTR: -Session   (     0) (removed)
02:12:53.540 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:53.799 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:54.308 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:54.558 MTR: HTTP async-resp in 243 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:55.266 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:55.779 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:12:56.079 MTR: HTTP async-resp in 294 ms from xxx.xxx.xxx.xxx: [275] '{"StatusNET":{"Hostname":"Kitc...'
02:12:56.283 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:12:56.786 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:57.542 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:12:57.800 MTR: HTTP async-resp in 251 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:12:58.494 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:58.501 MTR: HTTP timeout http_status=-2 phase=0 tcp_status=-3 size_payload=0
02:12:58.809 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:12:59.796 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:00.306 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:13:00.555 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:00.856 MTR: HTTP async-resp in 293 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:13:01.317 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:13:03.032 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:03.795 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:04.053 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+2'
02:13:04.100 MTR: HTTP async-resp in 295 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'
02:13:05.318 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+5'
02:13:06.268 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:06.528 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status'
02:13:07.030 MTR: HTTP async request 'http://xxx.xxx.xxx.xxx:80/cm?cmnd=Status+11'
02:13:07.585 MTR: HTTP async-resp in 548 ms from xxx.xxx.xxx.xxx: [313] '{"StatusSTS":{"Time":"2023-07-...'

s-hadinger · 2023-07-30T17:10:22Z

s-hadinger
Jul 30, 2023
Collaborator

Please try again with latest version. Also make sure that Tasmota is on the same LAN than your app and your gateway

12 replies

DavideCatto Oct 4, 2023

Log during pairing:

00:00:00.003 HDW: ESP32-D0WD-V3 
00:00:00.052 UFS: FlashFS mounted with 304 kB free
00:00:00.077 CFG: Loaded from File, Count 12
00:00:00.087 QPC: Count 1
00:00:00.089 CFG: CR 423/699, Busy 0
00:00:00.102 ROT: Mode 1
00:00:00.180 CFG: No '*.autoconf' file found
00:00:00.250 MTR: Session_Store::load Exception:attribute_error|'nil' value has no method 'find'
00:00:00.288 MTR: =Saved     parameters
00:00:00.311 MTR: Manual pairing code: 34284802920
00:00:00.319 RSL: RESULT = {"Matter":{"Commissioning":1,"PairingCode":"34284802920","QRCode":"MT:Y.K90KCZ16ME2I2W300"}}
00:00:00.779 BRY: Berry initialized, RAM used=11399 bytes
00:00:00.795 BRY: No 'preinit.be'
00:00:00.801 TFS: File '.drvset003' not found
00:00:00.801 CFG: Energy use defaults as file system not ready or file not found
00:00:00.805 SRC: Restart
00:00:00.816 Project tasmota - Tasmota Version 13.1.0(tasmota32)-2_0_11(2023-08-15T08:00:54)
00:00:00.817 ETH: No ETH MDC and/or ETH MDIO GPIO defined
00:00:00.884 BRY: No 'autoexec.be'
00:00:01.063 WIF: Attempting connection...
00:00:01.530 WIF: Connecting to AP1 Veradsl_EXT Channel 6 BSSId 60:32:B1:3C:3C:EB in mode 11n as tasmota-326FF0-4080...
00:00:02.107 WIF: Attempting connection...
00:00:02.322 WIF: IPv4 192.168.0.2, mask 255.255.0.0, gateway 192.168.0.1
00:00:03.333 WIF: Connected
00:00:03.393 MTR: Status 13 = {'Command': 'Error'}
00:00:03.403 MTR: autoconfig = {'1': {'type': 'light1'}}
00:00:03.405 MTR: Configuring endpoints
00:00:03.423 MTR:   endpoint =     0 type:root
00:00:03.445 MTR:   endpoint =     1 type:light1
00:00:03.446 MTR:   endpoint = 65280 type:aggregator
00:00:03.448 RSL: RESULT = {"Matter":{"Initialized":1}}
00:00:03.457 MTR: Starting UDP server on port: 5540
00:00:03.478 MTR: start mDNS on wifi host 'B0A732326FF0.local'
00:00:03.503 MTR: starting mDNS on wifi 'C762E4C0E6225A11' ptr to `B0A732326FF0.local`
00:00:03.505 MTR: adding subtype: _L3606
00:00:03.506 MTR: adding subtype: _S14
00:00:03.517 MTR: adding subtype: _V65521
00:00:03.519 MTR: adding subtype: _CM1
00:00:03.600 HTP: Web server active on tasmota-326FF0-4080 with IP address 192.168.0.2
00:00:04.366 WIF: IPv6 Local fe80::b2a7:32ff:fe32:6ff0
00:00:04.370 RTC: UTC 2023-10-04T07:10:04, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
08:10:04.000 RTC: Synced by NTP
08:10:04.471 RSL: INFO1 = {"Info1":{"Module":"ESP32-DevKit","Version":"13.1.0(tasmota32)","FallbackTopic":"cmnd/DVES_326FF0_fb/","GroupTopic":"cmnd/tasmotas/"}}
08:10:04.490 RSL: INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"tasmota-326FF0-4080","IPAddress":"192.168.0.2","IP6Global":"","IP6Local":"fe80::b2a7:32ff:fe32:6ff0"}}
08:10:04.510 RSL: INFO3 = {"Info3":{"RestartReason":"Software reset CPU","BootCount":4}}
08:10:04.521 RSL: RESULT = {"POWER":"OFF"}
08:10:04.523 RSL: POWER = OFF
08:10:04.530 TFS: File 'autoexec.bat' not found
08:10:06.937 QPC: Reset
08:10:07.875 HTP: Main Menu
08:10:08.920 APP: Boot Count 4
08:10:08.929 RSL: STATE = {"Time":"2023-10-04T08:10:08","Uptime":"0T00:00:09","UptimeSec":9,"Heap":86,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":45,"MqttCount":0,"Berry":{"HeapUsed":14,"Objects":202},"POWER":"OFF","Dimmer":10,"Fade":"OFF","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"Veradsl_EXT","BSSId":"60:32:B1:3C:3C:EB","Channel":6,"Mode":"11n","RSSI":84,"Signal":-58,"LinkCount":1,"Downtime":"0T00:00:04"}}
08:10:09.443 CFG: Saved, Count 13, Bytes 4096
08:10:13.051 HTP: Main Menu
08:10:15.945 HTP: Consoles
08:10:17.416 HTP: Console
08:11:06.800 MTR: >Received  (     0) PBKDFParamRequest rid=87017451 exch=57630 from [fe80::35b5:ca3:126e:afa1]:47437
08:11:06.815 MTR: +Session   ( 18991) from '[fe80::35b5:ca3:126e:afa1]:47437'
08:11:06.840 MTR: <Replied   (     0) PBKDFParamResponse
08:11:06.950 MTR: >Received  (     0) PASE_Pake1 rid=87017453 exch=57630 from [fe80::35b5:ca3:126e:afa1]:47437
08:11:07.602 MTR: <Replied   (     0) PASE_Pake2
08:11:07.607 MTR: New Commissioning (PASE id=18991) from [fe80::35b5:ca3:126e:afa1]:47437
08:11:07.773 MTR: >Received  (     0) PASE_Pake3 rid=87017455 exch=57630 from [fe80::35b5:ca3:126e:afa1]:47437
08:11:07.786 MTR: <Replied   (     0) StatusReport
08:11:08.110 MTR: >Read_Attr1( 18991) [00]0028/0002! (VendorID) - 65521U
08:11:08.258 MTR: >Read_Attr1( 18991) [00]0028/0004! (ProductID) - 32768U
08:11:08.530 MTR: >Read_Attr1( 18991) [00]003E/0002! (SupportedFabrics) - 5U
08:11:08.696 MTR: >Read_Attr1( 18991) [00]003E/0003! (CommissionedFabrics) - 0U
08:11:08.869 MTR: >Read_Attr1( 18991) [00]003E/0001 (Fabrics) - []
08:11:09.071 MTR: >Read_Attr1( 18991) [00]001D/0001! (ServerList) - [48U, 49U, 50U, 51U, 52U, 40U, 63U, 56U, 57U, 60U, 43U, 44U, 29U, 62U, 31U]
08:11:09.652 MTR: >Read_Attr1( 18991) [00]0031/FFFC! (FeatureMap) - 4U
08:11:11.281 MTR: >Command   ( 18991) TimedRequest=10000
08:11:11.417 MTR: >Command1  ( 18991) [00]0030/0000 ArmFailSafe 
08:11:11.429 MTR: <Replied   ( 18991) [00]0030/0001 ArmFailSafe
08:11:11.760 MTR: >Read_Attr1( 18991) [00]0030/0003! (LocationCapability) - 2U
08:11:11.926 MTR: >Command   ( 18991) TimedRequest=10000
08:11:12.438 MTR: >Command1  ( 18991) [00]0030/0002 SetRegulatoryConfig 
08:11:12.451 MTR: <Replied   ( 18991) [00]0030/0003 SetRegulatoryConfig
08:11:12.547 MTR: .          Duplicate encrypted message = 237833943 counter=237833943
08:11:12.726 MTR: >Command1  ( 18991) [00]003E/0002 CertificateChainRequest 
08:11:12.738 MTR: <Replied   ( 18991) [00]003E/0003 CertificateChainRequest
08:11:13.207 MTR: >Command1  ( 18991) [00]003E/0002 CertificateChainRequest 
08:11:13.219 MTR: <Replied   ( 18991) [00]003E/0003 CertificateChainRequest
08:11:13.266 MTR: .          Duplicate encrypted message = 237833947 counter=237833948
08:11:13.703 MTR: >Command1  ( 18991) [00]003E/0000 AttestationRequest 
08:11:13.719 MTR: <Replied   ( 18991) [00]003E/0001 AttestationRequest
08:11:13.747 MTR: .          Duplicate encrypted message = 237833950 counter=237833950
08:11:14.131 MTR: .          Duplicate encrypted message = 237833950 counter=237833950
08:11:14.532 MTR: >Command1  ( 18991) [00]003E/0004 CSRRequest 
08:11:14.545 MTR: <Replied   ( 18991) [00]003E/0005 CSRRequest
08:11:17.162 MTR: >Command1  ( 18991) [00]003E/000B AddTrustedRootCertificate 
08:11:17.167 MTR: <Replied   ( 18991) OK exch=57645
08:11:17.515 MTR: +Fabric    fab='EC734199009FAF3C' vendorid=Google LLC
08:11:17.520 MTR: >Command1  ( 18991) [00]003E/0006 AddNOC 
08:11:17.531 MTR: <Replied   ( 18991) [00]003E/0008 AddNOC
08:11:17.556 RSL: RESULT = {"Matter":{"Commissioning":0}}
08:11:17.565 MTR: calling mdns.remove_service(_matterc, _udp, C762E4C0E6225A11, B0A732326FF0)
08:11:17.574 MTR: remove mDNS on wifi 'C762E4C0E6225A11'
08:11:17.579 MTR: Operational Discovery node = 730138818DD37066-0000000065D9F49D
08:11:17.587 MTR: adding mDNS on wifi '730138818DD37066-0000000065D9F49D' ptr to `B0A732326FF0.local`
08:11:17.601 MTR: adding subtype: _I730138818DD37066
08:11:18.846 MTR: >Received  (     0) CASE_Sigma1 rid=87017456 exch=57647 from [fe80::35b5:ca3:126e:afa1]:47437
08:11:18.919 MTR: +Session   (  6124) from '[fe80::35b5:ca3:126e:afa1]:47437'
08:11:19.328 MTR: <Replied   (     0) CASE_Sigma2
08:11:19.335 MTR: New Connection (CASE id=6124) from [fe80::35b5:ca3:126e:afa1]:47437
08:11:19.507 MTR: >Received  (     0) CASE_Sigma3 rid=87017458 exch=57647 from [fe80::35b5:ca3:126e:afa1]:47437
08:11:19.844 MTR: Sigma3 verified, computing new keys
08:11:19.861 MTR: <Replied   (     0) StatusReport
08:11:19.921 MTR: =Saved     0 fabric(s) and 0 session(s)
08:11:20.052 MTR: >Read_Attr1(  6124) [00]003C/0000! (WindowStatus) - 0U
08:11:21.007 MTR: >Read_Attr1(  6124) [00]0028/000F! (SerialNumber) - B0:A7:32:32:6F:F0
08:11:21.134 MTR: .          Duplicate encrypted message = 262496341 counter=262496341
08:11:21.538 MTR: >Read_Attr1(  6124) [00]001D/0003! (PartsList) - [65280U, 1U]
08:11:21.742 MTR: >Read_Attr1(  6124) [00]001D/0000! (DeviceTypeList) - [{0 = 22U, 1 = 1U}]
08:11:21.947 MTR: >Read_Attr1(  6124) [00]001D/0001! (ServerList) - [48U, 49U, 50U, 51U, 52U, 40U, 63U, 56U, 57U, 60U, 43U, 44U, 29U, 62U, 31U]
08:11:22.073 MTR: >Read_Attr1(  6124) [00]001D/0002! (ClientList) - []
08:11:22.437 MTR: >Read_Attr1(  6124) [FF00]001D/0003! (PartsList) - [1U]
08:11:22.669 MTR: >Read_Attr1(  6124) [FF00]001D/0000! (DeviceTypeList) - [{0 = 14U, 1 = 1U}]
08:11:22.833 MTR: >Read_Attr1(  6124) [FF00]001D/0001! (ServerList) - [57U, 29U]
08:11:23.140 MTR: >Read_Attr1(  6124) [FF00]001D/0002! (ClientList) - []
08:11:23.285 MTR: >Read_Attr1(  6124) [01]001D/0003! (PartsList) - []
08:11:23.465 MTR: >Read_Attr1(  6124) [01]001D/0000! (DeviceTypeList) - [{0 = 257U, 1 = 2U}, {0 = 19U, 1 = 1U}]
08:11:23.900 MTR: .          Duplicate encrypted message = 262496370 counter=262496370
08:11:24.010 MTR: >Read_Attr1(  6124) [01]001D/0001! (ServerList) - [8U, 57U, 3U, 4U, 5U, 6U, 29U]
08:11:24.188 MTR: >Read_Attr1(  6124) [01]001D/0002! (ClientList) - []
08:11:24.404 MTR: >Read_Attr1(  6124) [00]0028/0012! (UniqueID) - B0:A7:32:32:6F:F0
08:11:30.448 MTR: =Saved     1 fabric(s) and 1 session(s)
08:11:30.480 MTR: =Saved     parameters and configuration
08:11:30.487 MTR: >Command1  (  6124) [00]0030/0004 CommissioningComplete 
08:11:30.502 MTR: <Replied   (  6124) [00]0030/0005 CommissioningComplete
08:11:30.527 MTR: --- Commissioning complete for Fabric 'EC734199009FAF3C' (Vendor Google LLC) ---

The device is always Offline on my Google Home Application.

DavideCatto Oct 4, 2023

Using the application "GHSAFM-TC Android app" recommended by @Derek-K it works, but only toggle button. With GH app nope. LoL

s-hadinger Oct 4, 2023
Collaborator

Thanks. The initial connection is from fe80::35b5:ca3:126e:afa1. Can you check which device this is on your network? Is it your phone or your GH device?

Do you see any new connection later in the logs?

Edit: all logs are normal and show nothing out of ordinary.

DavideCatto Oct 4, 2023

Yes it's my phone.
I found maybe the problem: actually my google home device is broken: I read that for matter protocol it's necessary to have an hub device indeed when I try to connect my ESP32 device a warning from GHApp is displayed but then after a while everything seem ok and the device is added (already verified the logs).

Is it possible to connect an ESP32 like a normal Sonoff device without the hub?
I can t understand why with "GHSAFM-TC Android app" everything work and with GH nope.

s-hadinger Oct 4, 2023
Collaborator

GH requires a hub on the same network as your end device. We have no control on this.

Derek-K · 2023-11-05T14:56:12Z

Derek-K
Nov 5, 2023
Author

Update. Was busy earlier, and finally have time to test 13.2.0.2. It works much better, very close to my expectation. I was even able to add another ESP8266 based Tasmota device to the ESP32 Tasmota that I was testing Matter. I was able to see all the switches (and one temp reading too). Except, one of the switch was showing offline in Google home, but it works the whole time on the ESP32 main screen. I have a feeling is during the matter connection phase, if there is some sort of timeout, it will remain offline in Google home.

I will have to experiment it more to see what's the cause.

I have even tried briefly, using Google home (on my phone), disconnected from my home network, and I was still able to control it.

2 replies

Derek-K Nov 5, 2023
Author

I speak too soon... I just re-tested it.

I removed all the matter device from Google Home on my phone.
Added all tasmota (ESP8266 based) to a central ESP32 Tasmota that I plan to enable Matter
Checked the Main screen on the ESP32 device, every looks ok.
Connect Google Home via Matter

All 'end point' appears after second tries. (first time Google said "Something went wrong", my gut feel it is taking too long) except 2 end points shows offline no matter what I tried.

It was all good for a while (maybe 5-10 minutes), all of the sudden, everything on my Google home screen disappears, even the ESP32 bridge. Except one random endpoint, but even the label was wrong.

p.s. My previous test, I have never experience all end point disappear in Google Home. This is the first time. Very odd.

s-hadinger Nov 5, 2023
Collaborator

Matter is still in early stage with some brands...

Dyrke · 2024-12-12T09:12:31Z

Dyrke
Dec 12, 2024

I am still having this problem even running tasmota 14.4
Sometimes it works sometimes it says offline, sometimes it says not configured.
And yes its google home I am using.

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

What's the matter? #19008

{{title}}

Replies: 4 comments 26 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

What's the matter? #19008

Derek-K Jul 1, 2023

Replies: 4 comments · 26 replies

Derek-K Jul 1, 2023 Author

Derek-K Jul 8, 2023 Author

s-hadinger Jul 8, 2023 Collaborator

Derek-K Jul 18, 2023 Author

s-hadinger Jul 18, 2023 Collaborator

Derek-K Jul 21, 2023 Author

s-hadinger Jul 30, 2023 Collaborator

DavideCatto Oct 4, 2023

DavideCatto Oct 4, 2023

s-hadinger Oct 4, 2023 Collaborator

DavideCatto Oct 4, 2023

s-hadinger Oct 4, 2023 Collaborator

Derek-K Nov 5, 2023 Author

Derek-K Nov 5, 2023 Author

s-hadinger Nov 5, 2023 Collaborator

Dyrke Dec 12, 2024

Derek-K
Jul 1, 2023

Replies: 4 comments 26 replies

Derek-K
Jul 1, 2023
Author

Derek-K Jul 8, 2023
Author

s-hadinger Jul 8, 2023
Collaborator

Derek-K Jul 18, 2023
Author

s-hadinger Jul 18, 2023
Collaborator

Derek-K Jul 21, 2023
Author

s-hadinger
Jul 30, 2023
Collaborator

s-hadinger Oct 4, 2023
Collaborator

s-hadinger Oct 4, 2023
Collaborator

Derek-K
Nov 5, 2023
Author

Derek-K Nov 5, 2023
Author

s-hadinger Nov 5, 2023
Collaborator

Dyrke
Dec 12, 2024