I am trying to pair another ecosystem after being paired with the Home app. The third party ecosystem here is the chip-tool from the matter SDK. I can reproduce the issue following these steps: Pair with Apple Home app. In accessory settings in Home app, open pairing mode to obtain pairing code. Pair accessory with Chip-Tool using the following command: “./chip-tool pairing code 1234 <Home App generated Pairing Code>” Chip tool fails pairing at the “SendComplete” step. I wonder if there is some configuration that is limiting the number of pairing systems.

Matter device fails to pair a second ecosystem

I am trying to pair another ecosystem after being paired with the Home app. The third party ecosystem here is the chip-tool from the matter SDK.

I can reproduce the issue following these steps:

Pair with Apple Home app.
In accessory settings in Home app, open pairing mode to obtain pairing code.
Pair accessory with Chip-Tool using the following command: “./chip-tool pairing code 1234 <Home App generated Pairing Code>”
Chip tool fails pairing at the “SendComplete” step.

I wonder if there is some configuration that is limiting the number of pairing systems.

Parents

0 Hugo.Dev over 2 years ago

Attached logs from both sides.

Device logs:

b05> [5D[J[21:52:03.437,927] <inf> chip: [EM]>>> [E:61084r S:5793 M:53712788] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:02 (IM:ReadRequest)
b05> [5D[J[21:52:03.438,873] <inf> chip: [DL]OpenThread SED interval is 200ms
b05> [5D[J[21:52:03.440,124] <inf> chip: [EM]<<< [E:61084r S:5793 M:129165141 (Ack:53712788)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:03.440,277] <inf> chip: [IN](S) Sending msg 129165141 on secure session with LSID: 5793
b05> [5D[J[21:52:03.519,256] <inf> chip: [EM]>>> [E:61084r S:5793 M:53712789 (Ack:129165141)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:03.519,439] <inf> chip: [DL]OpenThread SED interval is 1000ms
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:04.762,359] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712790] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:0a (IM:TimedRequest)
b05> [5D[J[21:52:04.763,519] <inf> chip: [EM]<<< [E:61085r S:5793 M:129165142 (Ack:53712790)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:04.763,671] <inf> chip: [IN](S) Sending msg 129165142 on secure session with LSID: 5793
b05> [5D[J[21:52:04.764,984] <inf> chip: [DL]OpenThread SED interval is 200ms
b05> [5D[J[21:52:05.338,958] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712791 (Ack:129165142)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:05.339,721] <inf> chip: [ZCL]Received command to open commissioning window
b05> [5D[J[21:52:05.340,332] <inf> chip: [DIS]Updating services using commissioning mode 2
b05> [5D[J[21:52:05.340,545] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:05.341,094] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:05.341,613] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:05.343,688] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=0495/01 cm=2
b05> [5D[J[21:52:05.344,238] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:05.344,482] <inf> chip: [DL]removing srp service: 2D6B524ECA0C2C2A._matterc._udp
b05> [5D[J[21:52:05.344,696] <inf> chip: [ZCL]Commissioning window is now open
b05> [5D[J[21:52:05.345,977] <inf> chip: [EM]<<< [E:61085r S:5793 M:129165143 (Ack:53712791)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:05.346,130] <inf> chip: [IN](S) Sending msg 129165143 on secure session with LSID: 5793
b05> [5D[J[21:52:05.358,367] <inf> chip: [EM]<<< [E:4664i S:5793 M:129165144] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:05.358,581] <inf> chip: [IN](S) Sending msg 129165144 on secure session with LSID: 5793
b05> [5D[J[21:52:05.360,534] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:05.385,498] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712792 (Ack:129165143)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:05.627,868] <inf> chip: [EM]>>> [E:4664i S:5793 M:53712793 (Ack:129165144)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:05.628,143] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:05.629,547] <inf> chip: [EM]<<< [E:4664i S:5793 M:129165145 (Ack:53712793)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:05.629,699] <inf> chip: [IN](S) Sending msg 129165145 on secure session with LSID: 5793
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:22.591,644] <inf> chip: [EM]>>> [E:7114r S:0 M:164073984] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:20 (SecureChannel:PBKDFParamRequest)
b05> [5D[J[21:52:22.592,132] <inf> chip: [EM]<<< [E:7114r S:0 M:145049556 (Ack:164073984)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:22.592,376] <inf> chip: [IN](U) Sending msg 145049556 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:22.594,818] <inf> chip: [EM]<<< [E:7114r S:0 M:145049557 (Ack:164073984)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:21 (SecureChannel:PBKDFParamResponse)
b05> [5D[J[21:52:22.595,062] <inf> chip: [IN](U) Sending msg 145049557 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:22.810,028] <inf> chip: [EM]>>> [E:7114r S:0 M:164073985 (Ack:145049557)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:22 (SecureChannel:PASE_Pake1)
b05> [5D[J[21:52:22.810,546] <inf> chip: [EM]<<< [E:7114r S:0 M:145049558 (Ack:164073985)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:22.810,791] <inf> chip: [IN](U) Sending msg 145049558 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:24.485,229] <inf> chip: [EM]<<< [E:7114r S:0 M:145049559 (Ack:164073985)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:23 (SecureChannel:PASE_Pake2)
b05> [5D[J[21:52:24.485,473] <inf> chip: [IN](U) Sending msg 145049559 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:25.537,384] <inf> chip: [IN](U) Sending msg 145049559 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.803,375] <inf> chip: [EM]>>> [E:7114r S:0 M:164073986 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:24 (SecureChannel:PASE_Pake3)
b05> [5D[J[21:52:25.803,894] <inf> chip: [EM]<<< [E:7114r S:0 M:145049560 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:25.804,138] <inf> chip: [IN](U) Sending msg 145049560 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.806,091] <inf> chip: [EM]<<< [E:7114r S:0 M:145049561 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:40 (SecureChannel:StatusReport)
b05> [5D[J[21:52:25.806,549] <inf> chip: [IN](U) Sending msg 145049561 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.808,166] <inf> chip: [SC]SecureSession[0x200042c0]: Moving from state ‘kEstablishing’ —> ‘kActive’
b05> [5D[J[21:52:25.808,258] <inf> chip: [SVR]Commissioning completed session establishment step
b05> [5D[J[21:52:25.808,776] <inf> chip: [DIS]Updating services using commissioning mode 0
b05> [5D[J[21:52:25.809,051] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:25.810,729] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:25.811,645] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:25.813,751] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
b05> [5D[J[21:52:25.814,422] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:25.814,666] <inf> chip: [SVR]Device completed Rendezvous process
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:26.198,486] <inf> chip: [EM]>>> [E:7114r S:0 M:164073987 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.222,167] <inf> chip: [EM]>>> [E:7114r S:0 M:164073986 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:24 (SecureChannel:PASE_Pake3)
b05> [5D[J[21:52:26.222,595] <inf> chip: [EM]<<< [E:7114r S:0 M:145049562 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.222,839] <inf> chip: [IN](U) Sending msg 145049562 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:26.299,041] <inf> chip: [EM]>>> [E:7115r S:5800 M:196832937] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:02 (IM:ReadRequest)
b05> [5D[J[21:52:26.308,380] <inf> chip: [EM]<<< [E:7115r S:5800 M:117603833 (Ack:196832937)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:26.308,532] <inf> chip: [IN](S) Sending msg 117603833 on secure session with LSID: 5800
b05> [5D[J[21:52:26.310,455] <inf> chip: [EM]>>> [E:7114r S:0 M:164073988 (Ack:145049561)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.653,594] <inf> chip: [EM]>>> [E:7116r S:5800 M:196832938] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:26.654,266] <inf> chip: [FS]GeneralCommissioning: Received ArmFailSafe (60s)
b05> [5D[J[21:52:26.655,609] <inf> chip: [EM]<<< [E:7116r S:5800 M:117603834 (Ack:196832938)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:26.655,792] <inf> chip: [IN](S) Sending msg 117603834 on secure session with LSID: 5800
b05> [5D[J[21:52:26.667,724] <inf> chip: [EM]<<< [E:4665i S:5793 M:129165146] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:26.667,877] <inf> chip: [IN](S) Sending msg 129165146 on secure session with LSID: 5793
b05> [5D[J[21:52:26.668,670] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:26.677,368] <inf> chip: [EM]>>> [E:7115r S:5800 M:196832939 (Ack:117603833)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:26.867,340] <inf> chip: [EM]>>> [E:4665i S:5793 M:53712794 (Ack:129165146)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:26.867,614] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:26.868,530] <inf> chip: [EM]<<< [E:4665i S:5793 M:129165147 (Ack:53712794)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.868,682] <inf> chip: [IN](S) Sending msg 129165147 on secure session with LSID: 5793
b05> [5D[J[21:52:27.391,479] <inf> chip: [EM]>>> [E:7117r S:5800 M:196832940] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:27.395,477] <inf> chip: [EM]<<< [E:7117r S:5800 M:117603835 (Ack:196832940)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:27.395,629] <inf> chip: [IN](S) Sending msg 117603835 on secure session with LSID: 5800
b05> [5D[J[21:52:27.407,623] <inf> chip: [EM]<<< [E:4666i S:5793 M:129165148] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:27.407,775] <inf> chip: [IN](S) Sending msg 129165148 on secure session with LSID: 5793
b05> [5D[J[21:52:27.408,538] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:27.409,606] <inf> chip: [EM]>>> [E:7116r S:5800 M:196832941 (Ack:117603834)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:27.736,633] <inf> chip: [EM]>>> [E:4666i S:5793 M:53712795 (Ack:129165148)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:27.736,968] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:27.738,281] <inf> chip: [EM]<<< [E:4666i S:5793 M:129165149 (Ack:53712795)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:27.738,433] <inf> chip: [IN](S) Sending msg 129165149 on secure session with LSID: 5793
b05> [5D[J[21:52:27.984,954] <inf> chip: [EM]>>> [E:7118r S:5800 M:196832942] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:27.985,992] <inf> chip: [ZCL]OpCreds: Certificate Chain request received for PAI
b05> [5D[J[21:52:27.989,440] <inf> chip: [EM]<<< [E:7118r S:5800 M:117603836 (Ack:196832942)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:27.989,624] <inf> chip: [IN](S) Sending msg 117603836 on secure session with LSID: 5800
b05> [5D[J[21:52:27.996,002] <inf> chip: [EM]>>> [E:7117r S:5800 M:196832943 (Ack:117603835)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:28.501,770] <inf> chip: [EM]>>> [E:7119r S:5800 M:196832944] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:28.502,471] <inf> chip: [ZCL]OpCreds: Certificate Chain request received for DAC
b05> [5D[J[21:52:28.506,408] <inf> chip: [EM]<<< [E:7119r S:5800 M:117603837 (Ack:196832944)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:28.506,591] <inf> chip: [IN](S) Sending msg 117603837 on secure session with LSID: 5800
b05> [5D[J[21:52:28.514,312] <inf> chip: [EM]>>> [E:7118r S:5800 M:196832945 (Ack:117603836)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:29.247,192] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832946] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:29.248,107] <inf> chip: [ZCL]OpCreds: Received an AttestationRequest command
b05> [5D[J[21:52:29.249,053] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603838 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:29.249,572] <inf> chip: [IN](S) Sending msg 117603838 on secure session with LSID: 5800
b05> [5D[J[21:52:29.287,567] <inf> chip: [ZCL]OpCreds: AttestationRequest successful.
b05> [5D[J[21:52:29.291,748] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603839 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:29.291,900] <inf> chip: [IN](S) Sending msg 117603839 on secure session with LSID: 5800
b05> [5D[J[21:52:29.294,830] <inf> chip: [EM]>>> [E:7119r S:5800 M:196832947 (Ack:117603837)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:30.297,180] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832946] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:30.298,034] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603840 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:30.298,187] <inf> chip: [IN](S) Sending msg 117603840 on secure session with LSID: 5800
b05> [5D[J[21:52:30.324,768] <inf> chip: [IN](S) Sending msg 117603839 on secure session with LSID: 5800
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:31.038,330] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832948] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:31.039,062] <inf> chip: [ZCL]OpCreds: Received a CSRRequest command
b05> [5D[J[21:52:31.040,405] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603841 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:31.040,588] <inf> chip: [IN](S) Sending msg 117603841 on secure session with LSID: 5800
b05> [5D[J[21:52:31.213,623] <inf> chip: [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
b05> [5D[J[21:52:31.250,976] <inf> chip: [ZCL]OpCreds: CSRRequest successful.
b05> [5D[J[21:52:31.254,791] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603842 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:31.254,943] <inf> chip: [IN](S) Sending msg 117603842 on secure session with LSID: 5800
b05> [5D[J[21:52:31.256,774] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832949 (Ack:117603839)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:31.852,447] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832950 (Ack:117603839)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.107,696] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832948] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.108,825] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603843 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.108,978] <inf> chip: [IN](S) Sending msg 117603843 on secure session with LSID: 5800
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:32.170,013] <inf> chip: [EM]>>> [E:7122r S:5800 M:196832951] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.171,051] <inf> chip: [ZCL]OpCreds: Received an AddTrustedRootCertificate command
b05> [5D[J[21:52:32.171,905] <inf> chip: [EM]<<< [E:7122r S:5800 M:117603844 (Ack:196832951)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.172,088] <inf> chip: [IN](S) Sending msg 117603844 on secure session with LSID: 5800
b05> [5D[J[21:52:32.238,311] <inf> chip: [ZCL]OpCreds: AddTrustedRootCertificate successful.
b05> [5D[J[21:52:32.239,532] <inf> chip: [EM]<<< [E:7122r S:5800 M:117603845 (Ack:196832951)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:32.239,685] <inf> chip: [IN](S) Sending msg 117603845 on secure session with LSID: 5800
b05> [5D[J[21:52:32.242,248] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832952 (Ack:117603842)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.979,797] <inf> chip: [EM]>>> [E:7123r S:5800 M:196832953] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.980,621] <inf> chip: [ZCL]OpCreds: Received an AddNOC command
b05> [5D[J[21:52:32.981,842] <inf> chip: [EM]<<< [E:7123r S:5800 M:117603846 (Ack:196832953)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.982,025] <inf> chip: [IN](S) Sending msg 117603846 on secure session with LSID: 5800
b05> [5D[J[21:52:32.985,198] <err> chip: [ZCL]OpCreds: Failed AddNOC request (err=7e) with OperationalCert error 9
b05> [5D[J[21:52:32.986,419] <inf> chip: [EM]<<< [E:7123r S:5800 M:117603847 (Ack:196832953)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:32.986,572] <inf> chip: [IN](S) Sending msg 117603847 on secure session with LSID: 5800
b05> [5D[J[21:52:32.993,988] <inf> chip: [EM]>>> [E:7122r S:5800 M:196832954 (Ack:117603845)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:33.392,517] <inf> chip: [EM]>>> [E:7124r S:5800 M:196832955] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:33.393,249] <inf> chip: [FS]GeneralCommissioning: Received ArmFailSafe (0s)
b05> [5D[J[21:52:33.393,341] <inf> chip: [FS]Fail-safe timer expired
b05> [5D[J[21:52:33.395,050] <inf> chip: [EM]<<< [E:7124r S:5800 M:117603848 (Ack:196832955)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:33.395,202] <inf> chip: [IN](S) Sending msg 117603848 on secure session with LSID: 5800
b05> [5D[J[21:52:33.395,965] <err> chip: [SVR]Failsafe timer expired
b05> [5D[J[21:52:33.396,118] <inf> chip: [SC]SecureSession[0x200042c0]: Moving from state ‘kActive’ —> ‘kPendingEviction’
b05> [5D[J[21:52:33.396,362] <err> chip: [SVR]Commissioning failed (attempt 1): 32
b05> [5D[J[21:52:33.396,789] <inf> chip: [DIS]Updating services using commissioning mode 2
b05> [5D[J[21:52:33.397,003] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:33.397,521] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:33.398,071] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:33.400,360] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=0495/01 cm=2
b05> [5D[J[21:52:33.401,031] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:33.401,245] <err> chip: [ZCL]OpCreds: Got FailSafeTimerExpired
b05> [5D[J[21:52:33.401,336] <err> chip: [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
b05> [5D[J[21:52:33.401,428] <err> chip: [FP]Reverting pending fabric data for fabric 0x4
b05> [5D[J[21:52:33.401,855] <inf> chip: [TS]Pending Last Known Good Time: 2023-06-19T15:43:08
b05> [5D[J[21:52:33.403,503] <inf> chip: [TS]Previous Last Known Good Time: 2023-06-19T15:43:08
b05> [5D[J[21:52:33.403,564] <inf> chip: [TS]Reverted Last Known Good Time to previous value
b05> [5D[J[21:52:33.405,212] <inf> chip: [FS]Fail-safe cleanly disarmed
b05> [5D[J[21:52:33.414,093] <inf> chip: [EM]<<< [E:4667i S:5793 M:129165150] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:33.414,245] <inf> chip: [IN](S) Sending msg 129165150 on secure session with LSID: 5793
b05> [5D[J[21:52:33.415,039] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:33.560,729] <err> chip: [IN]Data received on an unknown session (LSID=5800). Dropping it!
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:33.635,437] <inf> chip: [EM]>>> [E:4667i S:5793 M:53712796 (Ack:129165150)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:33.635,681] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:33.636,596] <inf> chip: [EM]<<< [E:4667i S:5793 M:129165151 (Ack:53712796)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:33.636,749] <inf> chip: [IN](S) Sending msg 129165151 on secure session with LSID: 5793
b05> [5D[Jb05> [5D[Jb05>

Chip-tool logs:

./chip-tool pairing code 1234 03101038080
[1687268077697] [71452:11938902] [DL] KVS will be written to: file:///tmp/chip_kvs
[1687268077704] [71452:11938902] [DL] _Init
[1687268077704] [71452:11938902] [IN] UDP::Init bind&listen port=0
[1687268077705] [71452:11938902] [IN] UDP::Init bound to port=57735
[1687268077705] [71452:11938902] [IN] UDP::Init bind&listen port=0
[1687268077705] [71452:11938902] [IN] UDP::Init bound to port=50538
[1687268077705] [71452:11938902] [IN] BLEBase::Init - setting/overriding transport
[1687268077705] [71452:11938902] [IN] TransportMgr initialized
[1687268077705] [71452:11938902] [FP] Initializing FabricTable from persistent storage
[1687268077705] [71452:11938902] [TS] Last Known Good Time: 2023-06-16T15:24:10
[1687268077707] [71452:11938902] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x00666433E3012986, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
[1687268077707] [71452:11938902] [FP] Fabric index 0x2 was retrieved from storage. Compressed FabricId 0x09A0A133024A6F00, FabricId 0x0000000000000004, NodeId 0x000000000001B669, VendorId 0xFFF1
[1687268077709] [71452:11938902] [ZCL] Using ZAP configuration...
[1687268077709] [71452:11938902] [CTL] System State Initialized...
[1687268077709] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077709] [71452:11938902] [CTL] Setting attestation nonce to random value
[1687268077709] [71452:11938902] [CTL] Setting CSR nonce to random value
[1687268077709] [71452:11938902] [IN] UDP::Init bind&listen port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bound to port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bind&listen port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bound to port=5550
[1687268077710] [71452:11938902] [IN] TransportMgr initialized
[1687268077710] [71452:11938905] [DL] _OnPlatformEvent
[1687268077710] [71452:11938905] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077710] [71452:11938905] [CTL] Setting attestation nonce to random value
[1687268077710] [71452:11938905] [CTL] Setting CSR nonce to random value
[1687268077711] [71452:11938905] [CTL] Generating NOC
[1687268077712] [71452:11938905] [FP] Validating NOC chain
[1687268077716] [71452:11938905] [FP] NOC chain validation successful
[1687268077716] [71452:11938905] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669
[1687268077716] [71452:11938905] [TS] Last Known Good Time: 2023-06-16T15:24:10
[1687268077716] [71452:11938905] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00
[1687268077716] [71452:11938905] [TS] Retaining current Last Known Good Time
[1687268077717] [71452:11938905] [FP] Metadata for Fabric 0x1 persisted to storage.
[1687268077717] [71452:11938905] [TS] Committing Last Known Good Time to storage: 2023-06-16T15:24:10
[1687268077718] [71452:11938905] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 00666433E3012986)
[1687268077718] [71452:11938905] [IN] UDP::Init bind&listen port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bound to port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bind&listen port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bound to port=5550
[1687268077718] [71452:11938905] [IN] TransportMgr initialized
[1687268077724] [71452:11938905] [CTL] Setting attestation nonce to random value
[1687268077724] [71452:11938905] [CTL] Setting CSR nonce to random value
[1687268077724] [71452:11938905] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077724] [71452:11938905] [CTL] Starting commissioning discovery over DNS-SD
[1687268077724] [71452:11938905] [DIS] Browsing for: _matterc._udp,_S1
[1687268077725] [71452:11938906] [DIS] Mdns: OnBrowseAdd  name: 7F6BF7BD580EBC96, type: _matterc._udp., domain: local., interface: 12
[1687268077725] [71452:11938906] [DIS] Resolve type=_matterc._udp name=7F6BF7BD580EBC96 interface=12
[1687268077725] [71452:11938906] [DIS] Mdns: OnResolve flags: 0, interface: 12, fullname: 7F6BF7BD580EBC96._matterc._udp.local., hostname: 9A2D4D9F3E4D45B9.local., port: 42005
[1687268077725] [71452:11938906] [DIS] Mdns : OnNewInterface hostname:9A2D4D9F3E4D45B9.local. fullname:7F6BF7BD580EBC96._matterc._udp.local. interface: 12 port: 5540 TXT:"VP=65521+32773,SII=1800,SAI=1000,T=0,D=495,CM=2,PH=36,PI="
[1687268078189] [71452:11938906] [DIS] Mdns: OnGetAddrInfo flags: 2, interface: 12, hostname: 9A2D4D9F3E4D45B9.local.
[1687268078189] [71452:11938906] [DIS] Mdns: OnNewAddress interface: 12 ip:fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8
[1687268078189] [71452:11938906] [DIS] Mdns: Resolve success on interface 12
[1687268078189] [71452:11938906] [DIS] Discovered node:
[1687268078189] [71452:11938906] [DIS] 	Hostname: 9A2D4D9F3E4D45B9
[1687268078189] [71452:11938906] [DIS] 	IP Address #1: fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8
[1687268078189] [71452:11938906] [DIS] 	Port: 5540
[1687268078189] [71452:11938906] [DIS] 	Mrp Interval idle: 1800 ms
[1687268078189] [71452:11938906] [DIS] 	Mrp Interval active: 1000 ms
[1687268078189] [71452:11938906] [DIS] 	TCP Supported: 0
[1687268078189] [71452:11938906] [DIS] 	Vendor ID: 65521
[1687268078189] [71452:11938906] [DIS] 	Product ID: 32773
[1687268078189] [71452:11938906] [DIS] 	Long Discriminator: 495
[1687268078189] [71452:11938906] [DIS] 	Pairing Hint: 36
[1687268078189] [71452:11938906] [DIS] 	Instance Name: 7F6BF7BD580EBC96
[1687268078189] [71452:11938906] [DIS] 	Commissioning Mode: 2
[1687268078189] [71452:11938906] [CTL] Discovered device to be commissioned over DNS-SD
[1687268078189] [71452:11938906] [CTL] Attempting PASE connection to UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8]:5540
[1687268078190] [71452:11938906] [IN] SecureSession[0x60000339c000]: Allocated Type:1 LSID:63495
[1687268078191] [71452:11938906] [SC] Assigned local session key ID 63495
[1687268078191] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073984] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1687268078191] [71452:11938906] [IN] (U) Sending msg 164073984 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8]:5540'
[1687268078192] [71452:11938906] [SC] Sent PBKDF param request
[1687268078192] [71452:11938906] [CTL] Commission called for node ID 0x00000000000004D2
[1687268078833] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049556 (Ack:164073984)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268078833] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268078833] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073984 from Retrans Table on exchange 7114i
[1687268078833] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049557 (Ack:164073984)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1687268078833] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268078833] [71452:11938906] [EM] CHIP MessageCounter:164073984 not in RetransTable on exchange 7114i
[1687268078833] [71452:11938906] [SC] Received PBKDF param response
[1687268078833] [71452:11938906] [SC] Peer assigned session ID 5800
[1687268078833] [71452:11938906] [SC] Found MRP parameters in the message
[1687268078864] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073985 (Ack:145049557)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1687268078864] [71452:11938906] [IN] (U) Sending msg 164073985 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268078864] [71452:11938906] [SC] Sent spake2p msg1
[1687268079048] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049558 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268079048] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268079048] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073985 from Retrans Table on exchange 7114i
[1687268080804] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049559 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1687268080804] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268080804] [71452:11938906] [EM] CHIP MessageCounter:164073985 not in RetransTable on exchange 7114i
[1687268080804] [71452:11938906] [SC] Received spake2p msg2
[1687268080819] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073986 (Ack:145049559)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1687268080819] [71452:11938906] [IN] (U) Sending msg 164073986 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268080819] [71452:11938906] [SC] Sent spake2p msg3
[1687268081929] [71452:11938906] [IN] Received a duplicate message with MessageCounter:145049559 on exchange 7114i
[1687268081929] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049559 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1687268081929] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268081929] [71452:11938906] [EM] Forcing tx of solitary ack for duplicate MessageCounter:145049559 on exchange 7114i
[1687268081930] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073987 (Ack:145049559)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268081930] [71452:11938906] [IN] (U) Sending msg 164073987 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082079] [71452:11938906] [EM] Retransmitting MessageCounter:164073986 on exchange 7114i Send Cnt 1
[1687268082080] [71452:11938906] [IN] (U) Sending msg 164073986 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082194] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049560 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082194] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268082194] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073986 from Retrans Table on exchange 7114i
[1687268082194] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049561 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1687268082194] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268082194] [71452:11938906] [EM] CHIP MessageCounter:164073986 not in RetransTable on exchange 7114i
[1687268082194] [71452:11938906] [SC] SecureSession[0x60000339c000, LSID:63495]: State change 'kEstablishing' --> 'kActive'
[1687268082194] [71452:11938906] [IN] SecureSession[0x60000339c000]: Activated - Type:1 LSID:63495
[1687268082194] [71452:11938906] [IN] New secure session activated for device <FFFFFFFB00000000, 0>, LSID:63495 PSID:5800!
[1687268082194] [71452:11938906] [CTL] Remote device completed SPAKE2+ handshake
[1687268082194] [71452:11938906] [CTL] Stopping commissioning discovery over DNS-SD
[1687268082194] [71452:11938906] [DIS] Mdns: Browse failure (src/platform/Darwin/DnssdImpl.cpp:412: CHIP Error 0x00000074: The operation has been cancelled)
[1687268082194] [71452:11938906] [TOO] Pairing Success
[1687268082194] [71452:11938906] [TOO] PASE establishment successful
[1687268082194] [71452:11938906] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1687268082194] [71452:11938906] [CTL] Performing next commissioning step 'ReadCommissioningInfo'
[1687268082194] [71452:11938906] [CTL] Sending request for commissioning information
[1687268082195] [71452:11938906] [DMG] SendReadRequest ReadClient[0x12ee042d0]: Sending Read Request
[1687268082196] [71452:11938906] [EM] <<< [E:7115i S:63495 M:196832937] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
[1687268082196] [71452:11938906] [IN] (S) Sending msg 196832937 on secure session with LSID: 63495
[1687268082196] [71452:11938906] [DMG] MoveToState ReadClient[0x12ee042d0]: Moving to [AwaitingIn]
[1687268082196] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073988 (Ack:145049561)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082197] [71452:11938906] [IN] (U) Sending msg 164073988 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082197] [71452:11938906] [EM] Flushed pending ack for MessageCounter:145049561 on exchange 7114i
[1687268082612] [71452:11938905] [EM] >>> [E:7114i S:0 M:145049562 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082612] [71452:11938905] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268082612] [71452:11938905] [EM] >>> [E:7115i S:63495 M:117603833 (Ack:196832937)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1687268082613] [71452:11938905] [EM] Found matching exchange: 7115i, Delegate: 0x12ee042e0
[1687268082613] [71452:11938905] [EM] Rxd Ack; Removing MessageCounter:196832937 from Retrans Table on exchange 7115i
[1687268082613] [71452:11938905] [DMG] ReportDataMessage =
[1687268082613] [71452:11938905] [DMG] {
[1687268082613] [71452:11938905] [DMG] 	AttributeReportIBs =
[1687268082613] [71452:11938905] [DMG] 	[
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x36d0582e,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x31,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0003,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 20, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x2d06f9df,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x28,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0004,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 32773, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x2d06f9df,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x28,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0002,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 65521, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082614] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082614] [71452:11938905] [DMG] 					Attribute = 0x0000_0003,
[1687268082614] [71452:11938905] [DMG] 				}
[1687268082614] [71452:11938905] [DMG] 					
[1687268082614] [71452:11938905] [DMG] 				Data = 0, 
[1687268082614] [71452:11938905] [DMG] 			},
[1687268082614] [71452:11938905] [DMG] 			
[1687268082614] [71452:11938905] [DMG] 		},
[1687268082614] [71452:11938905] [DMG] 		
[1687268082614] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082614] [71452:11938905] [DMG] 		{
[1687268082614] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082614] [71452:11938905] [DMG] 			{
[1687268082614] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082614] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082614] [71452:11938905] [DMG] 				{
[1687268082614] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082614] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082614] [71452:11938905] [DMG] 					Attribute = 0x0000_0002,
[1687268082614] [71452:11938905] [DMG] 				}
[1687268082614] [71452:11938905] [DMG] 					
[1687268082614] [71452:11938905] [DMG] 				Data = 0, 
[1687268082614] [71452:11938905] [DMG] 			},
[1687268082614] [71452:11938905] [DMG] 			
[1687268082614] [71452:11938905] [DMG] 		},
[1687268082614] [71452:11938905] [DMG] 		
[1687268082614] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082614] [71452:11938905] [DMG] 		{
[1687268082614] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082615] [71452:11938905] [DMG] 			{
[1687268082615] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082615] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082615] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082615] [71452:11938905] [DMG] 					Attribute = 0x0000_0001,
[1687268082615] [71452:11938905] [DMG] 				}
[1687268082615] [71452:11938905] [DMG] 					
[1687268082615] [71452:11938905] [DMG] 				Data = 
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					0x0 = 60, 
[1687268082615] [71452:11938905] [DMG] 					0x1 = 900, 
[1687268082615] [71452:11938905] [DMG] 				},
[1687268082615] [71452:11938905] [DMG] 			},
[1687268082615] [71452:11938905] [DMG] 			
[1687268082615] [71452:11938905] [DMG] 		},
[1687268082615] [71452:11938905] [DMG] 		
[1687268082615] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082615] [71452:11938905] [DMG] 		{
[1687268082615] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082615] [71452:11938905] [DMG] 			{
[1687268082615] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082615] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082615] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082615] [71452:11938905] [DMG] 					Attribute = 0x0000_0000,
[1687268082615] [71452:11938905] [DMG] 				}
[1687268082615] [71452:11938905] [DMG] 					
[1687268082615] [71452:11938905] [DMG] 				Data = 0, 
[1687268082615] [71452:11938905] [DMG] 			},
[1687268082615] [71452:11938905] [DMG] 			
[1687268082615] [71452:11938905] [DMG] 		},
[1687268082615] [71452:11938905] [DMG] 		
[1687268082615] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082615] [71452:11938905] [DMG] 		{
[1687268082615] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082616] [71452:11938905] [DMG] 			{
[1687268082616] [71452:11938905] [DMG] 				DataVersion = 0x36d0582e,
[1687268082616] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082616] [71452:11938905] [DMG] 				{
[1687268082616] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082616] [71452:11938905] [DMG] 					Cluster = 0x31,
[1687268082616] [71452:11938905] [DMG] 					Attribute = 0x0000_FFFC,
[1687268082616] [71452:11938905] [DMG] 				}
[1687268082616] [71452:11938905] [DMG] 					
[1687268082616] [71452:11938905] [DMG] 				Data = 2, 
[1687268082616] [71452:11938905] [DMG] 			},
[1687268082616] [71452:11938905] [DMG] 			
[1687268082616] [71452:11938905] [DMG] 		},
[1687268082616] [71452:11938905] [DMG] 		
[1687268082616] [71452:11938905] [DMG] 	],
[1687268082616] [71452:11938905] [DMG] 	
[1687268082616] [71452:11938905] [DMG] 	SuppressResponse = true, 
[1687268082616] [71452:11938905] [DMG] 	InteractionModelRevision = 1
[1687268082616] [71452:11938905] [DMG] }
[1687268082618] [71452:11938905] [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0
[1687268082618] [71452:11938905] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo'
[1687268082619] [71452:11938905] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
[1687268082619] [71452:11938905] [CTL] Performing next commissioning step 'ArmFailSafe'
[1687268082619] [71452:11938905] [CTL] Arming failsafe (60 seconds)
[1687268082619] [71452:11938905] [DMG] ICR moving to [AddingComm]
[1687268082619] [71452:11938905] [DMG] ICR moving to [AddedComma]
[1687268082619] [71452:11938905] [EM] <<< [E:7116i S:63495 M:196832938] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268082619] [71452:11938905] [IN] (S) Sending msg 196832938 on secure session with LSID: 63495
[1687268082620] [71452:11938905] [DMG] ICR moving to [CommandSen]
[1687268082620] [71452:11938905] [EM] <<< [E:7115i S:63495 M:196832939 (Ack:117603833)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082620] [71452:11938905] [IN] (S) Sending msg 196832939 on secure session with LSID: 63495
[1687268082620] [71452:11938905] [EM] Flushed pending ack for MessageCounter:117603833 on exchange 7115i
[1687268083214] [71452:11938907] [EM] >>> [E:7116i S:63495 M:117603834 (Ack:196832938)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268083214] [71452:11938907] [EM] Found matching exchange: 7116i, Delegate: 0x12ef04088
[1687268083214] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832938 from Retrans Table on exchange 7116i
[1687268083214] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268083214] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268083214] [71452:11938907] [DMG] {
[1687268083214] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268083214] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268083214] [71452:11938907] [DMG] 	[
[1687268083214] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268083214] [71452:11938907] [DMG] 		{
[1687268083214] [71452:11938907] [DMG] 			CommandDataIB =
[1687268083214] [71452:11938907] [DMG] 			{
[1687268083214] [71452:11938907] [DMG] 				CommandPathIB =
[1687268083214] [71452:11938907] [DMG] 				{
[1687268083214] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268083214] [71452:11938907] [DMG] 					ClusterId = 0x30,
[1687268083214] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268083214] [71452:11938907] [DMG] 				},
[1687268083214] [71452:11938907] [DMG] 				
[1687268083214] [71452:11938907] [DMG] 				CommandFields = 
[1687268083214] [71452:11938907] [DMG] 				{
[1687268083214] [71452:11938907] [DMG] 					0x0 = 0, 
[1687268083214] [71452:11938907] [DMG] 					0x1 = "" (0 chars), 
[1687268083214] [71452:11938907] [DMG] 				},
[1687268083214] [71452:11938907] [DMG] 			},
[1687268083214] [71452:11938907] [DMG] 			
[1687268083214] [71452:11938907] [DMG] 		},
[1687268083214] [71452:11938907] [DMG] 		
[1687268083214] [71452:11938907] [DMG] 	],
[1687268083214] [71452:11938907] [DMG] 	
[1687268083214] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268083214] [71452:11938907] [DMG] },
[1687268083215] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1687268083218] [71452:11938907] [CTL] Received ArmFailSafe response errorCode=0
[1687268083218] [71452:11938907] [CTL] Successfully finished commissioning step 'ArmFailSafe'
[1687268083218] [71452:11938907] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1687268083218] [71452:11938907] [CTL] Performing next commissioning step 'ConfigRegulatory'
[1687268083218] [71452:11938907] [CTL] Setting Regulatory Config
[1687268083218] [71452:11938907] [CTL] Device does not support configurable regulatory location
[1687268083218] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268083218] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268083219] [71452:11938907] [EM] <<< [E:7117i S:63495 M:196832940] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268083219] [71452:11938907] [IN] (S) Sending msg 196832940 on secure session with LSID: 63495
[1687268083219] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268083219] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268083219] [71452:11938907] [EM] <<< [E:7116i S:63495 M:196832941 (Ack:117603834)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268083219] [71452:11938907] [IN] (S) Sending msg 196832941 on secure session with LSID: 63495
[1687268083219] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603834 on exchange 7116i
[1687268083828] [71452:11938906] [EM] >>> [E:7117i S:63495 M:117603835 (Ack:196832940)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268083828] [71452:11938906] [EM] Found matching exchange: 7117i, Delegate: 0x12ee041c8
[1687268083828] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832940 from Retrans Table on exchange 7117i
[1687268083828] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268083828] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268083828] [71452:11938906] [DMG] {
[1687268083828] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268083828] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268083828] [71452:11938906] [DMG] 	[
[1687268083828] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268083828] [71452:11938906] [DMG] 		{
[1687268083828] [71452:11938906] [DMG] 			CommandDataIB =
[1687268083828] [71452:11938906] [DMG] 			{
[1687268083828] [71452:11938906] [DMG] 				CommandPathIB =
[1687268083828] [71452:11938906] [DMG] 				{
[1687268083828] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268083828] [71452:11938906] [DMG] 					ClusterId = 0x30,
[1687268083828] [71452:11938906] [DMG] 					CommandId = 0x3,
[1687268083829] [71452:11938906] [DMG] 				},
[1687268083829] [71452:11938906] [DMG] 				
[1687268083829] [71452:11938906] [DMG] 				CommandFields = 
[1687268083829] [71452:11938906] [DMG] 				{
[1687268083829] [71452:11938906] [DMG] 					0x0 = 0, 
[1687268083829] [71452:11938906] [DMG] 					0x1 = "" (0 chars), 
[1687268083829] [71452:11938906] [DMG] 				},
[1687268083829] [71452:11938906] [DMG] 			},
[1687268083829] [71452:11938906] [DMG] 			
[1687268083829] [71452:11938906] [DMG] 		},
[1687268083829] [71452:11938906] [DMG] 		
[1687268083829] [71452:11938906] [DMG] 	],
[1687268083829] [71452:11938906] [DMG] 	
[1687268083829] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268083829] [71452:11938906] [DMG] },
[1687268083829] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1687268083829] [71452:11938906] [CTL] Received SetRegulatoryConfig response errorCode=0
[1687268083829] [71452:11938906] [CTL] Successfully finished commissioning step 'ConfigRegulatory'
[1687268083829] [71452:11938906] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
[1687268083829] [71452:11938906] [CTL] Performing next commissioning step 'SendPAICertificateRequest'
[1687268083829] [71452:11938906] [CTL] Sending request for PAI certificate
[1687268083829] [71452:11938906] [CTL] Sending Certificate Chain request to 0x12f009200 device
[1687268083829] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268083831] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268083831] [71452:11938906] [EM] <<< [E:7118i S:63495 M:196832942] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268083831] [71452:11938906] [IN] (S) Sending msg 196832942 on secure session with LSID: 63495
[1687268083831] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268083831] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268083832] [71452:11938906] [EM] <<< [E:7117i S:63495 M:196832943 (Ack:117603835)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268083832] [71452:11938906] [IN] (S) Sending msg 196832943 on secure session with LSID: 63495
[1687268083832] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603835 on exchange 7117i
[1687268084315] [71452:11938907] [EM] >>> [E:7118i S:63495 M:117603836 (Ack:196832942)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268084315] [71452:11938907] [EM] Found matching exchange: 7118i, Delegate: 0x12ee04458
[1687268084315] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832942 from Retrans Table on exchange 7118i
[1687268084315] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268084315] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268084315] [71452:11938907] [DMG] {
[1687268084315] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268084315] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268084316] [71452:11938907] [DMG] 	[
[1687268084316] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268084316] [71452:11938907] [DMG] 		{
[1687268084316] [71452:11938907] [DMG] 			CommandDataIB =
[1687268084316] [71452:11938907] [DMG] 			{
[1687268084316] [71452:11938907] [DMG] 				CommandPathIB =
[1687268084316] [71452:11938907] [DMG] 				{
[1687268084316] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268084316] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268084316] [71452:11938907] [DMG] 					CommandId = 0x3,
[1687268084316] [71452:11938907] [DMG] 				},
[1687268084316] [71452:11938907] [DMG] 				
[1687268084316] [71452:11938907] [DMG] 				CommandFields = 
[1687268084316] [71452:11938907] [DMG] 				{
[1687268084316] [71452:11938907] [DMG] 					0x0 = [
[1687268084316] [71452:11938907] [DMG] 							0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64,
[1687268084316] [71452:11938907] [DMG] 					] (463 bytes)
[1687268084316] [71452:11938907] [DMG] 				},
[1687268084316] [71452:11938907] [DMG] 			},
[1687268084316] [71452:11938907] [DMG] 			
[1687268084316] [71452:11938907] [DMG] 		},
[1687268084316] [71452:11938907] [DMG] 		
[1687268084316] [71452:11938907] [DMG] 	],
[1687268084316] [71452:11938907] [DMG] 	
[1687268084316] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268084316] [71452:11938907] [DMG] },
[1687268084316] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1687268084316] [71452:11938907] [CTL] Received certificate chain from the device
[1687268084316] [71452:11938907] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest'
[1687268084316] [71452:11938907] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1687268084317] [71452:11938907] [CTL] Performing next commissioning step 'SendDACCertificateRequest'
[1687268084317] [71452:11938907] [CTL] Sending request for DAC certificate
[1687268084317] [71452:11938907] [CTL] Sending Certificate Chain request to 0x12f009200 device
[1687268084317] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268084317] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268084317] [71452:11938907] [EM] <<< [E:7119i S:63495 M:196832944] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268084317] [71452:11938907] [IN] (S) Sending msg 196832944 on secure session with LSID: 63495
[1687268084317] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268084317] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268084317] [71452:11938907] [EM] <<< [E:7118i S:63495 M:196832945 (Ack:117603836)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268084317] [71452:11938907] [IN] (S) Sending msg 196832945 on secure session with LSID: 63495
[1687268084318] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603836 on exchange 7118i
[1687268084832] [71452:11938906] [EM] >>> [E:7119i S:63495 M:117603837 (Ack:196832944)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268084832] [71452:11938906] [EM] Found matching exchange: 7119i, Delegate: 0x12ee041c8
[1687268084832] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832944 from Retrans Table on exchange 7119i
[1687268084832] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268084832] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268084832] [71452:11938906] [DMG] {
[1687268084832] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268084832] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268084832] [71452:11938906] [DMG] 	[
[1687268084832] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268084832] [71452:11938906] [DMG] 		{
[1687268084832] [71452:11938906] [DMG] 			CommandDataIB =
[1687268084832] [71452:11938906] [DMG] 			{
[1687268084832] [71452:11938906] [DMG] 				CommandPathIB =
[1687268084832] [71452:11938906] [DMG] 				{
[1687268084832] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268084832] [71452:11938906] [DMG] 					ClusterId = 0x3e,
[1687268084832] [71452:11938906] [DMG] 					CommandId = 0x3,
[1687268084832] [71452:11938906] [DMG] 				},
[1687268084832] [71452:11938906] [DMG] 				
[1687268084832] [71452:11938906] [DMG] 				CommandFields = 
[1687268084832] [71452:11938906] [DMG] 				{
[1687268084832] [71452:11938906] [DMG] 					0x0 = [
[1687268084832] [71452:11938906] [DMG] 							0x30, 0x82, 0x01, 0xe8, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x6f, 0xdc, 0xb6, 0xed, 0x06, 0xf3, 0x58, 0xf9, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x35, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x35, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x7e, 0xf2, 0xf1, 0x08, 0x2b, 0x2a, 0x56, 0x4a, 0x05, 0x44, 0xc0, 0xa6, 0x13, 0xd2, 0x1b, 0x15, 0x75, 0x00, 0x55, 0x2d, 0x03, 0x5f, 0x23, 0x02, 0x25, 0x2f, 0xce, 0xe8, 0x1d, 0x6e, 0xc6, 0x74, 0x17,
[1687268084832] [71452:11938906] [DMG] 					] (492 bytes)
[1687268084832] [71452:11938906] [DMG] 				},
[1687268084832] [71452:11938906] [DMG] 			},
[1687268084832] [71452:11938906] [DMG] 			
[1687268084832] [71452:11938906] [DMG] 		},
[1687268084832] [71452:11938906] [DMG] 		
[1687268084833] [71452:11938906] [DMG] 	],
[1687268084833] [71452:11938906] [DMG] 	
[1687268084833] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268084833] [71452:11938906] [DMG] },
[1687268084833] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1687268084833] [71452:11938906] [CTL] Received certificate chain from the device
[1687268084833] [71452:11938906] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest'
[1687268084833] [71452:11938906] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1687268084833] [71452:11938906] [CTL] Performing next commissioning step 'SendAttestationRequest'
[1687268084833] [71452:11938906] [CTL] Sending Attestation Request to the device.
[1687268084833] [71452:11938906] [CTL] Sending Attestation request to 0x12f009200 device
[1687268084833] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268084833] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268084833] [71452:11938906] [EM] <<< [E:7120i S:63495 M:196832946] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268084833] [71452:11938906] [IN] (S) Sending msg 196832946 on secure session with LSID: 63495
[1687268084834] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268084834] [71452:11938906] [CTL] Sent Attestation request, waiting for the Attestation Information
[1687268084834] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268084834] [71452:11938906] [EM] <<< [E:7119i S:63495 M:196832947 (Ack:117603837)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268084834] [71452:11938906] [IN] (S) Sending msg 196832947 on secure session with LSID: 63495
[1687268084834] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603837 on exchange 7119i
[1687268085961] [71452:11938906] [EM] Retransmitting MessageCounter:196832946 on exchange 7120i Send Cnt 1
[1687268085962] [71452:11938906] [IN] (S) Sending msg 196832946 on secure session with LSID: 63495
[1687268086131] [71452:11938907] [EM] >>> [E:7120i S:63495 M:117603838 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086131] [71452:11938907] [EM] Found matching exchange: 7120i, Delegate: 0x139104278
[1687268086131] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832946 from Retrans Table on exchange 7120i
[1687268086132] [71452:11938907] [EM] >>> [E:7120i S:63495 M:117603839 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268086132] [71452:11938907] [EM] Found matching exchange: 7120i, Delegate: 0x139104278
[1687268086132] [71452:11938907] [EM] CHIP MessageCounter:196832946 not in RetransTable on exchange 7120i
[1687268086132] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268086132] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268086132] [71452:11938907] [DMG] {
[1687268086132] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268086132] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268086132] [71452:11938907] [DMG] 	[
[1687268086132] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268086132] [71452:11938907] [DMG] 		{
[1687268086132] [71452:11938907] [DMG] 			CommandDataIB =
[1687268086132] [71452:11938907] [DMG] 			{
[1687268086132] [71452:11938907] [DMG] 				CommandPathIB =
[1687268086132] [71452:11938907] [DMG] 				{
[1687268086132] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268086132] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268086132] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268086132] [71452:11938907] [DMG] 				},
[1687268086132] [71452:11938907] [DMG] 				
[1687268086132] [71452:11938907] [DMG] 				CommandFields = 
[1687268086132] [71452:11938907] [DMG] 				{
[1687268086132] [71452:11938907] [DMG] 					0x0 = [
[1687268086132] [71452:11938907] [DMG] 							0x15, 0x31, 0x01, 0x1b, 0x02, 0x30, 0x82, 0x02, 0x17, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x08, 0x30, 0x82, 0x02, 0x04, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x70, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x61, 0x04, 0x82, 0x01, 0x5d, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80,
[1687268086132] [71452:11938907] [DMG] 					] (583 bytes)
[1687268086132] [71452:11938907] [DMG] 					0x1 = [
[1687268086132] [71452:11938907] [DMG] 							0xb8, 0xca, 0x92, 0xf6, 0xb1, 0xdd, 0x38, 0xec, 0x11, 0x55, 0x96, 0x53, 0xc3, 0x43, 0x50, 0xce, 0xa0, 0x9c, 0x61, 0xdb, 0x1a, 0xca, 0x6e, 0x80, 0xba, 0x86, 0xf6, 0xc6, 0x13, 0x15, 0x2b, 0x99, 0x03, 0xd4, 0x35, 0x45, 0xc4, 0x83, 0x95, 0xa1, 0xfc, 0x7f, 0xe6, 0x23, 0x55, 0x70, 0x95, 0xc5, 0xd2, 0x58, 0x27, 0xd2, 0xdb, 0xf1, 0xdd, 0x13, 0x48, 0xfd, 0xf3, 0x0b, 0xda, 0x13, 0x7d, 0x73, 
[1687268086133] [71452:11938907] [DMG] 					] (64 bytes)
[1687268086133] [71452:11938907] [DMG] 				},
[1687268086133] [71452:11938907] [DMG] 			},
[1687268086133] [71452:11938907] [DMG] 			
[1687268086133] [71452:11938907] [DMG] 		},
[1687268086133] [71452:11938907] [DMG] 		
[1687268086133] [71452:11938907] [DMG] 	],
[1687268086133] [71452:11938907] [DMG] 	
[1687268086133] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268086133] [71452:11938907] [DMG] },
[1687268086133] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1687268086133] [71452:11938907] [CTL] Received Attestation Information from the device
[1687268086133] [71452:11938907] [CTL] Successfully finished commissioning step 'SendAttestationRequest'
[1687268086133] [71452:11938907] [CTL] AutoCommissioner setting attestationElements buffer size 583/583
[1687268086133] [71452:11938907] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1687268086133] [71452:11938907] [CTL] Performing next commissioning step 'AttestationVerification'
[1687268086133] [71452:11938907] [CTL] Verifying attestation
[1687268086163] [71452:11938907] [CTL] Successfully validated 'Attestation Information' command received from the device.
[1687268086163] [71452:11938907] [CTL] Successfully finished commissioning step 'AttestationVerification'
[1687268086163] [71452:11938907] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1687268086163] [71452:11938907] [CTL] Performing next commissioning step 'SendOpCertSigningRequest'
[1687268086163] [71452:11938907] [CTL] Sending CSR request to 0x12f009200 device
[1687268086163] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268086163] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268086163] [71452:11938907] [EM] <<< [E:7121i S:63495 M:196832948] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268086163] [71452:11938907] [IN] (S) Sending msg 196832948 on secure session with LSID: 63495
[1687268086163] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268086163] [71452:11938907] [CTL] Sent CSR request, waiting for the CSR
[1687268086164] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268086164] [71452:11938907] [EM] <<< [E:7120i S:63495 M:196832949 (Ack:117603839)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086164] [71452:11938907] [IN] (S) Sending msg 196832949 on secure session with LSID: 63495
[1687268086164] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603839 on exchange 7120i
[1687268086566] [71452:11938906] [EM] >>> [E:7120i S:63495 M:117603840 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086566] [71452:11938906] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268086986] [71452:11938906] [IN] Received a duplicate message with MessageCounter:117603839 on exchange 7120i
[1687268086986] [71452:11938906] [EM] >>> [E:7120i S:63495 M:117603839 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268086987] [71452:11938906] [EM] Generating StandaloneAck via exchange: 7120i
[1687268086987] [71452:11938906] [EM] Forcing tx of solitary ack for duplicate MessageCounter:117603839 on exchange 7120i
[1687268086987] [71452:11938906] [EM] <<< [E:7120i S:63495 M:196832950 (Ack:117603839)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086987] [71452:11938906] [IN] (S) Sending msg 196832950 on secure session with LSID: 63495
[1687268087316] [71452:11938907] [EM] Retransmitting MessageCounter:196832948 on exchange 7121i Send Cnt 1
[1687268087316] [71452:11938907] [IN] (S) Sending msg 196832948 on secure session with LSID: 63495
[1687268087376] [71452:11938906] [EM] >>> [E:7121i S:63495 M:117603841 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268087376] [71452:11938906] [EM] Found matching exchange: 7121i, Delegate: 0x13ee5a958
[1687268087376] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832948 from Retrans Table on exchange 7121i
[1687268087779] [71452:11938906] [EM] >>> [E:7121i S:63495 M:117603842 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268087779] [71452:11938906] [EM] Found matching exchange: 7121i, Delegate: 0x13ee5a958
[1687268087779] [71452:11938906] [EM] CHIP MessageCounter:196832948 not in RetransTable on exchange 7121i
[1687268087779] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268087779] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268087779] [71452:11938906] [DMG] {
[1687268087779] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268087779] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268087779] [71452:11938906] [DMG] 	[
[1687268087779] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268087779] [71452:11938906] [DMG] 		{
[1687268087779] [71452:11938906] [DMG] 			CommandDataIB =
[1687268087779] [71452:11938906] [DMG] 			{
[1687268087779] [71452:11938906] [DMG] 				CommandPathIB =
[1687268087779] [71452:11938906] [DMG] 				{
[1687268087779] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268087779] [71452:11938906] [DMG] 					ClusterId = 0x3e,
[1687268087779] [71452:11938906] [DMG] 					CommandId = 0x5,
[1687268087779] [71452:11938906] [DMG] 				},
[1687268087779] [71452:11938906] [DMG] 				
[1687268087779] [71452:11938906] [DMG] 				CommandFields = 
[1687268087779] [71452:11938906] [DMG] 				{
[1687268087779] [71452:11938906] [DMG] 					0x0 = [
[1687268087779] [71452:11938906] [DMG] 							0x15, 0x30, 0x01, 0xcd, 0x30, 0x81, 0xca, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x90, 0x55, 0xb8, 0x1b, 0x82, 0xe7, 0xd4, 0x26, 0x5e, 0x07, 0xaa, 0x71, 0x83, 0xe3, 0x00, 0xcd, 0x2c, 0xf7, 0xbb, 0x21, 0x9c, 0x03, 0x71, 0x25, 0xce, 0x05, 0x66, 0x43, 0xe4, 0x6c, 0x13, 0x55, 0x0a, 0x26, 0x1b, 0x51, 0x10, 0x29, 0x54, 0xd7, 0xee, 0xab, 0x50, 0xc2, 0xa3, 0xfe, 0xb0, 0xfc, 0x21, 0xb1, 0x38, 0xd5, 0x59, 0x94, 0xc9, 0x69, 0xb8, 0x9b, 0x6d, 0x55, 0x43, 0x2e, 0x96, 0xee, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0xab, 0xbd, 0x6b, 0x72, 0xed, 0xdf, 0xd0, 0x7f, 0xd8, 0x99, 0xa4, 0xef, 0x05, 0x08, 0x51, 0xf4, 0xce, 0xd2, 0x87, 0x53, 0x04, 0xc7, 0x3f, 0x57, 0xe3, 0x64, 0xaf, 0xef, 0x5b, 0xa1, 0xa7, 0x26, 0x02, 0x20, 0x4f, 0xd3, 0x51, 0xc6, 0xbc, 0xf8, 0xc8, 0x6a, 0xfc, 0xd6, 0xa8, 0xef, 0x08, 0xab, 0x6c, 0x9a, 0x73, 0x47, 0xc8, 0x52, 0xf8, 0xdf, 0xc0, 0x77, 0x40, 0x8e, 0x74, 0xd2, 0xe4, 0xd6, 0x7e, 0x63, 0x30, 0x02, 0x20, 0x95, 0x34, 0x63, 0x96, 0x40, 0x47, 0xdc, 0xfd, 0xec, 0x8d, 0xd3, 0xd4, 0x7c, 0x0e, 0xa3, 0xef, 0xf4, 0xe2, 0x12, 0x8f, 0xaf, 0x9d, 0x5b, 0x2b, 0x44, 0xec, 0x48, 0x44, 0xd0, 0x70, 0xe0, 0x14, 0x18, 
[1687268087779] [71452:11938906] [DMG] 					] (245 bytes)
[1687268087779] [71452:11938906] [DMG] 					0x1 = [
[1687268087779] [71452:11938906] [DMG] 							0xeb, 0xde, 0xb2, 0xf6, 0x53, 0x5a, 0xe1, 0x1f, 0x7d, 0x72, 0xd2, 0x89, 0x28, 0x34, 0x45, 0x69, 0xb5, 0x85, 0xb9, 0x11, 0x21, 0x90, 0x4b, 0xf9, 0x18, 0xf9, 0x45, 0x0a, 0xe3, 0x8f, 0xe4, 0xd1, 0x50, 0x1f, 0x5c, 0x22, 0x8e, 0x6f, 0xc3, 0xed, 0xd2, 0xca, 0x49, 0xe8, 0x66, 0x1b, 0x00, 0xf6, 0xf0, 0x7f, 0xb4, 0x1f, 0x77, 0x0d, 0x2f, 0x29, 0x88, 0x53, 0x18, 0x55, 0xa7, 0x6c, 0xcc, 0x46, 
[1687268087779] [71452:11938906] [DMG] 					] (64 bytes)
[1687268087779] [71452:11938906] [DMG] 				},
[1687268087779] [71452:11938906] [DMG] 			},
[1687268087779] [71452:11938906] [DMG] 			
[1687268087779] [71452:11938906] [DMG] 		},
[1687268087779] [71452:11938906] [DMG] 		
[1687268087779] [71452:11938906] [DMG] 	],
[1687268087779] [71452:11938906] [DMG] 	
[1687268087779] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268087779] [71452:11938906] [DMG] },
[1687268087780] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1687268087780] [71452:11938906] [CTL] Received certificate signing request from the device
[1687268087780] [71452:11938906] [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest'
[1687268087780] [71452:11938906] [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1687268087780] [71452:11938906] [CTL] Performing next commissioning step 'ValidateCSR'
[1687268087790] [71452:11938906] [CTL] Successfully finished commissioning step 'ValidateCSR'
[1687268087790] [71452:11938906] [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1687268087790] [71452:11938906] [CTL] Performing next commissioning step 'GenerateNOCChain'
[1687268087790] [71452:11938906] [CTL] Getting certificate chain for the device from the issuer
[1687268087791] [71452:11938906] [CTL] Verifying Certificate Signing Request
[1687268087797] [71452:11938906] [CTL] Generating NOC
[1687268087799] [71452:11938906] [CTL] Providing certificate chain to the commissioner
[1687268087799] [71452:11938906] [CTL] Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
[1687268087799] [71452:11938906] [CTL] Successfully finished commissioning step 'GenerateNOCChain'
[1687268087799] [71452:11938906] [CTL] Performing next commissioning step 'SendTrustedRootCert'
[1687268087799] [71452:11938906] [CTL] Sending root certificate to the device
[1687268087799] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268087799] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268087799] [71452:11938906] [EM] <<< [E:7122i S:63495 M:196832951] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268087799] [71452:11938906] [IN] (S) Sending msg 196832951 on secure session with LSID: 63495
[1687268087800] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268087800] [71452:11938906] [CTL] Sent root certificate to the device
[1687268087800] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268087800] [71452:11938906] [EM] <<< [E:7121i S:63495 M:196832952 (Ack:117603842)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268087800] [71452:11938906] [IN] (S) Sending msg 196832952 on secure session with LSID: 63495
[1687268087800] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603842 on exchange 7121i
[1687268088421] [71452:11938907] [EM] >>> [E:7121i S:63495 M:117603843 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088421] [71452:11938907] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268088422] [71452:11938907] [EM] >>> [E:7122i S:63495 M:117603844 (Ack:196832951)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088422] [71452:11938907] [EM] Found matching exchange: 7122i, Delegate: 0x13ef05c68
[1687268088422] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832951 from Retrans Table on exchange 7122i
[1687268088726] [71452:11938907] [EM] >>> [E:7122i S:63495 M:117603845 (Ack:196832951)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268088726] [71452:11938907] [EM] Found matching exchange: 7122i, Delegate: 0x13ef05c68
[1687268088726] [71452:11938907] [EM] CHIP MessageCounter:196832951 not in RetransTable on exchange 7122i
[1687268088726] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268088726] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268088726] [71452:11938907] [DMG] {
[1687268088726] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268088726] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268088726] [71452:11938907] [DMG] 	[
[1687268088726] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268088726] [71452:11938907] [DMG] 		{
[1687268088726] [71452:11938907] [DMG] 			CommandStatusIB =
[1687268088726] [71452:11938907] [DMG] 			{
[1687268088726] [71452:11938907] [DMG] 				CommandPathIB =
[1687268088726] [71452:11938907] [DMG] 				{
[1687268088726] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268088726] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268088726] [71452:11938907] [DMG] 					CommandId = 0xb,
[1687268088726] [71452:11938907] [DMG] 				},
[1687268088726] [71452:11938907] [DMG] 				
[1687268088726] [71452:11938907] [DMG] 				StatusIB =
[1687268088726] [71452:11938907] [DMG] 				{
[1687268088727] [71452:11938907] [DMG] 					status = 0x00 (SUCCESS),
[1687268088727] [71452:11938907] [DMG] 				},
[1687268088727] [71452:11938907] [DMG] 				
[1687268088727] [71452:11938907] [DMG] 			},
[1687268088727] [71452:11938907] [DMG] 			
[1687268088727] [71452:11938907] [DMG] 		},
[1687268088727] [71452:11938907] [DMG] 		
[1687268088727] [71452:11938907] [DMG] 	],
[1687268088727] [71452:11938907] [DMG] 	
[1687268088727] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268088727] [71452:11938907] [DMG] },
[1687268088727] [71452:11938907] [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1687268088727] [71452:11938907] [CTL] Device confirmed that it has received the root certificate
[1687268088727] [71452:11938907] [CTL] Successfully finished commissioning step 'SendTrustedRootCert'
[1687268088727] [71452:11938907] [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1687268088727] [71452:11938907] [CTL] Performing next commissioning step 'SendNOC'
[1687268088727] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268088727] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268088728] [71452:11938907] [EM] <<< [E:7123i S:63495 M:196832953] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268088728] [71452:11938907] [IN] (S) Sending msg 196832953 on secure session with LSID: 63495
[1687268088729] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268088729] [71452:11938907] [CTL] Sent operational certificate to the device
[1687268088729] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268088729] [71452:11938907] [EM] <<< [E:7122i S:63495 M:196832954 (Ack:117603845)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088729] [71452:11938907] [IN] (S) Sending msg 196832954 on secure session with LSID: 63495
[1687268088729] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603845 on exchange 7122i
[1687268089366] [71452:11938907] [EM] >>> [E:7123i S:63495 M:117603846 (Ack:196832953)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268089366] [71452:11938907] [EM] Found matching exchange: 7123i, Delegate: 0x12ee041c8
[1687268089366] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832953 from Retrans Table on exchange 7123i
[1687268089366] [71452:11938907] [EM] >>> [E:7123i S:63495 M:117603847 (Ack:196832953)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268089366] [71452:11938907] [EM] Found matching exchange: 7123i, Delegate: 0x12ee041c8
[1687268089366] [71452:11938907] [EM] CHIP MessageCounter:196832953 not in RetransTable on exchange 7123i
[1687268089367] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268089367] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268089367] [71452:11938907] [DMG] {
[1687268089367] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268089367] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268089367] [71452:11938907] [DMG] 	[
[1687268089367] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268089367] [71452:11938907] [DMG] 		{
[1687268089367] [71452:11938907] [DMG] 			CommandDataIB =
[1687268089367] [71452:11938907] [DMG] 			{
[1687268089367] [71452:11938907] [DMG] 				CommandPathIB =
[1687268089367] [71452:11938907] [DMG] 				{
[1687268089367] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268089367] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268089367] [71452:11938907] [DMG] 					CommandId = 0x8,
[1687268089367] [71452:11938907] [DMG] 				},
[1687268089367] [71452:11938907] [DMG] 				
[1687268089367] [71452:11938907] [DMG] 				CommandFields = 
[1687268089367] [71452:11938907] [DMG] 				{
[1687268089367] [71452:11938907] [DMG] 					0x0 = 9, 
[1687268089367] [71452:11938907] [DMG] 				},
[1687268089367] [71452:11938907] [DMG] 			},
[1687268089367] [71452:11938907] [DMG] 			
[1687268089367] [71452:11938907] [DMG] 		},
[1687268089367] [71452:11938907] [DMG] 		
[1687268089367] [71452:11938907] [DMG] 	],
[1687268089367] [71452:11938907] [DMG] 	
[1687268089367] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268089367] [71452:11938907] [DMG] },
[1687268089367] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1687268089367] [71452:11938907] [CTL] Device returned status 9 on receiving the NOC
[1687268089367] [71452:11938907] [CTL] Add NOC failed with error src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists
[1687268089367] [71452:11938907] [CTL] Error on commissioning step 'SendNOC': 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists'
[1687268089367] [71452:11938907] [CTL] Failed to perform commissioning step 13
[1687268089367] [71452:11938907] [CTL] Going from commissioning step 'SendNOC' with lastErr = 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists' -> 'Cleanup'
[1687268089367] [71452:11938907] [CTL] Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists'
[1687268089367] [71452:11938907] [CTL] Expiring failsafe on proxy 0x12f009200
[1687268089367] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268089367] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268089367] [71452:11938907] [EM] <<< [E:7124i S:63495 M:196832955] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268089367] [71452:11938907] [IN] (S) Sending msg 196832955 on secure session with LSID: 63495
[1687268089368] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268089368] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268089368] [71452:11938907] [EM] <<< [E:7123i S:63495 M:196832956 (Ack:117603847)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268089368] [71452:11938907] [IN] (S) Sending msg 196832956 on secure session with LSID: 63495
[1687268089368] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603847 on exchange 7123i
[1687268089744] [71452:11938907] [EM] >>> [E:7124i S:63495 M:117603848 (Ack:196832955)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268089744] [71452:11938907] [EM] Found matching exchange: 7124i, Delegate: 0x139004088
[1687268089744] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832955 from Retrans Table on exchange 7124i
[1687268089745] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268089745] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268089745] [71452:11938907] [DMG] {
[1687268089745] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268089745] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268089745] [71452:11938907] [DMG] 	[
[1687268089745] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268089745] [71452:11938907] [DMG] 		{
[1687268089745] [71452:11938907] [DMG] 			CommandDataIB =
[1687268089745] [71452:11938907] [DMG] 			{
[1687268089745] [71452:11938907] [DMG] 				CommandPathIB =
[1687268089745] [71452:11938907] [DMG] 				{
[1687268089745] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268089745] [71452:11938907] [DMG] 					ClusterId = 0x30,
[1687268089745] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268089745] [71452:11938907] [DMG] 				},
[1687268089745] [71452:11938907] [DMG] 				
[1687268089745] [71452:11938907] [DMG] 				CommandFields = 
[1687268089745] [71452:11938907] [DMG] 				{
[1687268089745] [71452:11938907] [DMG] 					0x0 = 0, 
[1687268089745] [71452:11938907] [DMG] 					0x1 = "" (0 chars), 
[1687268089745] [71452:11938907] [DMG] 				},
[1687268089745] [71452:11938907] [DMG] 			},
[1687268089745] [71452:11938907] [DMG] 			
[1687268089745] [71452:11938907] [DMG] 		},
[1687268089745] [71452:11938907] [DMG] 		
[1687268089745] [71452:11938907] [DMG] 	],
[1687268089745] [71452:11938907] [DMG] 	
[1687268089745] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268089745] [71452:11938907] [DMG] },
[1687268089745] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1687268089745] [71452:11938907] [CTL] Failsafe disarmed
[1687268089745] [71452:11938907] [CTL] Successfully finished commissioning step 'Cleanup'
[1687268089745] [71452:11938907] [TOO] Device commissioning Failure: src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists
[1687268089745] [71452:11938907] [IN] SecureSession[0x60000339c000]: MarkForEviction Type:1 LSID:63495
[1687268089745] [71452:11938907] [SC] SecureSession[0x60000339c000, LSID:63495]: State change 'kActive' --> 'kPendingEviction'
[1687268089745] [71452:11938907] [EM] Failed to send Solitary ack for MessageCounter:117603848 on exchange 7124i:src/messaging/ExchangeContext.cpp:144: CHIP Error 0x00000002: Connection aborted
[1687268089745] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268089745] [71452:11938907] [IN] SecureSession[0x60000339c000]: Released - Type:1 LSID:63495
[1687268089745] [71452:11938902] [CTL] Shutting down the commissioner
[1687268089745] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268089745] [71452:11938902] [CTL] Shutting down the controller
[1687268089745] [71452:11938902] [IN] Expiring all sessions for fabric 0x1!!
[1687268089745] [71452:11938902] [FP] Forgetting fabric 0x1
[1687268089745] [71452:11938902] [TS] Pending Last Known Good Time: 2023-06-16T15:24:10
[1687268089746] [71452:11938902] [TS] Previous Last Known Good Time: 2023-06-16T15:24:10
[1687268089746] [71452:11938902] [TS] Reverted Last Known Good Time to previous value
[1687268089746] [71452:11938902] [CTL] Shutting down the commissioner
[1687268089746] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268089746] [71452:11938902] [CTL] Shutting down the controller
[1687268089746] [71452:11938902] [CTL] Shutting down the System State, this will teardown the CHIP Stack
[1687268089747] [71452:11938902] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1687268089747] [71452:11938902] [BLE] CancelConnection
[1687268089747] [71452:11938902] [FP] Shutting down FabricTable
[1687268089747] [71452:11938902] [TS] Pending Last Known Good Time: 2023-06-16T15:24:10
[1687268089747] [71452:11938902] [TS] Previous Last Known Good Time: 2023-06-16T15:24:10
[1687268089747] [71452:11938902] [TS] Reverted Last Known Good Time to previous value
[1687268089747] [71452:11938902] [DL] Inet Layer shutdown
[1687268089747] [71452:11938902] [DL] BLE shutdown
[1687268089747] [71452:11938902] [DL] System Layer shutdown
[1687268089747] [71452:11938902] [TOO] Run command failure: src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists

Reply

0 Hugo.Dev over 2 years ago

Attached logs from both sides.

Device logs:

b05> [5D[J[21:52:03.437,927] <inf> chip: [EM]>>> [E:61084r S:5793 M:53712788] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:02 (IM:ReadRequest)
b05> [5D[J[21:52:03.438,873] <inf> chip: [DL]OpenThread SED interval is 200ms
b05> [5D[J[21:52:03.440,124] <inf> chip: [EM]<<< [E:61084r S:5793 M:129165141 (Ack:53712788)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:03.440,277] <inf> chip: [IN](S) Sending msg 129165141 on secure session with LSID: 5793
b05> [5D[J[21:52:03.519,256] <inf> chip: [EM]>>> [E:61084r S:5793 M:53712789 (Ack:129165141)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:03.519,439] <inf> chip: [DL]OpenThread SED interval is 1000ms
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:04.762,359] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712790] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:0a (IM:TimedRequest)
b05> [5D[J[21:52:04.763,519] <inf> chip: [EM]<<< [E:61085r S:5793 M:129165142 (Ack:53712790)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:04.763,671] <inf> chip: [IN](S) Sending msg 129165142 on secure session with LSID: 5793
b05> [5D[J[21:52:04.764,984] <inf> chip: [DL]OpenThread SED interval is 200ms
b05> [5D[J[21:52:05.338,958] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712791 (Ack:129165142)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:05.339,721] <inf> chip: [ZCL]Received command to open commissioning window
b05> [5D[J[21:52:05.340,332] <inf> chip: [DIS]Updating services using commissioning mode 2
b05> [5D[J[21:52:05.340,545] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:05.341,094] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:05.341,613] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:05.343,688] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=0495/01 cm=2
b05> [5D[J[21:52:05.344,238] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:05.344,482] <inf> chip: [DL]removing srp service: 2D6B524ECA0C2C2A._matterc._udp
b05> [5D[J[21:52:05.344,696] <inf> chip: [ZCL]Commissioning window is now open
b05> [5D[J[21:52:05.345,977] <inf> chip: [EM]<<< [E:61085r S:5793 M:129165143 (Ack:53712791)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:05.346,130] <inf> chip: [IN](S) Sending msg 129165143 on secure session with LSID: 5793
b05> [5D[J[21:52:05.358,367] <inf> chip: [EM]<<< [E:4664i S:5793 M:129165144] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:05.358,581] <inf> chip: [IN](S) Sending msg 129165144 on secure session with LSID: 5793
b05> [5D[J[21:52:05.360,534] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:05.385,498] <inf> chip: [EM]>>> [E:61085r S:5793 M:53712792 (Ack:129165143)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:05.627,868] <inf> chip: [EM]>>> [E:4664i S:5793 M:53712793 (Ack:129165144)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:05.628,143] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:05.629,547] <inf> chip: [EM]<<< [E:4664i S:5793 M:129165145 (Ack:53712793)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:05.629,699] <inf> chip: [IN](S) Sending msg 129165145 on secure session with LSID: 5793
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:22.591,644] <inf> chip: [EM]>>> [E:7114r S:0 M:164073984] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:20 (SecureChannel:PBKDFParamRequest)
b05> [5D[J[21:52:22.592,132] <inf> chip: [EM]<<< [E:7114r S:0 M:145049556 (Ack:164073984)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:22.592,376] <inf> chip: [IN](U) Sending msg 145049556 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:22.594,818] <inf> chip: [EM]<<< [E:7114r S:0 M:145049557 (Ack:164073984)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:21 (SecureChannel:PBKDFParamResponse)
b05> [5D[J[21:52:22.595,062] <inf> chip: [IN](U) Sending msg 145049557 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:22.810,028] <inf> chip: [EM]>>> [E:7114r S:0 M:164073985 (Ack:145049557)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:22 (SecureChannel:PASE_Pake1)
b05> [5D[J[21:52:22.810,546] <inf> chip: [EM]<<< [E:7114r S:0 M:145049558 (Ack:164073985)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:22.810,791] <inf> chip: [IN](U) Sending msg 145049558 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:24.485,229] <inf> chip: [EM]<<< [E:7114r S:0 M:145049559 (Ack:164073985)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:23 (SecureChannel:PASE_Pake2)
b05> [5D[J[21:52:24.485,473] <inf> chip: [IN](U) Sending msg 145049559 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:25.537,384] <inf> chip: [IN](U) Sending msg 145049559 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.803,375] <inf> chip: [EM]>>> [E:7114r S:0 M:164073986 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:24 (SecureChannel:PASE_Pake3)
b05> [5D[J[21:52:25.803,894] <inf> chip: [EM]<<< [E:7114r S:0 M:145049560 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:25.804,138] <inf> chip: [IN](U) Sending msg 145049560 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.806,091] <inf> chip: [EM]<<< [E:7114r S:0 M:145049561 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:40 (SecureChannel:StatusReport)
b05> [5D[J[21:52:25.806,549] <inf> chip: [IN](U) Sending msg 145049561 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:25.808,166] <inf> chip: [SC]SecureSession[0x200042c0]: Moving from state ‘kEstablishing’ —> ‘kActive’
b05> [5D[J[21:52:25.808,258] <inf> chip: [SVR]Commissioning completed session establishment step
b05> [5D[J[21:52:25.808,776] <inf> chip: [DIS]Updating services using commissioning mode 0
b05> [5D[J[21:52:25.809,051] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:25.810,729] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:25.811,645] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:25.813,751] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
b05> [5D[J[21:52:25.814,422] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:25.814,666] <inf> chip: [SVR]Device completed Rendezvous process
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:26.198,486] <inf> chip: [EM]>>> [E:7114r S:0 M:164073987 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.222,167] <inf> chip: [EM]>>> [E:7114r S:0 M:164073986 (Ack:145049559)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:24 (SecureChannel:PASE_Pake3)
b05> [5D[J[21:52:26.222,595] <inf> chip: [EM]<<< [E:7114r S:0 M:145049562 (Ack:164073986)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.222,839] <inf> chip: [IN](U) Sending msg 145049562 to IP address ‘UDP:[fde0:5808:b16a:4517:e8:c198:5af7:8421]:57735’
b05> [5D[J[21:52:26.299,041] <inf> chip: [EM]>>> [E:7115r S:5800 M:196832937] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:02 (IM:ReadRequest)
b05> [5D[J[21:52:26.308,380] <inf> chip: [EM]<<< [E:7115r S:5800 M:117603833 (Ack:196832937)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:26.308,532] <inf> chip: [IN](S) Sending msg 117603833 on secure session with LSID: 5800
b05> [5D[J[21:52:26.310,455] <inf> chip: [EM]>>> [E:7114r S:0 M:164073988 (Ack:145049561)] (U) Msg RX from 0:C59EC11094475D93 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.653,594] <inf> chip: [EM]>>> [E:7116r S:5800 M:196832938] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:26.654,266] <inf> chip: [FS]GeneralCommissioning: Received ArmFailSafe (60s)
b05> [5D[J[21:52:26.655,609] <inf> chip: [EM]<<< [E:7116r S:5800 M:117603834 (Ack:196832938)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:26.655,792] <inf> chip: [IN](S) Sending msg 117603834 on secure session with LSID: 5800
b05> [5D[J[21:52:26.667,724] <inf> chip: [EM]<<< [E:4665i S:5793 M:129165146] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:26.667,877] <inf> chip: [IN](S) Sending msg 129165146 on secure session with LSID: 5793
b05> [5D[J[21:52:26.668,670] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:26.677,368] <inf> chip: [EM]>>> [E:7115r S:5800 M:196832939 (Ack:117603833)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:26.867,340] <inf> chip: [EM]>>> [E:4665i S:5793 M:53712794 (Ack:129165146)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:26.867,614] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:26.868,530] <inf> chip: [EM]<<< [E:4665i S:5793 M:129165147 (Ack:53712794)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:26.868,682] <inf> chip: [IN](S) Sending msg 129165147 on secure session with LSID: 5793
b05> [5D[J[21:52:27.391,479] <inf> chip: [EM]>>> [E:7117r S:5800 M:196832940] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:27.395,477] <inf> chip: [EM]<<< [E:7117r S:5800 M:117603835 (Ack:196832940)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:27.395,629] <inf> chip: [IN](S) Sending msg 117603835 on secure session with LSID: 5800
b05> [5D[J[21:52:27.407,623] <inf> chip: [EM]<<< [E:4666i S:5793 M:129165148] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:27.407,775] <inf> chip: [IN](S) Sending msg 129165148 on secure session with LSID: 5793
b05> [5D[J[21:52:27.408,538] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:27.409,606] <inf> chip: [EM]>>> [E:7116r S:5800 M:196832941 (Ack:117603834)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:27.736,633] <inf> chip: [EM]>>> [E:4666i S:5793 M:53712795 (Ack:129165148)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:27.736,968] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:27.738,281] <inf> chip: [EM]<<< [E:4666i S:5793 M:129165149 (Ack:53712795)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:27.738,433] <inf> chip: [IN](S) Sending msg 129165149 on secure session with LSID: 5793
b05> [5D[J[21:52:27.984,954] <inf> chip: [EM]>>> [E:7118r S:5800 M:196832942] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:27.985,992] <inf> chip: [ZCL]OpCreds: Certificate Chain request received for PAI
b05> [5D[J[21:52:27.989,440] <inf> chip: [EM]<<< [E:7118r S:5800 M:117603836 (Ack:196832942)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:27.989,624] <inf> chip: [IN](S) Sending msg 117603836 on secure session with LSID: 5800
b05> [5D[J[21:52:27.996,002] <inf> chip: [EM]>>> [E:7117r S:5800 M:196832943 (Ack:117603835)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:28.501,770] <inf> chip: [EM]>>> [E:7119r S:5800 M:196832944] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:28.502,471] <inf> chip: [ZCL]OpCreds: Certificate Chain request received for DAC
b05> [5D[J[21:52:28.506,408] <inf> chip: [EM]<<< [E:7119r S:5800 M:117603837 (Ack:196832944)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:28.506,591] <inf> chip: [IN](S) Sending msg 117603837 on secure session with LSID: 5800
b05> [5D[J[21:52:28.514,312] <inf> chip: [EM]>>> [E:7118r S:5800 M:196832945 (Ack:117603836)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:29.247,192] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832946] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:29.248,107] <inf> chip: [ZCL]OpCreds: Received an AttestationRequest command
b05> [5D[J[21:52:29.249,053] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603838 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:29.249,572] <inf> chip: [IN](S) Sending msg 117603838 on secure session with LSID: 5800
b05> [5D[J[21:52:29.287,567] <inf> chip: [ZCL]OpCreds: AttestationRequest successful.
b05> [5D[J[21:52:29.291,748] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603839 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:29.291,900] <inf> chip: [IN](S) Sending msg 117603839 on secure session with LSID: 5800
b05> [5D[J[21:52:29.294,830] <inf> chip: [EM]>>> [E:7119r S:5800 M:196832947 (Ack:117603837)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:30.297,180] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832946] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:30.298,034] <inf> chip: [EM]<<< [E:7120r S:5800 M:117603840 (Ack:196832946)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:30.298,187] <inf> chip: [IN](S) Sending msg 117603840 on secure session with LSID: 5800
b05> [5D[J[21:52:30.324,768] <inf> chip: [IN](S) Sending msg 117603839 on secure session with LSID: 5800
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:31.038,330] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832948] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:31.039,062] <inf> chip: [ZCL]OpCreds: Received a CSRRequest command
b05> [5D[J[21:52:31.040,405] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603841 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:31.040,588] <inf> chip: [IN](S) Sending msg 117603841 on secure session with LSID: 5800
b05> [5D[J[21:52:31.213,623] <inf> chip: [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
b05> [5D[J[21:52:31.250,976] <inf> chip: [ZCL]OpCreds: CSRRequest successful.
b05> [5D[J[21:52:31.254,791] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603842 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:31.254,943] <inf> chip: [IN](S) Sending msg 117603842 on secure session with LSID: 5800
b05> [5D[J[21:52:31.256,774] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832949 (Ack:117603839)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:31.852,447] <inf> chip: [EM]>>> [E:7120r S:5800 M:196832950 (Ack:117603839)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.107,696] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832948] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.108,825] <inf> chip: [EM]<<< [E:7121r S:5800 M:117603843 (Ack:196832948)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.108,978] <inf> chip: [IN](S) Sending msg 117603843 on secure session with LSID: 5800
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:32.170,013] <inf> chip: [EM]>>> [E:7122r S:5800 M:196832951] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.171,051] <inf> chip: [ZCL]OpCreds: Received an AddTrustedRootCertificate command
b05> [5D[J[21:52:32.171,905] <inf> chip: [EM]<<< [E:7122r S:5800 M:117603844 (Ack:196832951)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.172,088] <inf> chip: [IN](S) Sending msg 117603844 on secure session with LSID: 5800
b05> [5D[J[21:52:32.238,311] <inf> chip: [ZCL]OpCreds: AddTrustedRootCertificate successful.
b05> [5D[J[21:52:32.239,532] <inf> chip: [EM]<<< [E:7122r S:5800 M:117603845 (Ack:196832951)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:32.239,685] <inf> chip: [IN](S) Sending msg 117603845 on secure session with LSID: 5800
b05> [5D[J[21:52:32.242,248] <inf> chip: [EM]>>> [E:7121r S:5800 M:196832952 (Ack:117603842)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.979,797] <inf> chip: [EM]>>> [E:7123r S:5800 M:196832953] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:32.980,621] <inf> chip: [ZCL]OpCreds: Received an AddNOC command
b05> [5D[J[21:52:32.981,842] <inf> chip: [EM]<<< [E:7123r S:5800 M:117603846 (Ack:196832953)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:32.982,025] <inf> chip: [IN](S) Sending msg 117603846 on secure session with LSID: 5800
b05> [5D[J[21:52:32.985,198] <err> chip: [ZCL]OpCreds: Failed AddNOC request (err=7e) with OperationalCert error 9
b05> [5D[J[21:52:32.986,419] <inf> chip: [EM]<<< [E:7123r S:5800 M:117603847 (Ack:196832953)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:32.986,572] <inf> chip: [IN](S) Sending msg 117603847 on secure session with LSID: 5800
b05> [5D[J[21:52:32.993,988] <inf> chip: [EM]>>> [E:7122r S:5800 M:196832954 (Ack:117603845)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:33.392,517] <inf> chip: [EM]>>> [E:7124r S:5800 M:196832955] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest)
b05> [5D[J[21:52:33.393,249] <inf> chip: [FS]GeneralCommissioning: Received ArmFailSafe (0s)
b05> [5D[J[21:52:33.393,341] <inf> chip: [FS]Fail-safe timer expired
b05> [5D[J[21:52:33.395,050] <inf> chip: [EM]<<< [E:7124r S:5800 M:117603848 (Ack:196832955)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse)
b05> [5D[J[21:52:33.395,202] <inf> chip: [IN](S) Sending msg 117603848 on secure session with LSID: 5800
b05> [5D[J[21:52:33.395,965] <err> chip: [SVR]Failsafe timer expired
b05> [5D[J[21:52:33.396,118] <inf> chip: [SC]SecureSession[0x200042c0]: Moving from state ‘kActive’ —> ‘kPendingEviction’
b05> [5D[J[21:52:33.396,362] <err> chip: [SVR]Commissioning failed (attempt 1): 32
b05> [5D[J[21:52:33.396,789] <inf> chip: [DIS]Updating services using commissioning mode 2
b05> [5D[J[21:52:33.397,003] <inf> chip: [DIS]Advertise operational node 8BDF6D46F41EEC5B-00000000E9F27E32
b05> [5D[J[21:52:33.397,521] <inf> chip: [DIS]Advertise operational node 10FDFC35AD8D6D67-00000000C357B3F7
b05> [5D[J[21:52:33.398,071] <inf> chip: [DIS]Advertise operational node 00666433E3012986-00000000000004D2
b05> [5D[J[21:52:33.400,360] <inf> chip: [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=0495/01 cm=2
b05> [5D[J[21:52:33.401,031] <inf> chip: [DL]advertising srp service: 7F6BF7BD580EBC96._matterc._udp
b05> [5D[J[21:52:33.401,245] <err> chip: [ZCL]OpCreds: Got FailSafeTimerExpired
b05> [5D[J[21:52:33.401,336] <err> chip: [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
b05> [5D[J[21:52:33.401,428] <err> chip: [FP]Reverting pending fabric data for fabric 0x4
b05> [5D[J[21:52:33.401,855] <inf> chip: [TS]Pending Last Known Good Time: 2023-06-19T15:43:08
b05> [5D[J[21:52:33.403,503] <inf> chip: [TS]Previous Last Known Good Time: 2023-06-19T15:43:08
b05> [5D[J[21:52:33.403,564] <inf> chip: [TS]Reverted Last Known Good Time to previous value
b05> [5D[J[21:52:33.405,212] <inf> chip: [FS]Fail-safe cleanly disarmed
b05> [5D[J[21:52:33.414,093] <inf> chip: [EM]<<< [E:4667i S:5793 M:129165150] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0001:05 (IM:ReportData)
b05> [5D[J[21:52:33.414,245] <inf> chip: [IN](S) Sending msg 129165150 on secure session with LSID: 5793
b05> [5D[J[21:52:33.415,039] <inf> chip: [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 60 seconds
b05> [5D[J[21:52:33.560,729] <err> chip: [IN]Data received on an unknown session (LSID=5800). Dropping it!
b05> [5D[Jb05> [5D[Jb05> [5D[J[21:52:33.635,437] <inf> chip: [EM]>>> [E:4667i S:5793 M:53712796 (Ack:129165150)] (S) Msg RX from 1:5BC1324CA448C23D [EC5B] — Type 0001:01 (IM:StatusResponse)
b05> [5D[J[21:52:33.635,681] <inf> chip: [IM]Received status response, status is 0x00
b05> [5D[J[21:52:33.636,596] <inf> chip: [EM]<<< [E:4667i S:5793 M:129165151 (Ack:53712796)] (S) Msg TX to 1:5BC1324CA448C23D [EC5B] — Type 0000:10 (SecureChannel:StandaloneAck)
b05> [5D[J[21:52:33.636,749] <inf> chip: [IN](S) Sending msg 129165151 on secure session with LSID: 5793
b05> [5D[Jb05> [5D[Jb05>

Chip-tool logs:

./chip-tool pairing code 1234 03101038080
[1687268077697] [71452:11938902] [DL] KVS will be written to: file:///tmp/chip_kvs
[1687268077704] [71452:11938902] [DL] _Init
[1687268077704] [71452:11938902] [IN] UDP::Init bind&listen port=0
[1687268077705] [71452:11938902] [IN] UDP::Init bound to port=57735
[1687268077705] [71452:11938902] [IN] UDP::Init bind&listen port=0
[1687268077705] [71452:11938902] [IN] UDP::Init bound to port=50538
[1687268077705] [71452:11938902] [IN] BLEBase::Init - setting/overriding transport
[1687268077705] [71452:11938902] [IN] TransportMgr initialized
[1687268077705] [71452:11938902] [FP] Initializing FabricTable from persistent storage
[1687268077705] [71452:11938902] [TS] Last Known Good Time: 2023-06-16T15:24:10
[1687268077707] [71452:11938902] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x00666433E3012986, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
[1687268077707] [71452:11938902] [FP] Fabric index 0x2 was retrieved from storage. Compressed FabricId 0x09A0A133024A6F00, FabricId 0x0000000000000004, NodeId 0x000000000001B669, VendorId 0xFFF1
[1687268077709] [71452:11938902] [ZCL] Using ZAP configuration...
[1687268077709] [71452:11938902] [CTL] System State Initialized...
[1687268077709] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077709] [71452:11938902] [CTL] Setting attestation nonce to random value
[1687268077709] [71452:11938902] [CTL] Setting CSR nonce to random value
[1687268077709] [71452:11938902] [IN] UDP::Init bind&listen port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bound to port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bind&listen port=5550
[1687268077710] [71452:11938902] [IN] UDP::Init bound to port=5550
[1687268077710] [71452:11938902] [IN] TransportMgr initialized
[1687268077710] [71452:11938905] [DL] _OnPlatformEvent
[1687268077710] [71452:11938905] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077710] [71452:11938905] [CTL] Setting attestation nonce to random value
[1687268077710] [71452:11938905] [CTL] Setting CSR nonce to random value
[1687268077711] [71452:11938905] [CTL] Generating NOC
[1687268077712] [71452:11938905] [FP] Validating NOC chain
[1687268077716] [71452:11938905] [FP] NOC chain validation successful
[1687268077716] [71452:11938905] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669
[1687268077716] [71452:11938905] [TS] Last Known Good Time: 2023-06-16T15:24:10
[1687268077716] [71452:11938905] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00
[1687268077716] [71452:11938905] [TS] Retaining current Last Known Good Time
[1687268077717] [71452:11938905] [FP] Metadata for Fabric 0x1 persisted to storage.
[1687268077717] [71452:11938905] [TS] Committing Last Known Good Time to storage: 2023-06-16T15:24:10
[1687268077718] [71452:11938905] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 00666433E3012986)
[1687268077718] [71452:11938905] [IN] UDP::Init bind&listen port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bound to port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bind&listen port=5550
[1687268077718] [71452:11938905] [IN] UDP::Init bound to port=5550
[1687268077718] [71452:11938905] [IN] TransportMgr initialized
[1687268077724] [71452:11938905] [CTL] Setting attestation nonce to random value
[1687268077724] [71452:11938905] [CTL] Setting CSR nonce to random value
[1687268077724] [71452:11938905] [CTL] Stopping commissioning discovery over DNS-SD
[1687268077724] [71452:11938905] [CTL] Starting commissioning discovery over DNS-SD
[1687268077724] [71452:11938905] [DIS] Browsing for: _matterc._udp,_S1
[1687268077725] [71452:11938906] [DIS] Mdns: OnBrowseAdd  name: 7F6BF7BD580EBC96, type: _matterc._udp., domain: local., interface: 12
[1687268077725] [71452:11938906] [DIS] Resolve type=_matterc._udp name=7F6BF7BD580EBC96 interface=12
[1687268077725] [71452:11938906] [DIS] Mdns: OnResolve flags: 0, interface: 12, fullname: 7F6BF7BD580EBC96._matterc._udp.local., hostname: 9A2D4D9F3E4D45B9.local., port: 42005
[1687268077725] [71452:11938906] [DIS] Mdns : OnNewInterface hostname:9A2D4D9F3E4D45B9.local. fullname:7F6BF7BD580EBC96._matterc._udp.local. interface: 12 port: 5540 TXT:"VP=65521+32773,SII=1800,SAI=1000,T=0,D=495,CM=2,PH=36,PI="
[1687268078189] [71452:11938906] [DIS] Mdns: OnGetAddrInfo flags: 2, interface: 12, hostname: 9A2D4D9F3E4D45B9.local.
[1687268078189] [71452:11938906] [DIS] Mdns: OnNewAddress interface: 12 ip:fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8
[1687268078189] [71452:11938906] [DIS] Mdns: Resolve success on interface 12
[1687268078189] [71452:11938906] [DIS] Discovered node:
[1687268078189] [71452:11938906] [DIS] 	Hostname: 9A2D4D9F3E4D45B9
[1687268078189] [71452:11938906] [DIS] 	IP Address #1: fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8
[1687268078189] [71452:11938906] [DIS] 	Port: 5540
[1687268078189] [71452:11938906] [DIS] 	Mrp Interval idle: 1800 ms
[1687268078189] [71452:11938906] [DIS] 	Mrp Interval active: 1000 ms
[1687268078189] [71452:11938906] [DIS] 	TCP Supported: 0
[1687268078189] [71452:11938906] [DIS] 	Vendor ID: 65521
[1687268078189] [71452:11938906] [DIS] 	Product ID: 32773
[1687268078189] [71452:11938906] [DIS] 	Long Discriminator: 495
[1687268078189] [71452:11938906] [DIS] 	Pairing Hint: 36
[1687268078189] [71452:11938906] [DIS] 	Instance Name: 7F6BF7BD580EBC96
[1687268078189] [71452:11938906] [DIS] 	Commissioning Mode: 2
[1687268078189] [71452:11938906] [CTL] Discovered device to be commissioned over DNS-SD
[1687268078189] [71452:11938906] [CTL] Attempting PASE connection to UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8]:5540
[1687268078190] [71452:11938906] [IN] SecureSession[0x60000339c000]: Allocated Type:1 LSID:63495
[1687268078191] [71452:11938906] [SC] Assigned local session key ID 63495
[1687268078191] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073984] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1687268078191] [71452:11938906] [IN] (U) Sending msg 164073984 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8]:5540'
[1687268078192] [71452:11938906] [SC] Sent PBKDF param request
[1687268078192] [71452:11938906] [CTL] Commission called for node ID 0x00000000000004D2
[1687268078833] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049556 (Ack:164073984)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268078833] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268078833] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073984 from Retrans Table on exchange 7114i
[1687268078833] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049557 (Ack:164073984)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1687268078833] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268078833] [71452:11938906] [EM] CHIP MessageCounter:164073984 not in RetransTable on exchange 7114i
[1687268078833] [71452:11938906] [SC] Received PBKDF param response
[1687268078833] [71452:11938906] [SC] Peer assigned session ID 5800
[1687268078833] [71452:11938906] [SC] Found MRP parameters in the message
[1687268078864] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073985 (Ack:145049557)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1687268078864] [71452:11938906] [IN] (U) Sending msg 164073985 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268078864] [71452:11938906] [SC] Sent spake2p msg1
[1687268079048] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049558 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268079048] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268079048] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073985 from Retrans Table on exchange 7114i
[1687268080804] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049559 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1687268080804] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268080804] [71452:11938906] [EM] CHIP MessageCounter:164073985 not in RetransTable on exchange 7114i
[1687268080804] [71452:11938906] [SC] Received spake2p msg2
[1687268080819] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073986 (Ack:145049559)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1687268080819] [71452:11938906] [IN] (U) Sending msg 164073986 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268080819] [71452:11938906] [SC] Sent spake2p msg3
[1687268081929] [71452:11938906] [IN] Received a duplicate message with MessageCounter:145049559 on exchange 7114i
[1687268081929] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049559 (Ack:164073985)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1687268081929] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268081929] [71452:11938906] [EM] Forcing tx of solitary ack for duplicate MessageCounter:145049559 on exchange 7114i
[1687268081930] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073987 (Ack:145049559)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268081930] [71452:11938906] [IN] (U) Sending msg 164073987 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082079] [71452:11938906] [EM] Retransmitting MessageCounter:164073986 on exchange 7114i Send Cnt 1
[1687268082080] [71452:11938906] [IN] (U) Sending msg 164073986 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082194] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049560 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082194] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268082194] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:164073986 from Retrans Table on exchange 7114i
[1687268082194] [71452:11938906] [EM] >>> [E:7114i S:0 M:145049561 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1687268082194] [71452:11938906] [EM] Found matching exchange: 7114i, Delegate: 0x12f009250
[1687268082194] [71452:11938906] [EM] CHIP MessageCounter:164073986 not in RetransTable on exchange 7114i
[1687268082194] [71452:11938906] [SC] SecureSession[0x60000339c000, LSID:63495]: State change 'kEstablishing' --> 'kActive'
[1687268082194] [71452:11938906] [IN] SecureSession[0x60000339c000]: Activated - Type:1 LSID:63495
[1687268082194] [71452:11938906] [IN] New secure session activated for device <FFFFFFFB00000000, 0>, LSID:63495 PSID:5800!
[1687268082194] [71452:11938906] [CTL] Remote device completed SPAKE2+ handshake
[1687268082194] [71452:11938906] [CTL] Stopping commissioning discovery over DNS-SD
[1687268082194] [71452:11938906] [DIS] Mdns: Browse failure (src/platform/Darwin/DnssdImpl.cpp:412: CHIP Error 0x00000074: The operation has been cancelled)
[1687268082194] [71452:11938906] [TOO] Pairing Success
[1687268082194] [71452:11938906] [TOO] PASE establishment successful
[1687268082194] [71452:11938906] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1687268082194] [71452:11938906] [CTL] Performing next commissioning step 'ReadCommissioningInfo'
[1687268082194] [71452:11938906] [CTL] Sending request for commissioning information
[1687268082195] [71452:11938906] [DMG] SendReadRequest ReadClient[0x12ee042d0]: Sending Read Request
[1687268082196] [71452:11938906] [EM] <<< [E:7115i S:63495 M:196832937] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
[1687268082196] [71452:11938906] [IN] (S) Sending msg 196832937 on secure session with LSID: 63495
[1687268082196] [71452:11938906] [DMG] MoveToState ReadClient[0x12ee042d0]: Moving to [AwaitingIn]
[1687268082196] [71452:11938906] [EM] <<< [E:7114i S:0 M:164073988 (Ack:145049561)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082197] [71452:11938906] [IN] (U) Sending msg 164073988 to IP address 'UDP:[fd9c:398b:edff:0:cf3c:b6c4:c22d:54e8%en1]:5540'
[1687268082197] [71452:11938906] [EM] Flushed pending ack for MessageCounter:145049561 on exchange 7114i
[1687268082612] [71452:11938905] [EM] >>> [E:7114i S:0 M:145049562 (Ack:164073986)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082612] [71452:11938905] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268082612] [71452:11938905] [EM] >>> [E:7115i S:63495 M:117603833 (Ack:196832937)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1687268082613] [71452:11938905] [EM] Found matching exchange: 7115i, Delegate: 0x12ee042e0
[1687268082613] [71452:11938905] [EM] Rxd Ack; Removing MessageCounter:196832937 from Retrans Table on exchange 7115i
[1687268082613] [71452:11938905] [DMG] ReportDataMessage =
[1687268082613] [71452:11938905] [DMG] {
[1687268082613] [71452:11938905] [DMG] 	AttributeReportIBs =
[1687268082613] [71452:11938905] [DMG] 	[
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x36d0582e,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x31,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0003,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 20, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x2d06f9df,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x28,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0004,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 32773, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x2d06f9df,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082613] [71452:11938905] [DMG] 					Cluster = 0x28,
[1687268082613] [71452:11938905] [DMG] 					Attribute = 0x0000_0002,
[1687268082613] [71452:11938905] [DMG] 				}
[1687268082613] [71452:11938905] [DMG] 					
[1687268082613] [71452:11938905] [DMG] 				Data = 65521, 
[1687268082613] [71452:11938905] [DMG] 			},
[1687268082613] [71452:11938905] [DMG] 			
[1687268082613] [71452:11938905] [DMG] 		},
[1687268082613] [71452:11938905] [DMG] 		
[1687268082613] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082613] [71452:11938905] [DMG] 		{
[1687268082613] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082613] [71452:11938905] [DMG] 			{
[1687268082613] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082613] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082613] [71452:11938905] [DMG] 				{
[1687268082613] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082614] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082614] [71452:11938905] [DMG] 					Attribute = 0x0000_0003,
[1687268082614] [71452:11938905] [DMG] 				}
[1687268082614] [71452:11938905] [DMG] 					
[1687268082614] [71452:11938905] [DMG] 				Data = 0, 
[1687268082614] [71452:11938905] [DMG] 			},
[1687268082614] [71452:11938905] [DMG] 			
[1687268082614] [71452:11938905] [DMG] 		},
[1687268082614] [71452:11938905] [DMG] 		
[1687268082614] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082614] [71452:11938905] [DMG] 		{
[1687268082614] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082614] [71452:11938905] [DMG] 			{
[1687268082614] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082614] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082614] [71452:11938905] [DMG] 				{
[1687268082614] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082614] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082614] [71452:11938905] [DMG] 					Attribute = 0x0000_0002,
[1687268082614] [71452:11938905] [DMG] 				}
[1687268082614] [71452:11938905] [DMG] 					
[1687268082614] [71452:11938905] [DMG] 				Data = 0, 
[1687268082614] [71452:11938905] [DMG] 			},
[1687268082614] [71452:11938905] [DMG] 			
[1687268082614] [71452:11938905] [DMG] 		},
[1687268082614] [71452:11938905] [DMG] 		
[1687268082614] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082614] [71452:11938905] [DMG] 		{
[1687268082614] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082615] [71452:11938905] [DMG] 			{
[1687268082615] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082615] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082615] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082615] [71452:11938905] [DMG] 					Attribute = 0x0000_0001,
[1687268082615] [71452:11938905] [DMG] 				}
[1687268082615] [71452:11938905] [DMG] 					
[1687268082615] [71452:11938905] [DMG] 				Data = 
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					0x0 = 60, 
[1687268082615] [71452:11938905] [DMG] 					0x1 = 900, 
[1687268082615] [71452:11938905] [DMG] 				},
[1687268082615] [71452:11938905] [DMG] 			},
[1687268082615] [71452:11938905] [DMG] 			
[1687268082615] [71452:11938905] [DMG] 		},
[1687268082615] [71452:11938905] [DMG] 		
[1687268082615] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082615] [71452:11938905] [DMG] 		{
[1687268082615] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082615] [71452:11938905] [DMG] 			{
[1687268082615] [71452:11938905] [DMG] 				DataVersion = 0x3acfa6fe,
[1687268082615] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082615] [71452:11938905] [DMG] 				{
[1687268082615] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082615] [71452:11938905] [DMG] 					Cluster = 0x30,
[1687268082615] [71452:11938905] [DMG] 					Attribute = 0x0000_0000,
[1687268082615] [71452:11938905] [DMG] 				}
[1687268082615] [71452:11938905] [DMG] 					
[1687268082615] [71452:11938905] [DMG] 				Data = 0, 
[1687268082615] [71452:11938905] [DMG] 			},
[1687268082615] [71452:11938905] [DMG] 			
[1687268082615] [71452:11938905] [DMG] 		},
[1687268082615] [71452:11938905] [DMG] 		
[1687268082615] [71452:11938905] [DMG] 		AttributeReportIB =
[1687268082615] [71452:11938905] [DMG] 		{
[1687268082615] [71452:11938905] [DMG] 			AttributeDataIB =
[1687268082616] [71452:11938905] [DMG] 			{
[1687268082616] [71452:11938905] [DMG] 				DataVersion = 0x36d0582e,
[1687268082616] [71452:11938905] [DMG] 				AttributePathIB =
[1687268082616] [71452:11938905] [DMG] 				{
[1687268082616] [71452:11938905] [DMG] 					Endpoint = 0x0,
[1687268082616] [71452:11938905] [DMG] 					Cluster = 0x31,
[1687268082616] [71452:11938905] [DMG] 					Attribute = 0x0000_FFFC,
[1687268082616] [71452:11938905] [DMG] 				}
[1687268082616] [71452:11938905] [DMG] 					
[1687268082616] [71452:11938905] [DMG] 				Data = 2, 
[1687268082616] [71452:11938905] [DMG] 			},
[1687268082616] [71452:11938905] [DMG] 			
[1687268082616] [71452:11938905] [DMG] 		},
[1687268082616] [71452:11938905] [DMG] 		
[1687268082616] [71452:11938905] [DMG] 	],
[1687268082616] [71452:11938905] [DMG] 	
[1687268082616] [71452:11938905] [DMG] 	SuppressResponse = true, 
[1687268082616] [71452:11938905] [DMG] 	InteractionModelRevision = 1
[1687268082616] [71452:11938905] [DMG] }
[1687268082618] [71452:11938905] [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0
[1687268082618] [71452:11938905] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo'
[1687268082619] [71452:11938905] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
[1687268082619] [71452:11938905] [CTL] Performing next commissioning step 'ArmFailSafe'
[1687268082619] [71452:11938905] [CTL] Arming failsafe (60 seconds)
[1687268082619] [71452:11938905] [DMG] ICR moving to [AddingComm]
[1687268082619] [71452:11938905] [DMG] ICR moving to [AddedComma]
[1687268082619] [71452:11938905] [EM] <<< [E:7116i S:63495 M:196832938] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268082619] [71452:11938905] [IN] (S) Sending msg 196832938 on secure session with LSID: 63495
[1687268082620] [71452:11938905] [DMG] ICR moving to [CommandSen]
[1687268082620] [71452:11938905] [EM] <<< [E:7115i S:63495 M:196832939 (Ack:117603833)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268082620] [71452:11938905] [IN] (S) Sending msg 196832939 on secure session with LSID: 63495
[1687268082620] [71452:11938905] [EM] Flushed pending ack for MessageCounter:117603833 on exchange 7115i
[1687268083214] [71452:11938907] [EM] >>> [E:7116i S:63495 M:117603834 (Ack:196832938)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268083214] [71452:11938907] [EM] Found matching exchange: 7116i, Delegate: 0x12ef04088
[1687268083214] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832938 from Retrans Table on exchange 7116i
[1687268083214] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268083214] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268083214] [71452:11938907] [DMG] {
[1687268083214] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268083214] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268083214] [71452:11938907] [DMG] 	[
[1687268083214] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268083214] [71452:11938907] [DMG] 		{
[1687268083214] [71452:11938907] [DMG] 			CommandDataIB =
[1687268083214] [71452:11938907] [DMG] 			{
[1687268083214] [71452:11938907] [DMG] 				CommandPathIB =
[1687268083214] [71452:11938907] [DMG] 				{
[1687268083214] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268083214] [71452:11938907] [DMG] 					ClusterId = 0x30,
[1687268083214] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268083214] [71452:11938907] [DMG] 				},
[1687268083214] [71452:11938907] [DMG] 				
[1687268083214] [71452:11938907] [DMG] 				CommandFields = 
[1687268083214] [71452:11938907] [DMG] 				{
[1687268083214] [71452:11938907] [DMG] 					0x0 = 0, 
[1687268083214] [71452:11938907] [DMG] 					0x1 = "" (0 chars), 
[1687268083214] [71452:11938907] [DMG] 				},
[1687268083214] [71452:11938907] [DMG] 			},
[1687268083214] [71452:11938907] [DMG] 			
[1687268083214] [71452:11938907] [DMG] 		},
[1687268083214] [71452:11938907] [DMG] 		
[1687268083214] [71452:11938907] [DMG] 	],
[1687268083214] [71452:11938907] [DMG] 	
[1687268083214] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268083214] [71452:11938907] [DMG] },
[1687268083215] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1687268083218] [71452:11938907] [CTL] Received ArmFailSafe response errorCode=0
[1687268083218] [71452:11938907] [CTL] Successfully finished commissioning step 'ArmFailSafe'
[1687268083218] [71452:11938907] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1687268083218] [71452:11938907] [CTL] Performing next commissioning step 'ConfigRegulatory'
[1687268083218] [71452:11938907] [CTL] Setting Regulatory Config
[1687268083218] [71452:11938907] [CTL] Device does not support configurable regulatory location
[1687268083218] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268083218] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268083219] [71452:11938907] [EM] <<< [E:7117i S:63495 M:196832940] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268083219] [71452:11938907] [IN] (S) Sending msg 196832940 on secure session with LSID: 63495
[1687268083219] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268083219] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268083219] [71452:11938907] [EM] <<< [E:7116i S:63495 M:196832941 (Ack:117603834)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268083219] [71452:11938907] [IN] (S) Sending msg 196832941 on secure session with LSID: 63495
[1687268083219] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603834 on exchange 7116i
[1687268083828] [71452:11938906] [EM] >>> [E:7117i S:63495 M:117603835 (Ack:196832940)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268083828] [71452:11938906] [EM] Found matching exchange: 7117i, Delegate: 0x12ee041c8
[1687268083828] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832940 from Retrans Table on exchange 7117i
[1687268083828] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268083828] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268083828] [71452:11938906] [DMG] {
[1687268083828] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268083828] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268083828] [71452:11938906] [DMG] 	[
[1687268083828] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268083828] [71452:11938906] [DMG] 		{
[1687268083828] [71452:11938906] [DMG] 			CommandDataIB =
[1687268083828] [71452:11938906] [DMG] 			{
[1687268083828] [71452:11938906] [DMG] 				CommandPathIB =
[1687268083828] [71452:11938906] [DMG] 				{
[1687268083828] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268083828] [71452:11938906] [DMG] 					ClusterId = 0x30,
[1687268083828] [71452:11938906] [DMG] 					CommandId = 0x3,
[1687268083829] [71452:11938906] [DMG] 				},
[1687268083829] [71452:11938906] [DMG] 				
[1687268083829] [71452:11938906] [DMG] 				CommandFields = 
[1687268083829] [71452:11938906] [DMG] 				{
[1687268083829] [71452:11938906] [DMG] 					0x0 = 0, 
[1687268083829] [71452:11938906] [DMG] 					0x1 = "" (0 chars), 
[1687268083829] [71452:11938906] [DMG] 				},
[1687268083829] [71452:11938906] [DMG] 			},
[1687268083829] [71452:11938906] [DMG] 			
[1687268083829] [71452:11938906] [DMG] 		},
[1687268083829] [71452:11938906] [DMG] 		
[1687268083829] [71452:11938906] [DMG] 	],
[1687268083829] [71452:11938906] [DMG] 	
[1687268083829] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268083829] [71452:11938906] [DMG] },
[1687268083829] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1687268083829] [71452:11938906] [CTL] Received SetRegulatoryConfig response errorCode=0
[1687268083829] [71452:11938906] [CTL] Successfully finished commissioning step 'ConfigRegulatory'
[1687268083829] [71452:11938906] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
[1687268083829] [71452:11938906] [CTL] Performing next commissioning step 'SendPAICertificateRequest'
[1687268083829] [71452:11938906] [CTL] Sending request for PAI certificate
[1687268083829] [71452:11938906] [CTL] Sending Certificate Chain request to 0x12f009200 device
[1687268083829] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268083831] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268083831] [71452:11938906] [EM] <<< [E:7118i S:63495 M:196832942] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268083831] [71452:11938906] [IN] (S) Sending msg 196832942 on secure session with LSID: 63495
[1687268083831] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268083831] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268083832] [71452:11938906] [EM] <<< [E:7117i S:63495 M:196832943 (Ack:117603835)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268083832] [71452:11938906] [IN] (S) Sending msg 196832943 on secure session with LSID: 63495
[1687268083832] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603835 on exchange 7117i
[1687268084315] [71452:11938907] [EM] >>> [E:7118i S:63495 M:117603836 (Ack:196832942)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268084315] [71452:11938907] [EM] Found matching exchange: 7118i, Delegate: 0x12ee04458
[1687268084315] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832942 from Retrans Table on exchange 7118i
[1687268084315] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268084315] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268084315] [71452:11938907] [DMG] {
[1687268084315] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268084315] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268084316] [71452:11938907] [DMG] 	[
[1687268084316] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268084316] [71452:11938907] [DMG] 		{
[1687268084316] [71452:11938907] [DMG] 			CommandDataIB =
[1687268084316] [71452:11938907] [DMG] 			{
[1687268084316] [71452:11938907] [DMG] 				CommandPathIB =
[1687268084316] [71452:11938907] [DMG] 				{
[1687268084316] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268084316] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268084316] [71452:11938907] [DMG] 					CommandId = 0x3,
[1687268084316] [71452:11938907] [DMG] 				},
[1687268084316] [71452:11938907] [DMG] 				
[1687268084316] [71452:11938907] [DMG] 				CommandFields = 
[1687268084316] [71452:11938907] [DMG] 				{
[1687268084316] [71452:11938907] [DMG] 					0x0 = [
[1687268084316] [71452:11938907] [DMG] 							0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64,
[1687268084316] [71452:11938907] [DMG] 					] (463 bytes)
[1687268084316] [71452:11938907] [DMG] 				},
[1687268084316] [71452:11938907] [DMG] 			},
[1687268084316] [71452:11938907] [DMG] 			
[1687268084316] [71452:11938907] [DMG] 		},
[1687268084316] [71452:11938907] [DMG] 		
[1687268084316] [71452:11938907] [DMG] 	],
[1687268084316] [71452:11938907] [DMG] 	
[1687268084316] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268084316] [71452:11938907] [DMG] },
[1687268084316] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1687268084316] [71452:11938907] [CTL] Received certificate chain from the device
[1687268084316] [71452:11938907] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest'
[1687268084316] [71452:11938907] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1687268084317] [71452:11938907] [CTL] Performing next commissioning step 'SendDACCertificateRequest'
[1687268084317] [71452:11938907] [CTL] Sending request for DAC certificate
[1687268084317] [71452:11938907] [CTL] Sending Certificate Chain request to 0x12f009200 device
[1687268084317] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268084317] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268084317] [71452:11938907] [EM] <<< [E:7119i S:63495 M:196832944] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268084317] [71452:11938907] [IN] (S) Sending msg 196832944 on secure session with LSID: 63495
[1687268084317] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268084317] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268084317] [71452:11938907] [EM] <<< [E:7118i S:63495 M:196832945 (Ack:117603836)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268084317] [71452:11938907] [IN] (S) Sending msg 196832945 on secure session with LSID: 63495
[1687268084318] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603836 on exchange 7118i
[1687268084832] [71452:11938906] [EM] >>> [E:7119i S:63495 M:117603837 (Ack:196832944)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268084832] [71452:11938906] [EM] Found matching exchange: 7119i, Delegate: 0x12ee041c8
[1687268084832] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832944 from Retrans Table on exchange 7119i
[1687268084832] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268084832] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268084832] [71452:11938906] [DMG] {
[1687268084832] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268084832] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268084832] [71452:11938906] [DMG] 	[
[1687268084832] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268084832] [71452:11938906] [DMG] 		{
[1687268084832] [71452:11938906] [DMG] 			CommandDataIB =
[1687268084832] [71452:11938906] [DMG] 			{
[1687268084832] [71452:11938906] [DMG] 				CommandPathIB =
[1687268084832] [71452:11938906] [DMG] 				{
[1687268084832] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268084832] [71452:11938906] [DMG] 					ClusterId = 0x3e,
[1687268084832] [71452:11938906] [DMG] 					CommandId = 0x3,
[1687268084832] [71452:11938906] [DMG] 				},
[1687268084832] [71452:11938906] [DMG] 				
[1687268084832] [71452:11938906] [DMG] 				CommandFields = 
[1687268084832] [71452:11938906] [DMG] 				{
[1687268084832] [71452:11938906] [DMG] 					0x0 = [
[1687268084832] [71452:11938906] [DMG] 							0x30, 0x82, 0x01, 0xe8, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x6f, 0xdc, 0xb6, 0xed, 0x06, 0xf3, 0x58, 0xf9, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x35, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x35, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x7e, 0xf2, 0xf1, 0x08, 0x2b, 0x2a, 0x56, 0x4a, 0x05, 0x44, 0xc0, 0xa6, 0x13, 0xd2, 0x1b, 0x15, 0x75, 0x00, 0x55, 0x2d, 0x03, 0x5f, 0x23, 0x02, 0x25, 0x2f, 0xce, 0xe8, 0x1d, 0x6e, 0xc6, 0x74, 0x17,
[1687268084832] [71452:11938906] [DMG] 					] (492 bytes)
[1687268084832] [71452:11938906] [DMG] 				},
[1687268084832] [71452:11938906] [DMG] 			},
[1687268084832] [71452:11938906] [DMG] 			
[1687268084832] [71452:11938906] [DMG] 		},
[1687268084832] [71452:11938906] [DMG] 		
[1687268084833] [71452:11938906] [DMG] 	],
[1687268084833] [71452:11938906] [DMG] 	
[1687268084833] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268084833] [71452:11938906] [DMG] },
[1687268084833] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1687268084833] [71452:11938906] [CTL] Received certificate chain from the device
[1687268084833] [71452:11938906] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest'
[1687268084833] [71452:11938906] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1687268084833] [71452:11938906] [CTL] Performing next commissioning step 'SendAttestationRequest'
[1687268084833] [71452:11938906] [CTL] Sending Attestation Request to the device.
[1687268084833] [71452:11938906] [CTL] Sending Attestation request to 0x12f009200 device
[1687268084833] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268084833] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268084833] [71452:11938906] [EM] <<< [E:7120i S:63495 M:196832946] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268084833] [71452:11938906] [IN] (S) Sending msg 196832946 on secure session with LSID: 63495
[1687268084834] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268084834] [71452:11938906] [CTL] Sent Attestation request, waiting for the Attestation Information
[1687268084834] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268084834] [71452:11938906] [EM] <<< [E:7119i S:63495 M:196832947 (Ack:117603837)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268084834] [71452:11938906] [IN] (S) Sending msg 196832947 on secure session with LSID: 63495
[1687268084834] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603837 on exchange 7119i
[1687268085961] [71452:11938906] [EM] Retransmitting MessageCounter:196832946 on exchange 7120i Send Cnt 1
[1687268085962] [71452:11938906] [IN] (S) Sending msg 196832946 on secure session with LSID: 63495
[1687268086131] [71452:11938907] [EM] >>> [E:7120i S:63495 M:117603838 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086131] [71452:11938907] [EM] Found matching exchange: 7120i, Delegate: 0x139104278
[1687268086131] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832946 from Retrans Table on exchange 7120i
[1687268086132] [71452:11938907] [EM] >>> [E:7120i S:63495 M:117603839 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268086132] [71452:11938907] [EM] Found matching exchange: 7120i, Delegate: 0x139104278
[1687268086132] [71452:11938907] [EM] CHIP MessageCounter:196832946 not in RetransTable on exchange 7120i
[1687268086132] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268086132] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268086132] [71452:11938907] [DMG] {
[1687268086132] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268086132] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268086132] [71452:11938907] [DMG] 	[
[1687268086132] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268086132] [71452:11938907] [DMG] 		{
[1687268086132] [71452:11938907] [DMG] 			CommandDataIB =
[1687268086132] [71452:11938907] [DMG] 			{
[1687268086132] [71452:11938907] [DMG] 				CommandPathIB =
[1687268086132] [71452:11938907] [DMG] 				{
[1687268086132] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268086132] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268086132] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268086132] [71452:11938907] [DMG] 				},
[1687268086132] [71452:11938907] [DMG] 				
[1687268086132] [71452:11938907] [DMG] 				CommandFields = 
[1687268086132] [71452:11938907] [DMG] 				{
[1687268086132] [71452:11938907] [DMG] 					0x0 = [
[1687268086132] [71452:11938907] [DMG] 							0x15, 0x31, 0x01, 0x1b, 0x02, 0x30, 0x82, 0x02, 0x17, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x08, 0x30, 0x82, 0x02, 0x04, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x70, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x61, 0x04, 0x82, 0x01, 0x5d, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80,
[1687268086132] [71452:11938907] [DMG] 					] (583 bytes)
[1687268086132] [71452:11938907] [DMG] 					0x1 = [
[1687268086132] [71452:11938907] [DMG] 							0xb8, 0xca, 0x92, 0xf6, 0xb1, 0xdd, 0x38, 0xec, 0x11, 0x55, 0x96, 0x53, 0xc3, 0x43, 0x50, 0xce, 0xa0, 0x9c, 0x61, 0xdb, 0x1a, 0xca, 0x6e, 0x80, 0xba, 0x86, 0xf6, 0xc6, 0x13, 0x15, 0x2b, 0x99, 0x03, 0xd4, 0x35, 0x45, 0xc4, 0x83, 0x95, 0xa1, 0xfc, 0x7f, 0xe6, 0x23, 0x55, 0x70, 0x95, 0xc5, 0xd2, 0x58, 0x27, 0xd2, 0xdb, 0xf1, 0xdd, 0x13, 0x48, 0xfd, 0xf3, 0x0b, 0xda, 0x13, 0x7d, 0x73, 
[1687268086133] [71452:11938907] [DMG] 					] (64 bytes)
[1687268086133] [71452:11938907] [DMG] 				},
[1687268086133] [71452:11938907] [DMG] 			},
[1687268086133] [71452:11938907] [DMG] 			
[1687268086133] [71452:11938907] [DMG] 		},
[1687268086133] [71452:11938907] [DMG] 		
[1687268086133] [71452:11938907] [DMG] 	],
[1687268086133] [71452:11938907] [DMG] 	
[1687268086133] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268086133] [71452:11938907] [DMG] },
[1687268086133] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1687268086133] [71452:11938907] [CTL] Received Attestation Information from the device
[1687268086133] [71452:11938907] [CTL] Successfully finished commissioning step 'SendAttestationRequest'
[1687268086133] [71452:11938907] [CTL] AutoCommissioner setting attestationElements buffer size 583/583
[1687268086133] [71452:11938907] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1687268086133] [71452:11938907] [CTL] Performing next commissioning step 'AttestationVerification'
[1687268086133] [71452:11938907] [CTL] Verifying attestation
[1687268086163] [71452:11938907] [CTL] Successfully validated 'Attestation Information' command received from the device.
[1687268086163] [71452:11938907] [CTL] Successfully finished commissioning step 'AttestationVerification'
[1687268086163] [71452:11938907] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1687268086163] [71452:11938907] [CTL] Performing next commissioning step 'SendOpCertSigningRequest'
[1687268086163] [71452:11938907] [CTL] Sending CSR request to 0x12f009200 device
[1687268086163] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268086163] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268086163] [71452:11938907] [EM] <<< [E:7121i S:63495 M:196832948] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268086163] [71452:11938907] [IN] (S) Sending msg 196832948 on secure session with LSID: 63495
[1687268086163] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268086163] [71452:11938907] [CTL] Sent CSR request, waiting for the CSR
[1687268086164] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268086164] [71452:11938907] [EM] <<< [E:7120i S:63495 M:196832949 (Ack:117603839)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086164] [71452:11938907] [IN] (S) Sending msg 196832949 on secure session with LSID: 63495
[1687268086164] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603839 on exchange 7120i
[1687268086566] [71452:11938906] [EM] >>> [E:7120i S:63495 M:117603840 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086566] [71452:11938906] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268086986] [71452:11938906] [IN] Received a duplicate message with MessageCounter:117603839 on exchange 7120i
[1687268086986] [71452:11938906] [EM] >>> [E:7120i S:63495 M:117603839 (Ack:196832946)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268086987] [71452:11938906] [EM] Generating StandaloneAck via exchange: 7120i
[1687268086987] [71452:11938906] [EM] Forcing tx of solitary ack for duplicate MessageCounter:117603839 on exchange 7120i
[1687268086987] [71452:11938906] [EM] <<< [E:7120i S:63495 M:196832950 (Ack:117603839)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268086987] [71452:11938906] [IN] (S) Sending msg 196832950 on secure session with LSID: 63495
[1687268087316] [71452:11938907] [EM] Retransmitting MessageCounter:196832948 on exchange 7121i Send Cnt 1
[1687268087316] [71452:11938907] [IN] (S) Sending msg 196832948 on secure session with LSID: 63495
[1687268087376] [71452:11938906] [EM] >>> [E:7121i S:63495 M:117603841 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268087376] [71452:11938906] [EM] Found matching exchange: 7121i, Delegate: 0x13ee5a958
[1687268087376] [71452:11938906] [EM] Rxd Ack; Removing MessageCounter:196832948 from Retrans Table on exchange 7121i
[1687268087779] [71452:11938906] [EM] >>> [E:7121i S:63495 M:117603842 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268087779] [71452:11938906] [EM] Found matching exchange: 7121i, Delegate: 0x13ee5a958
[1687268087779] [71452:11938906] [EM] CHIP MessageCounter:196832948 not in RetransTable on exchange 7121i
[1687268087779] [71452:11938906] [DMG] ICR moving to [ResponseRe]
[1687268087779] [71452:11938906] [DMG] InvokeResponseMessage =
[1687268087779] [71452:11938906] [DMG] {
[1687268087779] [71452:11938906] [DMG] 	suppressResponse = false, 
[1687268087779] [71452:11938906] [DMG] 	InvokeResponseIBs =
[1687268087779] [71452:11938906] [DMG] 	[
[1687268087779] [71452:11938906] [DMG] 		InvokeResponseIB =
[1687268087779] [71452:11938906] [DMG] 		{
[1687268087779] [71452:11938906] [DMG] 			CommandDataIB =
[1687268087779] [71452:11938906] [DMG] 			{
[1687268087779] [71452:11938906] [DMG] 				CommandPathIB =
[1687268087779] [71452:11938906] [DMG] 				{
[1687268087779] [71452:11938906] [DMG] 					EndpointId = 0x0,
[1687268087779] [71452:11938906] [DMG] 					ClusterId = 0x3e,
[1687268087779] [71452:11938906] [DMG] 					CommandId = 0x5,
[1687268087779] [71452:11938906] [DMG] 				},
[1687268087779] [71452:11938906] [DMG] 				
[1687268087779] [71452:11938906] [DMG] 				CommandFields = 
[1687268087779] [71452:11938906] [DMG] 				{
[1687268087779] [71452:11938906] [DMG] 					0x0 = [
[1687268087779] [71452:11938906] [DMG] 							0x15, 0x30, 0x01, 0xcd, 0x30, 0x81, 0xca, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x90, 0x55, 0xb8, 0x1b, 0x82, 0xe7, 0xd4, 0x26, 0x5e, 0x07, 0xaa, 0x71, 0x83, 0xe3, 0x00, 0xcd, 0x2c, 0xf7, 0xbb, 0x21, 0x9c, 0x03, 0x71, 0x25, 0xce, 0x05, 0x66, 0x43, 0xe4, 0x6c, 0x13, 0x55, 0x0a, 0x26, 0x1b, 0x51, 0x10, 0x29, 0x54, 0xd7, 0xee, 0xab, 0x50, 0xc2, 0xa3, 0xfe, 0xb0, 0xfc, 0x21, 0xb1, 0x38, 0xd5, 0x59, 0x94, 0xc9, 0x69, 0xb8, 0x9b, 0x6d, 0x55, 0x43, 0x2e, 0x96, 0xee, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0xab, 0xbd, 0x6b, 0x72, 0xed, 0xdf, 0xd0, 0x7f, 0xd8, 0x99, 0xa4, 0xef, 0x05, 0x08, 0x51, 0xf4, 0xce, 0xd2, 0x87, 0x53, 0x04, 0xc7, 0x3f, 0x57, 0xe3, 0x64, 0xaf, 0xef, 0x5b, 0xa1, 0xa7, 0x26, 0x02, 0x20, 0x4f, 0xd3, 0x51, 0xc6, 0xbc, 0xf8, 0xc8, 0x6a, 0xfc, 0xd6, 0xa8, 0xef, 0x08, 0xab, 0x6c, 0x9a, 0x73, 0x47, 0xc8, 0x52, 0xf8, 0xdf, 0xc0, 0x77, 0x40, 0x8e, 0x74, 0xd2, 0xe4, 0xd6, 0x7e, 0x63, 0x30, 0x02, 0x20, 0x95, 0x34, 0x63, 0x96, 0x40, 0x47, 0xdc, 0xfd, 0xec, 0x8d, 0xd3, 0xd4, 0x7c, 0x0e, 0xa3, 0xef, 0xf4, 0xe2, 0x12, 0x8f, 0xaf, 0x9d, 0x5b, 0x2b, 0x44, 0xec, 0x48, 0x44, 0xd0, 0x70, 0xe0, 0x14, 0x18, 
[1687268087779] [71452:11938906] [DMG] 					] (245 bytes)
[1687268087779] [71452:11938906] [DMG] 					0x1 = [
[1687268087779] [71452:11938906] [DMG] 							0xeb, 0xde, 0xb2, 0xf6, 0x53, 0x5a, 0xe1, 0x1f, 0x7d, 0x72, 0xd2, 0x89, 0x28, 0x34, 0x45, 0x69, 0xb5, 0x85, 0xb9, 0x11, 0x21, 0x90, 0x4b, 0xf9, 0x18, 0xf9, 0x45, 0x0a, 0xe3, 0x8f, 0xe4, 0xd1, 0x50, 0x1f, 0x5c, 0x22, 0x8e, 0x6f, 0xc3, 0xed, 0xd2, 0xca, 0x49, 0xe8, 0x66, 0x1b, 0x00, 0xf6, 0xf0, 0x7f, 0xb4, 0x1f, 0x77, 0x0d, 0x2f, 0x29, 0x88, 0x53, 0x18, 0x55, 0xa7, 0x6c, 0xcc, 0x46, 
[1687268087779] [71452:11938906] [DMG] 					] (64 bytes)
[1687268087779] [71452:11938906] [DMG] 				},
[1687268087779] [71452:11938906] [DMG] 			},
[1687268087779] [71452:11938906] [DMG] 			
[1687268087779] [71452:11938906] [DMG] 		},
[1687268087779] [71452:11938906] [DMG] 		
[1687268087779] [71452:11938906] [DMG] 	],
[1687268087779] [71452:11938906] [DMG] 	
[1687268087779] [71452:11938906] [DMG] 	InteractionModelRevision = 1
[1687268087779] [71452:11938906] [DMG] },
[1687268087780] [71452:11938906] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1687268087780] [71452:11938906] [CTL] Received certificate signing request from the device
[1687268087780] [71452:11938906] [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest'
[1687268087780] [71452:11938906] [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1687268087780] [71452:11938906] [CTL] Performing next commissioning step 'ValidateCSR'
[1687268087790] [71452:11938906] [CTL] Successfully finished commissioning step 'ValidateCSR'
[1687268087790] [71452:11938906] [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1687268087790] [71452:11938906] [CTL] Performing next commissioning step 'GenerateNOCChain'
[1687268087790] [71452:11938906] [CTL] Getting certificate chain for the device from the issuer
[1687268087791] [71452:11938906] [CTL] Verifying Certificate Signing Request
[1687268087797] [71452:11938906] [CTL] Generating NOC
[1687268087799] [71452:11938906] [CTL] Providing certificate chain to the commissioner
[1687268087799] [71452:11938906] [CTL] Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
[1687268087799] [71452:11938906] [CTL] Successfully finished commissioning step 'GenerateNOCChain'
[1687268087799] [71452:11938906] [CTL] Performing next commissioning step 'SendTrustedRootCert'
[1687268087799] [71452:11938906] [CTL] Sending root certificate to the device
[1687268087799] [71452:11938906] [DMG] ICR moving to [AddingComm]
[1687268087799] [71452:11938906] [DMG] ICR moving to [AddedComma]
[1687268087799] [71452:11938906] [EM] <<< [E:7122i S:63495 M:196832951] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268087799] [71452:11938906] [IN] (S) Sending msg 196832951 on secure session with LSID: 63495
[1687268087800] [71452:11938906] [DMG] ICR moving to [CommandSen]
[1687268087800] [71452:11938906] [CTL] Sent root certificate to the device
[1687268087800] [71452:11938906] [DMG] ICR moving to [AwaitingDe]
[1687268087800] [71452:11938906] [EM] <<< [E:7121i S:63495 M:196832952 (Ack:117603842)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268087800] [71452:11938906] [IN] (S) Sending msg 196832952 on secure session with LSID: 63495
[1687268087800] [71452:11938906] [EM] Flushed pending ack for MessageCounter:117603842 on exchange 7121i
[1687268088421] [71452:11938907] [EM] >>> [E:7121i S:63495 M:117603843 (Ack:196832948)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088421] [71452:11938907] [EM] OnMessageReceived failed, err = src/messaging/ExchangeMgr.cpp:305: CHIP Error 0x00000070: Unsolicited msg with originator bit clear
[1687268088422] [71452:11938907] [EM] >>> [E:7122i S:63495 M:117603844 (Ack:196832951)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088422] [71452:11938907] [EM] Found matching exchange: 7122i, Delegate: 0x13ef05c68
[1687268088422] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832951 from Retrans Table on exchange 7122i
[1687268088726] [71452:11938907] [EM] >>> [E:7122i S:63495 M:117603845 (Ack:196832951)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268088726] [71452:11938907] [EM] Found matching exchange: 7122i, Delegate: 0x13ef05c68
[1687268088726] [71452:11938907] [EM] CHIP MessageCounter:196832951 not in RetransTable on exchange 7122i
[1687268088726] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268088726] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268088726] [71452:11938907] [DMG] {
[1687268088726] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268088726] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268088726] [71452:11938907] [DMG] 	[
[1687268088726] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268088726] [71452:11938907] [DMG] 		{
[1687268088726] [71452:11938907] [DMG] 			CommandStatusIB =
[1687268088726] [71452:11938907] [DMG] 			{
[1687268088726] [71452:11938907] [DMG] 				CommandPathIB =
[1687268088726] [71452:11938907] [DMG] 				{
[1687268088726] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268088726] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268088726] [71452:11938907] [DMG] 					CommandId = 0xb,
[1687268088726] [71452:11938907] [DMG] 				},
[1687268088726] [71452:11938907] [DMG] 				
[1687268088726] [71452:11938907] [DMG] 				StatusIB =
[1687268088726] [71452:11938907] [DMG] 				{
[1687268088727] [71452:11938907] [DMG] 					status = 0x00 (SUCCESS),
[1687268088727] [71452:11938907] [DMG] 				},
[1687268088727] [71452:11938907] [DMG] 				
[1687268088727] [71452:11938907] [DMG] 			},
[1687268088727] [71452:11938907] [DMG] 			
[1687268088727] [71452:11938907] [DMG] 		},
[1687268088727] [71452:11938907] [DMG] 		
[1687268088727] [71452:11938907] [DMG] 	],
[1687268088727] [71452:11938907] [DMG] 	
[1687268088727] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268088727] [71452:11938907] [DMG] },
[1687268088727] [71452:11938907] [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1687268088727] [71452:11938907] [CTL] Device confirmed that it has received the root certificate
[1687268088727] [71452:11938907] [CTL] Successfully finished commissioning step 'SendTrustedRootCert'
[1687268088727] [71452:11938907] [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1687268088727] [71452:11938907] [CTL] Performing next commissioning step 'SendNOC'
[1687268088727] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268088727] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268088728] [71452:11938907] [EM] <<< [E:7123i S:63495 M:196832953] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268088728] [71452:11938907] [IN] (S) Sending msg 196832953 on secure session with LSID: 63495
[1687268088729] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268088729] [71452:11938907] [CTL] Sent operational certificate to the device
[1687268088729] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268088729] [71452:11938907] [EM] <<< [E:7122i S:63495 M:196832954 (Ack:117603845)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268088729] [71452:11938907] [IN] (S) Sending msg 196832954 on secure session with LSID: 63495
[1687268088729] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603845 on exchange 7122i
[1687268089366] [71452:11938907] [EM] >>> [E:7123i S:63495 M:117603846 (Ack:196832953)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268089366] [71452:11938907] [EM] Found matching exchange: 7123i, Delegate: 0x12ee041c8
[1687268089366] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832953 from Retrans Table on exchange 7123i
[1687268089366] [71452:11938907] [EM] >>> [E:7123i S:63495 M:117603847 (Ack:196832953)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268089366] [71452:11938907] [EM] Found matching exchange: 7123i, Delegate: 0x12ee041c8
[1687268089366] [71452:11938907] [EM] CHIP MessageCounter:196832953 not in RetransTable on exchange 7123i
[1687268089367] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268089367] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268089367] [71452:11938907] [DMG] {
[1687268089367] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268089367] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268089367] [71452:11938907] [DMG] 	[
[1687268089367] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268089367] [71452:11938907] [DMG] 		{
[1687268089367] [71452:11938907] [DMG] 			CommandDataIB =
[1687268089367] [71452:11938907] [DMG] 			{
[1687268089367] [71452:11938907] [DMG] 				CommandPathIB =
[1687268089367] [71452:11938907] [DMG] 				{
[1687268089367] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268089367] [71452:11938907] [DMG] 					ClusterId = 0x3e,
[1687268089367] [71452:11938907] [DMG] 					CommandId = 0x8,
[1687268089367] [71452:11938907] [DMG] 				},
[1687268089367] [71452:11938907] [DMG] 				
[1687268089367] [71452:11938907] [DMG] 				CommandFields = 
[1687268089367] [71452:11938907] [DMG] 				{
[1687268089367] [71452:11938907] [DMG] 					0x0 = 9, 
[1687268089367] [71452:11938907] [DMG] 				},
[1687268089367] [71452:11938907] [DMG] 			},
[1687268089367] [71452:11938907] [DMG] 			
[1687268089367] [71452:11938907] [DMG] 		},
[1687268089367] [71452:11938907] [DMG] 		
[1687268089367] [71452:11938907] [DMG] 	],
[1687268089367] [71452:11938907] [DMG] 	
[1687268089367] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268089367] [71452:11938907] [DMG] },
[1687268089367] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1687268089367] [71452:11938907] [CTL] Device returned status 9 on receiving the NOC
[1687268089367] [71452:11938907] [CTL] Add NOC failed with error src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists
[1687268089367] [71452:11938907] [CTL] Error on commissioning step 'SendNOC': 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists'
[1687268089367] [71452:11938907] [CTL] Failed to perform commissioning step 13
[1687268089367] [71452:11938907] [CTL] Going from commissioning step 'SendNOC' with lastErr = 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists' -> 'Cleanup'
[1687268089367] [71452:11938907] [CTL] Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists'
[1687268089367] [71452:11938907] [CTL] Expiring failsafe on proxy 0x12f009200
[1687268089367] [71452:11938907] [DMG] ICR moving to [AddingComm]
[1687268089367] [71452:11938907] [DMG] ICR moving to [AddedComma]
[1687268089367] [71452:11938907] [EM] <<< [E:7124i S:63495 M:196832955] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1687268089367] [71452:11938907] [IN] (S) Sending msg 196832955 on secure session with LSID: 63495
[1687268089368] [71452:11938907] [DMG] ICR moving to [CommandSen]
[1687268089368] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268089368] [71452:11938907] [EM] <<< [E:7123i S:63495 M:196832956 (Ack:117603847)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1687268089368] [71452:11938907] [IN] (S) Sending msg 196832956 on secure session with LSID: 63495
[1687268089368] [71452:11938907] [EM] Flushed pending ack for MessageCounter:117603847 on exchange 7123i
[1687268089744] [71452:11938907] [EM] >>> [E:7124i S:63495 M:117603848 (Ack:196832955)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1687268089744] [71452:11938907] [EM] Found matching exchange: 7124i, Delegate: 0x139004088
[1687268089744] [71452:11938907] [EM] Rxd Ack; Removing MessageCounter:196832955 from Retrans Table on exchange 7124i
[1687268089745] [71452:11938907] [DMG] ICR moving to [ResponseRe]
[1687268089745] [71452:11938907] [DMG] InvokeResponseMessage =
[1687268089745] [71452:11938907] [DMG] {
[1687268089745] [71452:11938907] [DMG] 	suppressResponse = false, 
[1687268089745] [71452:11938907] [DMG] 	InvokeResponseIBs =
[1687268089745] [71452:11938907] [DMG] 	[
[1687268089745] [71452:11938907] [DMG] 		InvokeResponseIB =
[1687268089745] [71452:11938907] [DMG] 		{
[1687268089745] [71452:11938907] [DMG] 			CommandDataIB =
[1687268089745] [71452:11938907] [DMG] 			{
[1687268089745] [71452:11938907] [DMG] 				CommandPathIB =
[1687268089745] [71452:11938907] [DMG] 				{
[1687268089745] [71452:11938907] [DMG] 					EndpointId = 0x0,
[1687268089745] [71452:11938907] [DMG] 					ClusterId = 0x30,
[1687268089745] [71452:11938907] [DMG] 					CommandId = 0x1,
[1687268089745] [71452:11938907] [DMG] 				},
[1687268089745] [71452:11938907] [DMG] 				
[1687268089745] [71452:11938907] [DMG] 				CommandFields = 
[1687268089745] [71452:11938907] [DMG] 				{
[1687268089745] [71452:11938907] [DMG] 					0x0 = 0, 
[1687268089745] [71452:11938907] [DMG] 					0x1 = "" (0 chars), 
[1687268089745] [71452:11938907] [DMG] 				},
[1687268089745] [71452:11938907] [DMG] 			},
[1687268089745] [71452:11938907] [DMG] 			
[1687268089745] [71452:11938907] [DMG] 		},
[1687268089745] [71452:11938907] [DMG] 		
[1687268089745] [71452:11938907] [DMG] 	],
[1687268089745] [71452:11938907] [DMG] 	
[1687268089745] [71452:11938907] [DMG] 	InteractionModelRevision = 1
[1687268089745] [71452:11938907] [DMG] },
[1687268089745] [71452:11938907] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1687268089745] [71452:11938907] [CTL] Failsafe disarmed
[1687268089745] [71452:11938907] [CTL] Successfully finished commissioning step 'Cleanup'
[1687268089745] [71452:11938907] [TOO] Device commissioning Failure: src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists
[1687268089745] [71452:11938907] [IN] SecureSession[0x60000339c000]: MarkForEviction Type:1 LSID:63495
[1687268089745] [71452:11938907] [SC] SecureSession[0x60000339c000, LSID:63495]: State change 'kActive' --> 'kPendingEviction'
[1687268089745] [71452:11938907] [EM] Failed to send Solitary ack for MessageCounter:117603848 on exchange 7124i:src/messaging/ExchangeContext.cpp:144: CHIP Error 0x00000002: Connection aborted
[1687268089745] [71452:11938907] [DMG] ICR moving to [AwaitingDe]
[1687268089745] [71452:11938907] [IN] SecureSession[0x60000339c000]: Released - Type:1 LSID:63495
[1687268089745] [71452:11938902] [CTL] Shutting down the commissioner
[1687268089745] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268089745] [71452:11938902] [CTL] Shutting down the controller
[1687268089745] [71452:11938902] [IN] Expiring all sessions for fabric 0x1!!
[1687268089745] [71452:11938902] [FP] Forgetting fabric 0x1
[1687268089745] [71452:11938902] [TS] Pending Last Known Good Time: 2023-06-16T15:24:10
[1687268089746] [71452:11938902] [TS] Previous Last Known Good Time: 2023-06-16T15:24:10
[1687268089746] [71452:11938902] [TS] Reverted Last Known Good Time to previous value
[1687268089746] [71452:11938902] [CTL] Shutting down the commissioner
[1687268089746] [71452:11938902] [CTL] Stopping commissioning discovery over DNS-SD
[1687268089746] [71452:11938902] [CTL] Shutting down the controller
[1687268089746] [71452:11938902] [CTL] Shutting down the System State, this will teardown the CHIP Stack
[1687268089747] [71452:11938902] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet
[1687268089747] [71452:11938902] [BLE] CancelConnection
[1687268089747] [71452:11938902] [FP] Shutting down FabricTable
[1687268089747] [71452:11938902] [TS] Pending Last Known Good Time: 2023-06-16T15:24:10
[1687268089747] [71452:11938902] [TS] Previous Last Known Good Time: 2023-06-16T15:24:10
[1687268089747] [71452:11938902] [TS] Reverted Last Known Good Time to previous value
[1687268089747] [71452:11938902] [DL] Inet Layer shutdown
[1687268089747] [71452:11938902] [DL] BLE shutdown
[1687268089747] [71452:11938902] [DL] System Layer shutdown
[1687268089747] [71452:11938902] [TOO] Run command failure: src/controller/CHIPDeviceController.cpp:1455: CHIP Error 0x0000007E: Trying to add a NOC for a fabric that already exists

Children

No Data