Terminal ready
I: 82807 [EM]>>> [E:20082r S:49019 M:101059274] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 82819 [DMG]AccessControl: denied
I: 82824 [EM]<<< [E:20082r S:49019 M:138837793 (Ack:101059274)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 82837 [IN](S) Sending msg 138837793 on secure session with LSID: 49019
I: 83083 [EM]>>> [E:20082r S:49019 M:101059275 (Ack:138837793)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 105122 [EM]>>> [E:39165r S:0 M:22467518] (U) Msg RX from 0:05750D0F3DF6FC3E [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 105134 [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x20004ce0
I: 105141 [IN]CASE Server disabling CASE session setups
I: 105147 [EM]<<< [E:39165r S:0 M:20916448 (Ack:22467518)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 105159 [IN](U) Sending msg 20916448 to IP address 'UDP:[fd0a:abb0:77a8:c46b:754f:2b4a:7bdf:f959]:39594'
I: 105170 [SC]Received Sigma1 msg
I: 105178 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000078
I: 105390 [EM]<<< [E:39165r S:0 M:20916449 (Ack:22467518)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 105403 [IN](U) Sending msg 20916449 to IP address 'UDP:[fd0a:abb0:77a8:c46b:754f:2b4a:7bdf:f959]:39594'
I: 105414 [SC]Sent Sigma2 msg
I: 105587 [EM]>>> [E:39165r S:0 M:22467519 (Ack:20916449)] (U) Msg RX from 0:05750D0F3DF6FC3E [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 105600 [EM]<<< [E:39165r S:0 M:20916450 (Ack:22467519)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 105613 [IN](U) Sending msg 20916450 to IP address 'UDP:[fd0a:abb0:77a8:c46b:754f:2b4a:7bdf:f959]:39594'
I: 105623 [SC]Received Sigma3 msg
E: 105632 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 105831 [DL]Long dispatch time: 189 ms, for event type 2
I: 105931 [EM]<<< [E:39165r S:0 M:20916451 (Ack:22467519)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 105944 [IN](U) Sending msg 20916451 to IP address 'UDP:[fd0a:abb0:77a8:c46b:754f:2b4a:7bdf:f959]:39594'
I: 105956 [SC]SecureSession[0x20003320]: Moving from state 'kEstablishing' --> 'kActive'
D: 105964 [IN]SecureSession[0x20003320]: Activated - Type:2 LSID:49020
D: 105971 [IN]New secure session activated for device <000000000001B669, 1>, LSID:49020 PSID:38157!
I: 105980 [IN]CASE Session established to peer: <000000000001B669, 1>
I: 105986 [IN]CASE Server enabling CASE session setups
D: 105991 [IN]SecureSession[0x20003548]: Allocated Type:2 LSID:49021
E: 105997 [DL]Long dispatch time: 161 ms, for event type 2
I: 106015 [EM]>>> [E:39166r S:49020 M:215868018] (S) Msg RX from 1:000000000001B669 [D7CE] --- Type 0001:06 (IM:WriteRequest)
D: 106028 [IM]Received Write request
I: 106160 [EM]<<< [E:39166r S:49020 M:153229492 (Ack:215868018)] (S) Msg TX to 1:000000000001B669 [D7CE] --- Type 0001:07 (IM:WriteResponse)
I: 106173 [IN](S) Sending msg 153229492 on secure session with LSID: 49020
I: 106181 [EM]>>> [E:39165r S:0 M:22467520 (Ack:20916451)] (U) Msg RX from 0:05750D0F3DF6FC3E [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 106227 [EM]>>> [E:39166r S:49020 M:215868019 (Ack:153229492)] (S) Msg RX from 1:000000000001B669 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 113587 [EM]>>> [E:20083r S:49019 M:101059276] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 113600 [ZCL]Toggle ep1 on/off from state 0 to 1
I: 113604 [ZCL]On Command - OffWaitTime :  0
I: 113608 [ZCL]On/Toggle Command - Stop Timer
I: 113628 [ZCL]Cluster OnOff: attribute OnOff set to 1
I: Turn On Action has been initiated
I: Turn On Action has been completed
I: 113640 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: Level Action has been initiated
I: Setting brightness level to 1
I: Level Action has been completed
I: 113657 [EM]<<< [E:20083r S:49019 M:138837794 (Ack:101059276)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 113670 [IN](S) Sending msg 138837794 on secure session with LSID: 49019
I: 113678 [ZCL]Event: move from 1
I: 113681 [ZCL] to 254 
I: 113683 [ZCL](diff +1)
I: 113686 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: Level Action has been initiated
I: Setting brightness level to 254
I: Level Action has been completed
I: 113863 [EM]>>> [E:20083r S:49019 M:101059277 (Ack:138837794)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116368 [EM]>>> [E:20084r S:49019 M:101059278] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 116380 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 116386 [EM]<<< [E:20084r S:49019 M:138837795 (Ack:101059278)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 116399 [IN](S) Sending msg 138837795 on secure session with LSID: 49019
I: 116407 [ZCL]Event: move from 254
I: 116411 [ZCL] to 1 
I: 116413 [ZCL](diff -1)
I: 116415 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: Level Action has been initiated
I: Setting brightness level to 1
I: Level Action has been completed
I: 116431 [ZCL]Setting on/off to OFF due to level change
I: 116436 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 116459 [ZCL]Cluster OnOff: attribute OnOff set to 0
I: Turn Off Action has been initiated
I: Turn Off Action has been completed
I: 116470 [ZCL]Off completed. reset OnTime to  0
I: 116475 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: 116639 [EM]>>> [E:20084r S:49019 M:101059279 (Ack:138837795)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118688 [EM]>>> [E:20085r S:49019 M:101059280] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 118701 [ZCL]Toggle ep1 on/off from state 0 to 1
I: 118705 [ZCL]On Command - OffWaitTime :  0
I: 118709 [ZCL]On/Toggle Command - Stop Timer
I: 118732 [ZCL]Cluster OnOff: attribute OnOff set to 1
I: Turn On Action has been initiated
I: Turn On Action has been completed
I: 118744 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: 118751 [EM]<<< [E:20085r S:49019 M:138837796 (Ack:101059280)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 118764 [IN](S) Sending msg 138837796 on secure session with LSID: 49019
I: 118772 [ZCL]Event: move from 1
I: 118776 [ZCL] to 254 
I: 118778 [ZCL](diff +1)
I: 118781 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: Level Action has been initiated
I: Setting brightness level to 254
I: Level Action has been completed
I: 119041 [EM]>>> [E:20085r S:49019 M:101059281 (Ack:138837796)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 120841 [EM]>>> [E:20086r S:49019 M:101059282] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 120853 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 120859 [EM]<<< [E:20086r S:49019 M:138837797 (Ack:101059282)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 120872 [IN](S) Sending msg 138837797 on secure session with LSID: 49019
I: 120880 [ZCL]Event: move from 254
I: 120884 [ZCL] to 1 
I: 120886 [ZCL](diff -1)
I: 120888 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: Level Action has been initiated
I: Setting brightness level to 1
I: Level Action has been completed
I: 120904 [ZCL]Setting on/off to OFF due to level change
I: 120909 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 120928 [ZCL]Cluster OnOff: attribute OnOff set to 0
I: Turn Off Action has been initiated
I: Turn Off Action has been completed
I: 120940 [ZCL]Off completed. reset OnTime to  0
I: 120945 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: 121114 [EM]>>> [E:20086r S:49019 M:101059283 (Ack:138837797)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
D: 153991 [DL]OpenThread State Changed (Flags: 0x00000400)
I: 184878 [EM]>>> [E:20087r S:49019 M:101059284] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 184891 [ZCL]Toggle ep1 on/off from state 0 to 1
I: 184896 [ZCL]On Command - OffWaitTime :  0
I: 184900 [ZCL]On/Toggle Command - Stop Timer
I: 184922 [ZCL]Cluster OnOff: attribute OnOff set to 1
I: Turn On Action has been initiated
I: Turn On Action has been completed
I: 184934 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: 184941 [EM]<<< [E:20087r S:49019 M:138837798 (Ack:101059284)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 184954 [IN](S) Sending msg 138837798 on secure session with LSID: 49019
I: 184962 [ZCL]Event: move from 1
I: 184965 [ZCL] to 254 
I: 184967 [ZCL](diff +1)
I: 184969 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: Level Action has been initiated
I: Setting brightness level to 254
I: Level Action has been completed
I: 185150 [EM]>>> [E:20087r S:49019 M:101059285 (Ack:138837798)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 225361 [EM]>>> [E:20088r S:49019 M:101059286] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 225373 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 225379 [EM]<<< [E:20088r S:49019 M:138837799 (Ack:101059286)] (S) Msg TX to 1:0000000000000073 [D7CE] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 225392 [IN](S) Sending msg 138837799 on secure session with LSID: 49019
I: 225400 [ZCL]Event: move from 254
I: 225403 [ZCL] to 1 
I: 225405 [ZCL](diff -1)
I: 225407 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: Level Action has been initiated
I: Setting brightness level to 1
I: Level Action has been completed
I: 225423 [ZCL]Setting on/off to OFF due to level change
I: 225428 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 225448 [ZCL]Cluster OnOff: attribute OnOff set to 0
I: Turn Off Action has been initiated
I: Turn Off Action has been completed
I: 225460 [ZCL]Off completed. reset OnTime to  0
I: 225464 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: 225638 [EM]>>> [E:20088r S:49019 M:101059287 (Ack:138837799)] (S) Msg RX from 1:0000000000000073 [D7CE] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 286309 [DL]SRP Client was stopped, because current server is no longer detected.
D: 286317 [DL]OpenThread State Changed (Flags: 0x10000285)
D: 286322 [DL]   Device Role: LEADER
D: 286326 [DL]   Partition Id: 0x199DE521
D: 286330 [DL]   Thread Unicast Addresses:
D: 286334 [DL]        fd2d:b5c7:7093:108c:0:ff:fe00:fc00/64 valid
D: 286340 [DL]        fd2d:b5c7:7093:108c:0:ff:fe00:f000/64 valid rloc
D: 286346 [DL]        fd2d:b5c7:7093:108c:3593:54dd:2151:919e/64 valid
D: 286352 [DL]        fe80::4c6c:42f2:7759:638d/64 valid preferred
D: 286360 [DL]OpenThread State Changed (Flags: 0x00000002)
D: 286366 [DL]   Thread Unicast Addresses:
D: 286369 [DL]        fd2d:b5c7:7093:108c:0:ff:fe00:fc00/64 valid
D: 286375 [DL]        fd2d:b5c7:7093:108c:0:ff:fe00:f000/64 valid rloc
D: 286382 [DL]        fd2d:b5c7:7093:108c:3593:54dd:2151:919e/64 valid
D: 286388 [DL]        fe80::4c6c:42f2:7759:638d/64 valid preferred