uart:~$ [8D[J[00:05:57.660,881] <inf> chip: [EM]>>> [E:54512r S:36163 M:9888727] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:05:57.678,586] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:05:57.686,235] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:05:57.693,381] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:05:57.702,971] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:05:57.710,932] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:05:57.717,900] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:05:57.725,035] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:05:57.735,485] <inf> chip: [EM]<<< [E:54512r S:36163 M:117487279 (Ack:9888727)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:05:57.789,826] <inf> chip: [EM]??1 [E:54512r S:36163 M:117487279] (S) Msg Retransmission to 1:00000000086809DB scheduled for 354ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:05:57.848,371] <inf> chip: [EM]<<< [E:6904i S:36163 M:117487280] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:05:57.882,813] <inf> chip: [EM]??1 [E:6904i S:36163 M:117487280] (S) Msg Retransmission to 1:00000000086809DB scheduled for 352ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:05:57.936,033] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:05:57.945,391] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:05:57.952,176] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:05:57.958,972] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:05:57.966,912] <inf> chip: [EM]>>> [E:54512r S:36163 M:9888728 (Ack:117487279)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:05:57.986,719] <inf> chip: [EM]>>> [E:6904i S:36163 M:9888729 (Ack:117487280)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:05:58.004,661] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:05:58.013,887] <inf> chip: [EM]<<< [E:6904i S:36163 M:117487281 (Ack:9888729)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:05:58.073,030] <inf> chip: [EM]<<< [E:6905i S:36163 M:117487282] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:05:58.107,465] <inf> chip: [EM]??1 [E:6905i S:36163 M:117487282] (S) Msg Retransmission to 1:00000000086809DB scheduled for 384ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:05:58.161,329] <inf> chip: [EM]>>> [E:6905i S:36163 M:9888730 (Ack:117487282)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:05:58.179,276] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:05:58.188,086] <inf> chip: [EM]<<< [E:6905i S:36163 M:117487283 (Ack:9888730)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:03.693,451] <inf> chip: [EM]>>> [E:54513r S:36163 M:9888731] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:03.711,154] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:03.720,067] <inf> chip: [EM]<<< [E:54513r S:36163 M:117487284 (Ack:9888731)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:03.756,772] <inf> chip: [EM]??1 [E:54513r S:36163 M:117487284] (S) Msg Retransmission to 1:00000000086809DB scheduled for 357ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:03.810,168] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:03.819,353] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:03.826,139] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:03.832,761] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:03.839,570] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:03.847,757] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:03.857,734] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:03.865,700] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:03.872,756] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:03.879,830] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:03.887,617] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:03.902,703] <inf> chip: [EM]<<< [E:6906i S:36163 M:117487285] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:03.937,147] <inf> chip: [EM]??1 [E:6906i S:36163 M:117487285] (S) Msg Retransmission to 1:00000000086809DB scheduled for 359ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:03.990,770] <inf> chip: [EM]>>> [E:54513r S:36163 M:9888732 (Ack:117487284)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:04.010,979] <inf> chip: [EM]>>> [E:6906i S:36163 M:9888733 (Ack:117487285)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:04.028,928] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:04.037,739] <inf> chip: [EM]<<< [E:6906i S:36163 M:117487286 (Ack:9888733)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:11.236,183] <inf> chip: [EM]>>> [E:54514r S:36163 M:9888734] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:11.253,892] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:11.261,540] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:11.268,692] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:11.278,239] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:11.286,205] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:11.293,176] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:11.300,313] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:11.310,768] <inf> chip: [EM]<<< [E:54514r S:36163 M:117487287 (Ack:9888734)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:11.347,482] <inf> chip: [EM]??1 [E:54514r S:36163 M:117487287] (S) Msg Retransmission to 1:00000000086809DB scheduled for 409ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:11.405,966] <inf> chip: [EM]<<< [E:6907i S:36163 M:117487288] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:11.440,434] <inf> chip: [EM]??1 [E:6907i S:36163 M:117487288] (S) Msg Retransmission to 1:00000000086809DB scheduled for 383ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:11.493,716] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:11.503,077] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:11.509,866] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:11.516,665] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:11.524,352] <inf> chip: [EM]>>> [E:54514r S:36163 M:9888735 (Ack:117487287)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:11.544,162] <inf> chip: [EM]>>> [E:6907i S:36163 M:9888736 (Ack:117487288)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:11.562,116] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:11.570,935] <inf> chip: [EM]<<< [E:6907i S:36163 M:117487289 (Ack:9888736)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:11.630,111] <inf> chip: [EM]<<< [E:6908i S:36163 M:117487290] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:11.664,694] <inf> chip: [EM]??1 [E:6908i S:36163 M:117487290] (S) Msg Retransmission to 1:00000000086809DB scheduled for 365ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:11.718,965] <inf> chip: [EM]>>> [E:6908i S:36163 M:9888737 (Ack:117487290)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:11.736,916] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:11.745,729] <inf> chip: [EM]<<< [E:6908i S:36163 M:117487291 (Ack:9888737)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:16.033,197] <err> chip: [DIS]Timeout waiting for mDNS resolution.
uart:~$ [8D[J[00:06:17.000,305] <inf> chip: [EM]>>> [E:54515r S:36163 M:9888738] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:17.018,015] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:17.026,929] <inf> chip: [EM]<<< [E:54515r S:36163 M:117487292 (Ack:9888738)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:17.063,660] <inf> chip: [EM]??1 [E:54515r S:36163 M:117487292] (S) Msg Retransmission to 1:00000000086809DB scheduled for 387ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:17.117,070] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:17.126,255] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:17.133,042] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:17.139,665] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:17.146,474] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:17.154,659] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:17.164,628] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:17.172,592] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:17.179,648] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:17.186,724] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:17.194,510] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:17.210,011] <inf> chip: [EM]<<< [E:6909i S:36163 M:117487293] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:17.244,466] <inf> chip: [EM]??1 [E:6909i S:36163 M:117487293] (S) Msg Retransmission to 1:00000000086809DB scheduled for 367ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:17.298,072] <inf> chip: [EM]>>> [E:54515r S:36163 M:9888739 (Ack:117487292)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:17.317,883] <inf> chip: [EM]>>> [E:6909i S:36163 M:9888740 (Ack:117487293)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:17.335,839] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:17.344,654] <inf> chip: [EM]<<< [E:6909i S:36163 M:117487294 (Ack:9888740)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:20.114,103] <inf> chip: [EM]>>> [E:54516r S:36163 M:9888741] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:20.131,809] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:20.139,458] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:20.146,607] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:20.156,203] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:20.164,175] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:20.171,145] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:20.178,287] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:20.188,738] <inf> chip: [EM]<<< [E:54516r S:36163 M:117487295 (Ack:9888741)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:20.225,442] <inf> chip: [EM]??1 [E:54516r S:36163 M:117487295] (S) Msg Retransmission to 1:00000000086809DB scheduled for 377ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:20.283,459] <inf> chip: [EM]<<< [E:6910i S:36163 M:117487296] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:20.317,909] <inf> chip: [EM]??1 [E:6910i S:36163 M:117487296] (S) Msg Retransmission to 1:00000000086809DB scheduled for 379ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:20.371,133] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:20.380,489] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:20.387,274] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:20.394,070] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:20.401,754] <inf> chip: [EM]>>> [E:54516r S:36163 M:9888742 (Ack:117487295)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:20.421,971] <inf> chip: [EM]>>> [E:6910i S:36163 M:9888743 (Ack:117487296)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:20.439,922] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:20.448,739] <inf> chip: [EM]<<< [E:6910i S:36163 M:117487297 (Ack:9888743)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:20.507,893] <inf> chip: [EM]<<< [E:6911i S:36163 M:117487298] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:20.542,335] <inf> chip: [EM]??1 [E:6911i S:36163 M:117487298] (S) Msg Retransmission to 1:00000000086809DB scheduled for 357ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:20.596,272] <inf> chip: [EM]>>> [E:6911i S:36163 M:9888744 (Ack:117487298)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:20.614,212] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:20.623,020] <inf> chip: [EM]<<< [E:6911i S:36163 M:117487299 (Ack:9888744)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:23.259,695] <inf> chip: [EM]>>> [E:54517r S:36163 M:9888745] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:23.277,399] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:23.286,314] <inf> chip: [EM]<<< [E:54517r S:36163 M:117487300 (Ack:9888745)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:23.323,026] <inf> chip: [EM]??1 [E:54517r S:36163 M:117487300] (S) Msg Retransmission to 1:00000000086809DB scheduled for 344ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:23.376,373] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:23.385,558] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:23.392,342] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:23.398,968] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:23.405,778] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:23.413,964] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:23.423,931] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:23.431,903] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:23.438,961] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:23.446,063] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:23.453,853] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:23.468,948] <inf> chip: [EM]<<< [E:6912i S:36163 M:117487301] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:23.503,411] <inf> chip: [EM]??1 [E:6912i S:36163 M:117487301] (S) Msg Retransmission to 1:00000000086809DB scheduled for 350ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:23.557,125] <inf> chip: [EM]>>> [E:54517r S:36163 M:9888746 (Ack:117487300)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:23.577,346] <inf> chip: [EM]>>> [E:6912i S:36163 M:9888747 (Ack:117487301)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:23.595,300] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:23.604,112] <inf> chip: [EM]<<< [E:6912i S:36163 M:117487302 (Ack:9888747)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:30.032,074] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 45000 ms
uart:~$ [8D[J[00:06:30.043,671] <err> chip: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: operational discovery failed: 32
uart:~$ [8D[J[00:06:30.054,989] <err> chip: [DMG]Failed to establish CASE for subscription-resumption with error '32'
uart:~$ [8D[J[00:06:30.065,990] <inf> chip: [IM]Schedule subscription resumption when failing to establish session, Retries: 2
uart:~$ [8D[J[00:06:32.128,973] <inf> chip: [EM]>>> [E:54518r S:36163 M:9888748] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:32.146,680] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:32.154,328] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:32.161,475] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:32.171,062] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:32.197,118] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:32.221,749] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:32.229,572] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:32.240,024] <inf> chip: [EM]<<< [E:54518r S:36163 M:117487303 (Ack:9888748)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:32.276,731] <inf> chip: [EM]??1 [E:54518r S:36163 M:117487303] (S) Msg Retransmission to 1:00000000086809DB scheduled for 365ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:32.335,163] <inf> chip: [EM]<<< [E:6913i S:36163 M:117487304] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:32.369,602] <inf> chip: [EM]??1 [E:6913i S:36163 M:117487304] (S) Msg Retransmission to 1:00000000086809DB scheduled for 356ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:32.422,869] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:32.432,222] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:32.439,007] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:32.445,803] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:32.455,435] <inf> chip: [EM]>>> [E:54518r S:36163 M:9888749 (Ack:117487303)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:32.475,240] <inf> chip: [EM]>>> [E:6913i S:36163 M:9888750 (Ack:117487304)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:32.493,196] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:32.502,014] <inf> chip: [EM]<<< [E:6913i S:36163 M:117487305 (Ack:9888750)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:32.561,179] <inf> chip: [EM]<<< [E:6914i S:36163 M:117487306] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:32.595,627] <inf> chip: [EM]??1 [E:6914i S:36163 M:117487306] (S) Msg Retransmission to 1:00000000086809DB scheduled for 361ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:32.649,901] <inf> chip: [EM]>>> [E:6914i S:36163 M:9888751 (Ack:117487306)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:32.667,855] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:32.676,667] <inf> chip: [EM]<<< [E:6914i S:36163 M:117487307 (Ack:9888751)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:34.481,240] <inf> chip: [EM]>>> [E:54519r S:36163 M:9888752] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:34.498,941] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:34.507,853] <inf> chip: [EM]<<< [E:54519r S:36163 M:117487308 (Ack:9888752)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:34.544,557] <inf> chip: [EM]??1 [E:54519r S:36163 M:117487308] (S) Msg Retransmission to 1:00000000086809DB scheduled for 383ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:34.597,872] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:34.607,056] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:34.613,843] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:34.620,468] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:34.627,279] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:34.652,992] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:34.663,635] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:34.671,603] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:34.678,661] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:34.685,761] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:34.693,552] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:34.709,039] <inf> chip: [EM]<<< [E:6915i S:36163 M:117487309] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:34.743,479] <inf> chip: [EM]??1 [E:6915i S:36163 M:117487309] (S) Msg Retransmission to 1:00000000086809DB scheduled for 390ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:34.797,183] <inf> chip: [EM]>>> [E:54519r S:36163 M:9888753 (Ack:117487308)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:34.816,995] <inf> chip: [EM]>>> [E:6915i S:36163 M:9888754 (Ack:117487309)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:34.834,945] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:34.843,756] <inf> chip: [EM]<<< [E:6915i S:36163 M:117487310 (Ack:9888754)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:37.112,588] <inf> chip: [EM]>>> [E:54520r S:36163 M:9888755] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:37.130,299] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:37.137,950] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:37.145,101] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:37.154,688] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:37.162,657] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:37.169,631] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:37.176,774] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:37.187,229] <inf> chip: [EM]<<< [E:54520r S:36163 M:117487311 (Ack:9888755)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:37.223,944] <inf> chip: [EM]??1 [E:54520r S:36163 M:117487311] (S) Msg Retransmission to 1:00000000086809DB scheduled for 338ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:37.263,600] <inf> chip: [EM]<<< [E:6916i S:36163 M:117487312] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:37.298,062] <inf> chip: [EM]??1 [E:6916i S:36163 M:117487312] (S) Msg Retransmission to 1:00000000086809DB scheduled for 350ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:37.332,998] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:37.359,895] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:37.368,206] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:37.375,001] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:37.382,688] <inf> chip: [EM]>>> [E:54520r S:36163 M:9888756 (Ack:117487311)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:37.402,912] <inf> chip: [EM]>>> [E:6916i S:36163 M:9888757 (Ack:117487312)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:37.420,873] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:37.429,694] <inf> chip: [EM]<<< [E:6916i S:36163 M:117487313 (Ack:9888757)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:37.488,886] <inf> chip: [EM]<<< [E:6917i S:36163 M:117487314] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:37.523,351] <inf> chip: [EM]??1 [E:6917i S:36163 M:117487314] (S) Msg Retransmission to 1:00000000086809DB scheduled for 367ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:37.577,332] <inf> chip: [EM]>>> [E:6917i S:36163 M:9888758 (Ack:117487314)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:37.595,288] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:37.604,098] <inf> chip: [EM]<<< [E:6917i S:36163 M:117487315 (Ack:9888758)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:39.147,118] <inf> chip: [EM]>>> [E:54521r S:36163 M:9888759] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:39.164,828] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:39.173,744] <inf> chip: [EM]<<< [E:54521r S:36163 M:117487316 (Ack:9888759)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:39.210,458] <inf> chip: [EM]??1 [E:54521r S:36163 M:117487316] (S) Msg Retransmission to 1:00000000086809DB scheduled for 390ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:39.263,892] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:39.273,074] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:39.279,860] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:39.286,482] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:39.293,291] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:39.301,474] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:39.311,434] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:39.319,400] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:39.326,459] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:39.333,557] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:39.341,345] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:39.356,420] <inf> chip: [EM]<<< [E:6918i S:36163 M:117487317] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:39.390,861] <inf> chip: [EM]??1 [E:6918i S:36163 M:117487317] (S) Msg Retransmission to 1:00000000086809DB scheduled for 339ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:39.426,513] <inf> chip: [EM]>>> [E:54521r S:36163 M:9888760 (Ack:117487316)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:39.464,730] <inf> chip: [EM]>>> [E:6918i S:36163 M:9888761 (Ack:117487317)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:39.482,679] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:39.491,488] <inf> chip: [EM]<<< [E:6918i S:36163 M:117487318 (Ack:9888761)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:45.581,952] <inf> chip: [EM]>>> [E:54522r S:36163 M:9888762] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:45.599,660] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:45.607,313] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:45.614,460] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:45.624,055] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:45.632,018] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:45.638,987] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:45.646,122] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:45.656,576] <inf> chip: [EM]<<< [E:54522r S:36163 M:117487319 (Ack:9888762)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:45.693,278] <inf> chip: [EM]??1 [E:54522r S:36163 M:117487319] (S) Msg Retransmission to 1:00000000086809DB scheduled for 405ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:45.751,759] <inf> chip: [EM]<<< [E:6919i S:36163 M:117487320] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:45.786,211] <inf> chip: [EM]??1 [E:6919i S:36163 M:117487320] (S) Msg Retransmission to 1:00000000086809DB scheduled for 385ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:45.839,463] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:45.848,820] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:45.855,607] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:45.862,403] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:45.870,088] <inf> chip: [EM]>>> [E:54522r S:36163 M:9888763 (Ack:117487319)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:45.889,897] <inf> chip: [EM]>>> [E:6919i S:36163 M:9888764 (Ack:117487320)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:45.907,847] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:45.916,669] <inf> chip: [EM]<<< [E:6919i S:36163 M:117487321 (Ack:9888764)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:45.976,262] <inf> chip: [EM]<<< [E:6920i S:36163 M:117487322] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:46.010,721] <inf> chip: [EM]??1 [E:6920i S:36163 M:117487322] (S) Msg Retransmission to 1:00000000086809DB scheduled for 351ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:46.064,629] <inf> chip: [EM]>>> [E:6920i S:36163 M:9888765 (Ack:117487322)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:46.082,581] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:46.091,392] <inf> chip: [EM]<<< [E:6920i S:36163 M:117487323 (Ack:9888765)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:49.034,283] <inf> chip: [EM]>>> [E:54523r S:36163 M:9888766] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:49.051,992] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:49.060,906] <inf> chip: [EM]<<< [E:54523r S:36163 M:117487324 (Ack:9888766)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:49.097,621] <inf> chip: [EM]??1 [E:54523r S:36163 M:117487324] (S) Msg Retransmission to 1:00000000086809DB scheduled for 403ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:49.151,062] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:49.160,252] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:49.167,040] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:49.173,666] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:49.180,475] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:49.188,662] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:49.198,635] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:49.206,602] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:49.213,660] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:49.220,755] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:49.228,547] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:49.244,020] <inf> chip: [EM]<<< [E:6921i S:36163 M:117487325] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:49.278,476] <inf> chip: [EM]??1 [E:6921i S:36163 M:117487325] (S) Msg Retransmission to 1:00000000086809DB scheduled for 337ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:49.332,169] <inf> chip: [EM]>>> [E:54523r S:36163 M:9888767 (Ack:117487324)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:49.351,982] <inf> chip: [EM]>>> [E:6921i S:36163 M:9888768 (Ack:117487325)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:49.369,937] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:49.378,750] <inf> chip: [EM]<<< [E:6921i S:36163 M:117487326 (Ack:9888768)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:51.055,491] <inf> chip: [EM]>>> [E:54524r S:36163 M:9888769] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:51.073,197] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:51.080,845] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:51.087,997] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:51.097,581] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:51.105,544] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:51.112,516] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:51.119,662] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:51.130,119] <inf> chip: [EM]<<< [E:54524r S:36163 M:117487327 (Ack:9888769)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:51.166,831] <inf> chip: [EM]??1 [E:54524r S:36163 M:117487327] (S) Msg Retransmission to 1:00000000086809DB scheduled for 381ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:51.224,780] <inf> chip: [EM]<<< [E:6922i S:36163 M:117487328] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:51.259,229] <inf> chip: [EM]??1 [E:6922i S:36163 M:117487328] (S) Msg Retransmission to 1:00000000086809DB scheduled for 378ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:51.312,496] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:51.321,853] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:51.328,640] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:51.335,435] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:51.343,119] <inf> chip: [EM]>>> [E:54524r S:36163 M:9888770 (Ack:117487327)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:51.363,334] <inf> chip: [EM]>>> [E:6922i S:36163 M:9888771 (Ack:117487328)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:51.381,278] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:51.390,096] <inf> chip: [EM]<<< [E:6922i S:36163 M:117487329 (Ack:9888771)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:51.467,578] <inf> chip: [EM]<<< [E:6923i S:36163 M:117487330] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:51.502,029] <inf> chip: [EM]??1 [E:6923i S:36163 M:117487330] (S) Msg Retransmission to 1:00000000086809DB scheduled for 380ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:51.555,915] <inf> chip: [EM]>>> [E:6923i S:36163 M:9888772 (Ack:117487330)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:51.573,867] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:51.582,678] <inf> chip: [EM]<<< [E:6923i S:36163 M:117487331 (Ack:9888772)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:53.284,063] <inf> chip: [EM]>>> [E:54525r S:36163 M:9888773] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:53.301,772] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:53.310,691] <inf> chip: [EM]<<< [E:54525r S:36163 M:117487332 (Ack:9888773)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:53.347,407] <inf> chip: [EM]??1 [E:54525r S:36163 M:117487332] (S) Msg Retransmission to 1:00000000086809DB scheduled for 410ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:53.400,790] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:53.409,973] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:53.416,760] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:53.423,383] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:53.430,194] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:53.438,382] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:53.448,364] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:53.456,329] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:53.463,385] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:53.470,460] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:53.478,250] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:53.493,333] <inf> chip: [EM]<<< [E:6924i S:36163 M:117487333] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:53.527,788] <inf> chip: [EM]??1 [E:6924i S:36163 M:117487333] (S) Msg Retransmission to 1:00000000086809DB scheduled for 412ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:53.563,474] <inf> chip: [EM]>>> [E:54525r S:36163 M:9888774 (Ack:117487332)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:53.607,101] <inf> chip: [EM]>>> [E:6924i S:36163 M:9888775 (Ack:117487333)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:53.625,056] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:53.633,866] <inf> chip: [EM]<<< [E:6924i S:36163 M:117487334 (Ack:9888775)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:56.583,008] <inf> chip: [EM]>>> [E:54526r S:36163 M:9888776] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:56.600,717] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:06:56.608,368] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:06:56.615,519] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:06:56.625,120] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:06:56.633,088] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:06:56.640,060] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:06:56.647,206] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:56.658,074] <inf> chip: [EM]<<< [E:54526r S:36163 M:117487335 (Ack:9888776)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:56.694,794] <inf> chip: [EM]??1 [E:54526r S:36163 M:117487335] (S) Msg Retransmission to 1:00000000086809DB scheduled for 362ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:56.734,487] <inf> chip: [EM]<<< [E:6925i S:36163 M:117487336] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:06:56.804,911] <inf> chip: [EM]??1 [E:6925i S:36163 M:117487336] (S) Msg Retransmission to 1:00000000086809DB scheduled for 396ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:56.858,716] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:56.868,070] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:56.874,856] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:06:56.881,653] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:56.889,338] <inf> chip: [EM]>>> [E:54526r S:36163 M:9888777 (Ack:117487335)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:56.909,508] <inf> chip: [EM]>>> [E:6925i S:36163 M:9888778 (Ack:117487336)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:56.927,463] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:56.936,285] <inf> chip: [EM]<<< [E:6925i S:36163 M:117487337 (Ack:9888778)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:56.995,871] <inf> chip: [EM]<<< [E:6926i S:36163 M:117487338] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:06:57.030,318] <inf> chip: [EM]??1 [E:6926i S:36163 M:117487338] (S) Msg Retransmission to 1:00000000086809DB scheduled for 347ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:57.084,209] <inf> chip: [EM]>>> [E:6926i S:36163 M:9888779 (Ack:117487338)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:57.102,156] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:57.110,967] <inf> chip: [EM]<<< [E:6926i S:36163 M:117487339 (Ack:9888779)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:59.401,553] <inf> chip: [EM]>>> [E:54527r S:36163 M:9888780] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:06:59.419,266] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:59.428,592] <inf> chip: [EM]<<< [E:54527r S:36163 M:117487340 (Ack:9888780)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:06:59.465,313] <inf> chip: [EM]??1 [E:54527r S:36163 M:117487340] (S) Msg Retransmission to 1:00000000086809DB scheduled for 350ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:59.518,745] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:06:59.527,928] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:06:59.534,716] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:06:59.541,341] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:06:59.548,153] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:06:59.556,338] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:06:59.566,325] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:06:59.574,291] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:06:59.581,349] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:06:59.588,451] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:06:59.596,243] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:06:59.611,329] <inf> chip: [EM]<<< [E:6927i S:36163 M:117487341] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:06:59.645,776] <inf> chip: [EM]??1 [E:6927i S:36163 M:117487341] (S) Msg Retransmission to 1:00000000086809DB scheduled for 362ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:06:59.699,479] <inf> chip: [EM]>>> [E:54527r S:36163 M:9888781 (Ack:117487340)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:06:59.719,291] <inf> chip: [EM]>>> [E:6927i S:36163 M:9888782 (Ack:117487341)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:06:59.737,243] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:06:59.746,055] <inf> chip: [EM]<<< [E:6927i S:36163 M:117487342 (Ack:9888782)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:01.502,838] <inf> chip: [EM]>>> [E:54528r S:36163 M:9888783] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:01.520,548] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:01.528,195] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:01.535,346] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:01.544,918] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:01.552,886] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:01.559,855] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:01.567,002] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:01.577,457] <inf> chip: [EM]<<< [E:54528r S:36163 M:117487343 (Ack:9888783)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:01.614,164] <inf> chip: [EM]??1 [E:54528r S:36163 M:117487343] (S) Msg Retransmission to 1:00000000086809DB scheduled for 358ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:01.672,219] <inf> chip: [EM]<<< [E:6928i S:36163 M:117487344] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:01.706,671] <inf> chip: [EM]??1 [E:6928i S:36163 M:117487344] (S) Msg Retransmission to 1:00000000086809DB scheduled for 331ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:01.741,602] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:01.768,471] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:01.776,033] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:01.782,827] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:01.790,441] <inf> chip: [EM]>>> [E:54528r S:36163 M:9888784 (Ack:117487343)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:01.810,638] <inf> chip: [EM]>>> [E:6928i S:36163 M:9888785 (Ack:117487344)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:01.828,591] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:01.837,410] <inf> chip: [EM]<<< [E:6928i S:36163 M:117487345 (Ack:9888785)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:01.932,834] <inf> chip: [EM]<<< [E:6929i S:36163 M:117487346] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:01.967,289] <inf> chip: [EM]??1 [E:6929i S:36163 M:117487346] (S) Msg Retransmission to 1:00000000086809DB scheduled for 391ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:02.021,226] <inf> chip: [EM]>>> [E:6929i S:36163 M:9888786 (Ack:117487346)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:02.039,179] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:02.048,397] <inf> chip: [EM]<<< [E:6929i S:36163 M:117487347 (Ack:9888786)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:06.425,440] <inf> chip: [EM]>>> [E:54529r S:36163 M:9888787] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:06.443,148] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:06.452,067] <inf> chip: [EM]<<< [E:54529r S:36163 M:117487348 (Ack:9888787)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:06.507,284] <inf> chip: [EM]??1 [E:54529r S:36163 M:117487348] (S) Msg Retransmission to 1:00000000086809DB scheduled for 374ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:06.560,736] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:06.569,924] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:06.576,715] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:06.583,344] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:06.590,155] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:06.598,344] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:06.608,362] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:06.616,330] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:06.623,387] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:06.630,480] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:06.638,272] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:06.653,671] <inf> chip: [EM]<<< [E:6930i S:36163 M:117487349] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:06.688,947] <inf> chip: [EM]??1 [E:6930i S:36163 M:117487349] (S) Msg Retransmission to 1:00000000086809DB scheduled for 330ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:06.813,898] <inf> chip: [EM]>>> [E:54529r S:36163 M:9888788 (Ack:117487348)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:06.833,708] <inf> chip: [EM]>>> [E:6930i S:36163 M:9888789 (Ack:117487349)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:06.851,658] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:06.860,468] <inf> chip: [EM]<<< [E:6930i S:36163 M:117487350 (Ack:9888789)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:09.119,149] <inf> chip: [EM]>>> [E:54530r S:36163 M:9888790] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:09.136,860] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:09.144,513] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:09.151,664] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:09.161,242] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:09.169,207] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:09.176,182] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:09.183,328] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:09.194,194] <inf> chip: [EM]<<< [E:54530r S:36163 M:117487351 (Ack:9888790)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:09.230,918] <inf> chip: [EM]??1 [E:54530r S:36163 M:117487351] (S) Msg Retransmission to 1:00000000086809DB scheduled for 375ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:09.270,583] <inf> chip: [EM]<<< [E:6931i S:36163 M:117487352] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:09.305,046] <inf> chip: [EM]??1 [E:6931i S:36163 M:117487352] (S) Msg Retransmission to 1:00000000086809DB scheduled for 381ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:09.359,131] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:09.368,484] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:09.375,270] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:09.382,067] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:09.389,750] <inf> chip: [EM]>>> [E:54530r S:36163 M:9888791 (Ack:117487351)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:09.409,553] <inf> chip: [EM]>>> [E:6931i S:36163 M:9888792 (Ack:117487352)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:09.427,494] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:09.436,311] <inf> chip: [EM]<<< [E:6931i S:36163 M:117487353 (Ack:9888792)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:09.478,431] <inf> chip: [EM]<<< [E:6932i S:36163 M:117487354] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:09.530,228] <inf> chip: [EM]??1 [E:6932i S:36163 M:117487354] (S) Msg Retransmission to 1:00000000086809DB scheduled for 362ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:09.620,423] <inf> chip: [EM]>>> [E:6932i S:36163 M:9888793 (Ack:117487354)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:09.638,370] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:09.647,179] <inf> chip: [EM]<<< [E:6932i S:36163 M:117487355 (Ack:9888793)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:12.003,811] <inf> chip: [EM]>>> [E:54531r S:36163 M:9888794] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:12.021,518] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:12.030,846] <inf> chip: [EM]<<< [E:54531r S:36163 M:117487356 (Ack:9888794)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:12.067,557] <inf> chip: [EM]??1 [E:54531r S:36163 M:117487356] (S) Msg Retransmission to 1:00000000086809DB scheduled for 361ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:12.120,869] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:12.130,050] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:12.136,837] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:12.143,461] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:12.150,269] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:12.158,453] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:12.168,421] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:12.176,390] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:12.183,446] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:12.190,545] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:12.198,334] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:12.213,414] <inf> chip: [EM]<<< [E:6933i S:36163 M:117487357] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:12.247,856] <inf> chip: [EM]??1 [E:6933i S:36163 M:117487357] (S) Msg Retransmission to 1:00000000086809DB scheduled for 351ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:12.301,473] <inf> chip: [EM]>>> [E:54531r S:36163 M:9888795 (Ack:117487356)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:12.321,271] <inf> chip: [EM]>>> [E:6933i S:36163 M:9888796 (Ack:117487357)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:12.339,218] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:12.348,439] <inf> chip: [EM]<<< [E:6933i S:36163 M:117487358 (Ack:9888796)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:15.777,265] <inf> chip: [EM]>>> [E:54532r S:36163 M:9888797] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:15.794,962] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:15.802,607] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:15.809,751] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:15.819,298] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:15.827,264] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:15.834,230] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:15.841,370] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:15.851,817] <inf> chip: [EM]<<< [E:54532r S:36163 M:117487359 (Ack:9888797)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:15.888,508] <inf> chip: [EM]??1 [E:54532r S:36163 M:117487359] (S) Msg Retransmission to 1:00000000086809DB scheduled for 361ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:15.946,535] <inf> chip: [EM]<<< [E:6934i S:36163 M:117487360] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:15.999,410] <inf> chip: [EM]??1 [E:6934i S:36163 M:117487360] (S) Msg Retransmission to 1:00000000086809DB scheduled for 397ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:16.052,671] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:16.062,026] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:16.068,810] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:16.075,605] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:16.083,698] <inf> chip: [EM]>>> [E:54532r S:36163 M:9888798 (Ack:117487359)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:16.103,499] <inf> chip: [EM]>>> [E:6934i S:36163 M:9888799 (Ack:117487360)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:16.121,440] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:16.130,256] <inf> chip: [EM]<<< [E:6934i S:36163 M:117487361 (Ack:9888799)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:16.189,404] <inf> chip: [EM]<<< [E:6935i S:36163 M:117487362] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:16.223,840] <inf> chip: [EM]??1 [E:6935i S:36163 M:117487362] (S) Msg Retransmission to 1:00000000086809DB scheduled for 382ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:16.277,759] <inf> chip: [EM]>>> [E:6935i S:36163 M:9888800 (Ack:117487362)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:16.295,706] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:16.304,922] <inf> chip: [EM]<<< [E:6935i S:36163 M:117487363 (Ack:9888800)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:18.443,354] <inf> chip: [EM]>>> [E:54533r S:36163 M:9888801] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:18.461,052] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:18.469,963] <inf> chip: [EM]<<< [E:54533r S:36163 M:117487364 (Ack:9888801)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:18.506,656] <inf> chip: [EM]??1 [E:54533r S:36163 M:117487364] (S) Msg Retransmission to 1:00000000086809DB scheduled for 341ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:18.578,188] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:18.587,366] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:18.594,149] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:18.600,770] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:18.607,572] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:18.615,750] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:18.625,725] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:18.633,682] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:18.640,733] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:18.647,805] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:18.655,594] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:18.671,074] <inf> chip: [EM]<<< [E:6936i S:36163 M:117487365] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:18.705,495] <inf> chip: [EM]??1 [E:6936i S:36163 M:117487365] (S) Msg Retransmission to 1:00000000086809DB scheduled for 339ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:18.740,747] <inf> chip: [EM]>>> [E:54533r S:36163 M:9888802 (Ack:117487364)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:18.781,332] <inf> chip: [EM]>>> [E:6936i S:36163 M:9888803 (Ack:117487365)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:18.799,269] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:18.808,075] <inf> chip: [EM]<<< [E:6936i S:36163 M:117487366 (Ack:9888803)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:20.889,993] <inf> chip: [EM]>>> [E:54534r S:36163 M:9888804] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:20.907,689] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:20.915,336] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:20.922,484] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:20.932,054] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:20.940,017] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:20.946,985] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:20.954,128] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:20.964,986] <inf> chip: [EM]<<< [E:54534r S:36163 M:117487367 (Ack:9888804)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:21.001,685] <inf> chip: [EM]??1 [E:54534r S:36163 M:117487367] (S) Msg Retransmission to 1:00000000086809DB scheduled for 371ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:21.059,665] <inf> chip: [EM]<<< [E:6937i S:36163 M:117487368] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:21.094,097] <inf> chip: [EM]??1 [E:6937i S:36163 M:117487368] (S) Msg Retransmission to 1:00000000086809DB scheduled for 396ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:21.147,266] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:21.156,620] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:21.163,400] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:21.170,191] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:21.177,871] <inf> chip: [EM]>>> [E:54534r S:36163 M:9888805 (Ack:117487367)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:21.197,663] <inf> chip: [EM]>>> [E:6937i S:36163 M:9888806 (Ack:117487368)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:21.215,605] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:21.224,417] <inf> chip: [EM]<<< [E:6937i S:36163 M:117487369 (Ack:9888806)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:21.283,950] <inf> chip: [EM]<<< [E:6938i S:36163 M:117487370] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:21.318,376] <inf> chip: [EM]??1 [E:6938i S:36163 M:117487370] (S) Msg Retransmission to 1:00000000086809DB scheduled for 404ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:21.419,217] <inf> chip: [EM]>>> [E:6938i S:36163 M:9888807 (Ack:117487370)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:21.455,428] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:21.464,244] <inf> chip: [EM]<<< [E:6938i S:36163 M:117487371 (Ack:9888807)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:23.000,575] <inf> chip: [EM]>>> [E:54535r S:36163 M:9888808] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:23.018,269] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:23.027,590] <inf> chip: [EM]<<< [E:54535r S:36163 M:117487372 (Ack:9888808)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:23.064,276] <inf> chip: [EM]??1 [E:54535r S:36163 M:117487372] (S) Msg Retransmission to 1:00000000086809DB scheduled for 333ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:23.117,591] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:23.126,772] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:23.133,553] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:23.140,171] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:23.146,972] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:23.155,150] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:23.165,106] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:23.173,072] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:23.180,122] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:23.187,190] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:23.194,970] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:23.210,036] <inf> chip: [EM]<<< [E:6939i S:36163 M:117487373] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:23.244,457] <inf> chip: [EM]??1 [E:6939i S:36163 M:117487373] (S) Msg Retransmission to 1:00000000086809DB scheduled for 400ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:23.316,368] <inf> chip: [EM]>>> [E:54535r S:36163 M:9888809 (Ack:117487372)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:23.336,162] <inf> chip: [EM]>>> [E:6939i S:36163 M:9888810 (Ack:117487373)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:23.354,101] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:23.363,313] <inf> chip: [EM]<<< [E:6939i S:36163 M:117487374 (Ack:9888810)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:30.066,050] <dbg> chip: LogV: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: State change 1 --> 2
uart:~$ [8D[J[00:07:30.090,226] <inf> chip: [DIS]Lookup started for CCC10070EA402D1F-00000000BCDA9022
uart:~$ [8D[J[00:07:30.117,535] <inf> chip: [IM]Skip resuming live subscriptionId 4047205492
uart:~$ [8D[J[00:07:30.276,636] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 200 ms
uart:~$ [8D[J[00:07:33.612,296] <inf> chip: [EM]>>> [E:54536r S:36163 M:9888811] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:33.630,000] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:33.637,645] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:33.644,791] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:33.654,358] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:33.662,325] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:33.669,291] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:33.676,425] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:33.686,874] <inf> chip: [EM]<<< [E:54536r S:36163 M:117487375 (Ack:9888811)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:33.723,571] <inf> chip: [EM]??1 [E:54536r S:36163 M:117487375] (S) Msg Retransmission to 1:00000000086809DB scheduled for 402ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:33.781,499] <inf> chip: [EM]<<< [E:6940i S:36163 M:117487376] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:33.815,938] <inf> chip: [EM]??1 [E:6940i S:36163 M:117487376] (S) Msg Retransmission to 1:00000000086809DB scheduled for 403ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:33.869,122] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:33.878,479] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:33.885,264] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:33.892,058] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:33.900,151] <inf> chip: [EM]>>> [E:54536r S:36163 M:9888812 (Ack:117487375)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:33.919,953] <inf> chip: [EM]>>> [E:6940i S:36163 M:9888813 (Ack:117487376)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:33.937,903] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:33.946,718] <inf> chip: [EM]<<< [E:6940i S:36163 M:117487377 (Ack:9888813)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:33.988,409] <inf> chip: [EM]<<< [E:6941i S:36163 M:117487378] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:34.040,288] <inf> chip: [EM]??1 [E:6941i S:36163 M:117487378] (S) Msg Retransmission to 1:00000000086809DB scheduled for 350ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:34.094,165] <inf> chip: [EM]>>> [E:6941i S:36163 M:9888814 (Ack:117487378)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:34.112,108] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:34.121,305] <inf> chip: [EM]<<< [E:6941i S:36163 M:117487379 (Ack:9888814)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:35.605,439] <inf> chip: [EM]>>> [E:54537r S:36163 M:9888815] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:35.623,148] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:35.632,061] <inf> chip: [EM]<<< [E:54537r S:36163 M:117487380 (Ack:9888815)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:35.668,766] <inf> chip: [EM]??1 [E:54537r S:36163 M:117487380] (S) Msg Retransmission to 1:00000000086809DB scheduled for 383ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:35.722,161] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:35.731,342] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:35.738,126] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:35.744,748] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:35.751,556] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:35.759,738] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:35.769,709] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:35.777,677] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:35.784,734] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:35.791,806] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:35.799,590] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:35.815,081] <inf> chip: [EM]<<< [E:6942i S:36163 M:117487381] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:35.849,519] <inf> chip: [EM]??1 [E:6942i S:36163 M:117487381] (S) Msg Retransmission to 1:00000000086809DB scheduled for 398ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:35.903,135] <inf> chip: [EM]>>> [E:54537r S:36163 M:9888816 (Ack:117487380)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:35.922,940] <inf> chip: [EM]>>> [E:6942i S:36163 M:9888817 (Ack:117487381)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:35.940,882] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:35.949,689] <inf> chip: [EM]<<< [E:6942i S:36163 M:117487382 (Ack:9888817)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:41.094,454] <inf> chip: [EM]>>> [E:54538r S:36163 M:9888818] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:41.112,156] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:41.119,801] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:41.126,946] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:41.136,517] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:41.144,477] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:41.151,447] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:41.158,579] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:41.169,440] <inf> chip: [EM]<<< [E:54538r S:36163 M:117487383 (Ack:9888818)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:41.206,137] <inf> chip: [EM]??1 [E:54538r S:36163 M:117487383] (S) Msg Retransmission to 1:00000000086809DB scheduled for 357ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:41.245,822] <inf> chip: [EM]<<< [E:6943i S:36163 M:117487384] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:41.280,274] <inf> chip: [EM]??1 [E:6943i S:36163 M:117487384] (S) Msg Retransmission to 1:00000000086809DB scheduled for 382ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:41.351,743] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:41.361,107] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:41.367,893] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:41.374,689] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:41.382,377] <inf> chip: [EM]>>> [E:54538r S:36163 M:9888819 (Ack:117487383)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:41.402,190] <inf> chip: [EM]>>> [E:6943i S:36163 M:9888820 (Ack:117487384)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:41.420,147] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:41.429,377] <inf> chip: [EM]<<< [E:6943i S:36163 M:117487385 (Ack:9888820)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:41.471,094] <inf> chip: [EM]<<< [E:6944i S:36163 M:117487386] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:41.522,943] <inf> chip: [EM]??1 [E:6944i S:36163 M:117487386] (S) Msg Retransmission to 1:00000000086809DB scheduled for 395ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:41.576,842] <inf> chip: [EM]>>> [E:6944i S:36163 M:9888821 (Ack:117487386)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:41.594,789] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:41.603,602] <inf> chip: [EM]<<< [E:6944i S:36163 M:117487387 (Ack:9888821)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:43.024,853] <inf> chip: [EM]>>> [E:54539r S:36163 M:9888822] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:43.042,560] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:43.051,886] <inf> chip: [EM]<<< [E:54539r S:36163 M:117487388 (Ack:9888822)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:43.088,601] <inf> chip: [EM]??1 [E:54539r S:36163 M:117487388] (S) Msg Retransmission to 1:00000000086809DB scheduled for 372ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:43.142,036] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:43.151,217] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:43.158,002] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:43.164,626] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:43.171,435] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:43.179,619] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:43.189,613] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:43.197,582] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:43.204,641] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:43.211,742] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:43.219,528] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:43.234,616] <inf> chip: [EM]<<< [E:6945i S:36163 M:117487389] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:43.269,067] <inf> chip: [EM]??1 [E:6945i S:36163 M:117487389] (S) Msg Retransmission to 1:00000000086809DB scheduled for 333ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:43.322,751] <inf> chip: [EM]>>> [E:54539r S:36163 M:9888823 (Ack:117487388)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:43.342,961] <inf> chip: [EM]>>> [E:6945i S:36163 M:9888824 (Ack:117487389)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:43.360,908] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:43.369,716] <inf> chip: [EM]<<< [E:6945i S:36163 M:117487390 (Ack:9888824)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:54.572,033] <inf> chip: [EM]>>> [E:54543r S:36163 M:9888825] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:54.589,742] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:07:54.597,389] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:07:54.604,538] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:07:54.614,149] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:07:54.622,111] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:07:54.629,083] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:07:54.636,220] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:54.646,677] <inf> chip: [EM]<<< [E:54543r S:36163 M:117487391 (Ack:9888825)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:54.683,383] <inf> chip: [EM]??1 [E:54543r S:36163 M:117487391] (S) Msg Retransmission to 1:00000000086809DB scheduled for 347ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:54.741,829] <inf> chip: [EM]<<< [E:6946i S:36163 M:117487392] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:07:54.776,280] <inf> chip: [EM]??1 [E:6946i S:36163 M:117487392] (S) Msg Retransmission to 1:00000000086809DB scheduled for 387ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:54.829,493] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:54.838,851] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:54.845,638] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:07:54.852,439] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:54.860,123] <inf> chip: [EM]>>> [E:54543r S:36163 M:9888826 (Ack:117487391)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:54.879,932] <inf> chip: [EM]>>> [E:6946i S:36163 M:9888827 (Ack:117487392)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:54.897,939] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:54.906,765] <inf> chip: [EM]<<< [E:6946i S:36163 M:117487393 (Ack:9888827)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:54.965,966] <inf> chip: [EM]<<< [E:6947i S:36163 M:117487394] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:07:55.000,428] <inf> chip: [EM]??1 [E:6947i S:36163 M:117487394] (S) Msg Retransmission to 1:00000000086809DB scheduled for 349ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:55.054,719] <inf> chip: [EM]>>> [E:6947i S:36163 M:9888828 (Ack:117487394)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:55.072,673] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:55.081,487] <inf> chip: [EM]<<< [E:6947i S:36163 M:117487395 (Ack:9888828)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:56.886,988] <inf> chip: [EM]>>> [E:54544r S:36163 M:9888829] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:07:56.904,690] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:56.913,602] <inf> chip: [EM]<<< [E:54544r S:36163 M:117487396 (Ack:9888829)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:07:56.950,298] <inf> chip: [EM]??1 [E:54544r S:36163 M:117487396] (S) Msg Retransmission to 1:00000000086809DB scheduled for 405ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:56.985,343] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:07:56.994,525] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:07:57.001,307] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:07:57.007,927] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:07:57.014,732] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:07:57.022,911] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:07:57.050,359] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:07:57.058,323] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:07:57.065,377] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:07:57.072,449] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:07:57.080,233] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:07:57.095,724] <inf> chip: [EM]<<< [E:6948i S:36163 M:117487397] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:07:57.130,154] <inf> chip: [EM]??1 [E:6948i S:36163 M:117487397] (S) Msg Retransmission to 1:00000000086809DB scheduled for 363ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:07:57.165,401] <inf> chip: [EM]>>> [E:54544r S:36163 M:9888830 (Ack:117487396)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:07:57.203,605] <inf> chip: [EM]>>> [E:6948i S:36163 M:9888831 (Ack:117487397)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:07:57.221,547] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:07:57.230,350] <inf> chip: [EM]<<< [E:6948i S:36163 M:117487398 (Ack:9888831)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:01.077,613] <err> chip: [DIS]Timeout waiting for mDNS resolution.
uart:~$ [8D[J[00:08:10.262,297] <inf> chip: [EM]>>> [E:54548r S:36163 M:9888832] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:10.279,998] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:08:10.287,646] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:08:10.294,794] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:08:10.304,361] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:08:10.312,328] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:08:10.319,298] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:08:10.326,433] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:10.337,271] <inf> chip: [EM]<<< [E:54548r S:36163 M:117487399 (Ack:9888832)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:10.373,976] <inf> chip: [EM]??1 [E:54548r S:36163 M:117487399] (S) Msg Retransmission to 1:00000000086809DB scheduled for 389ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:10.432,020] <inf> chip: [EM]<<< [E:6949i S:36163 M:117487400] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:08:10.466,463] <inf> chip: [EM]??1 [E:6949i S:36163 M:117487400] (S) Msg Retransmission to 1:00000000086809DB scheduled for 356ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:10.519,772] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:10.529,132] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:10.535,917] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:08:10.542,710] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:10.550,395] <inf> chip: [EM]>>> [E:54548r S:36163 M:9888833 (Ack:117487399)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:10.570,195] <inf> chip: [EM]>>> [E:6949i S:36163 M:9888834 (Ack:117487400)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:10.588,140] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:10.597,365] <inf> chip: [EM]<<< [E:6949i S:36163 M:117487401 (Ack:9888834)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:10.656,502] <inf> chip: [EM]<<< [E:6950i S:36163 M:117487402] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:08:10.708,621] <inf> chip: [EM]??1 [E:6950i S:36163 M:117487402] (S) Msg Retransmission to 1:00000000086809DB scheduled for 375ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:10.762,857] <inf> chip: [EM]>>> [E:6950i S:36163 M:9888835 (Ack:117487402)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:10.780,805] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:10.789,612] <inf> chip: [EM]<<< [E:6950i S:36163 M:117487403 (Ack:9888835)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:12.341,929] <inf> chip: [EM]>>> [E:54549r S:36163 M:9888836] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:12.359,637] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:12.368,556] <inf> chip: [EM]<<< [E:54549r S:36163 M:117487404 (Ack:9888836)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:12.405,270] <inf> chip: [EM]??1 [E:54549r S:36163 M:117487404] (S) Msg Retransmission to 1:00000000086809DB scheduled for 378ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:12.458,601] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:12.467,784] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:12.474,568] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:08:12.481,191] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:12.487,999] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:08:12.496,183] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:12.506,128] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:08:12.514,092] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:08:12.521,149] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:08:12.528,222] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:08:12.536,006] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:12.551,093] <inf> chip: [EM]<<< [E:6951i S:36163 M:117487405] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:08:12.585,543] <inf> chip: [EM]??1 [E:6951i S:36163 M:117487405] (S) Msg Retransmission to 1:00000000086809DB scheduled for 334ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:12.639,188] <inf> chip: [EM]>>> [E:54549r S:36163 M:9888837 (Ack:117487404)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:12.659,398] <inf> chip: [EM]>>> [E:6951i S:36163 M:9888838 (Ack:117487405)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:12.677,345] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:12.686,157] <inf> chip: [EM]<<< [E:6951i S:36163 M:117487406 (Ack:9888838)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:15.076,329] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 45000 ms
uart:~$ [8D[J[00:08:15.087,919] <err> chip: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: operational discovery failed: 32
uart:~$ [8D[J[00:08:15.099,241] <err> chip: [DMG]Failed to establish CASE for subscription-resumption with error '32'
uart:~$ [8D[J[00:08:15.110,219] <inf> chip: [IM]Schedule subscription resumption when failing to establish session, Retries: 3
uart:~$ [8D[J[00:08:22.230,344] <inf> chip: [EM]>>> [E:54553r S:36163 M:9888839] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:22.248,048] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:08:22.255,696] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:08:22.262,844] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:08:22.272,405] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:08:22.280,371] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:08:22.287,340] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:08:22.294,476] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:22.304,927] <inf> chip: [EM]<<< [E:54553r S:36163 M:117487407 (Ack:9888839)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:22.341,619] <inf> chip: [EM]??1 [E:54553r S:36163 M:117487407] (S) Msg Retransmission to 1:00000000086809DB scheduled for 357ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:22.399,986] <inf> chip: [EM]<<< [E:6952i S:36163 M:117487408] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:08:22.434,439] <inf> chip: [EM]??1 [E:6952i S:36163 M:117487408] (S) Msg Retransmission to 1:00000000086809DB scheduled for 332ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:22.487,711] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:22.497,071] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:22.503,857] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:08:22.510,654] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:22.518,335] <inf> chip: [EM]>>> [E:54553r S:36163 M:9888840 (Ack:117487407)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:22.538,141] <inf> chip: [EM]>>> [E:6952i S:36163 M:9888841 (Ack:117487408)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:22.556,085] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:22.564,903] <inf> chip: [EM]<<< [E:6952i S:36163 M:117487409 (Ack:9888841)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:22.624,054] <inf> chip: [EM]<<< [E:6953i S:36163 M:117487410] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:08:22.658,798] <inf> chip: [EM]??1 [E:6953i S:36163 M:117487410] (S) Msg Retransmission to 1:00000000086809DB scheduled for 363ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:22.733,435] <inf> chip: [EM]>>> [E:6953i S:36163 M:9888842 (Ack:117487410)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:22.751,378] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:22.760,185] <inf> chip: [EM]<<< [E:6953i S:36163 M:117487411 (Ack:9888842)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:25.651,959] <inf> chip: [EM]>>> [E:54554r S:36163 M:9888843] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:25.669,650] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:25.678,559] <inf> chip: [EM]<<< [E:54554r S:36163 M:117487412 (Ack:9888843)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:25.715,251] <inf> chip: [EM]??1 [E:54554r S:36163 M:117487412] (S) Msg Retransmission to 1:00000000086809DB scheduled for 350ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:25.768,604] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:25.777,780] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:25.784,567] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:08:25.791,188] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:25.797,996] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:08:25.806,180] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:25.816,147] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:08:25.824,113] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:08:25.831,168] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:08:25.838,239] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:08:25.846,026] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:25.861,511] <inf> chip: [EM]<<< [E:6954i S:36163 M:117487413] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:08:25.895,939] <inf> chip: [EM]??1 [E:6954i S:36163 M:117487413] (S) Msg Retransmission to 1:00000000086809DB scheduled for 339ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:25.949,603] <inf> chip: [EM]>>> [E:54554r S:36163 M:9888844 (Ack:117487412)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:25.969,397] <inf> chip: [EM]>>> [E:6954i S:36163 M:9888845 (Ack:117487413)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:25.987,340] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:25.996,146] <inf> chip: [EM]<<< [E:6954i S:36163 M:117487414 (Ack:9888845)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:32.510,300] <inf> chip: [EM]>>> [E:54555r S:36163 M:9888846] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:32.528,007] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:08:32.535,655] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:08:32.542,806] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:08:32.552,389] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:08:32.560,356] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:08:32.567,327] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:08:32.574,461] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:32.584,910] <inf> chip: [EM]<<< [E:54555r S:36163 M:117487415 (Ack:9888846)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:32.621,607] <inf> chip: [EM]??1 [E:54555r S:36163 M:117487415] (S) Msg Retransmission to 1:00000000086809DB scheduled for 331ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:32.661,265] <inf> chip: [EM]<<< [E:6955i S:36163 M:117487416] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:08:32.731,608] <inf> chip: [EM]??1 [E:6955i S:36163 M:117487416] (S) Msg Retransmission to 1:00000000086809DB scheduled for 398ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:32.785,414] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:32.794,767] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:32.801,551] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:08:32.808,348] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:32.816,030] <inf> chip: [EM]>>> [E:54555r S:36163 M:9888847 (Ack:117487415)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:32.836,196] <inf> chip: [EM]>>> [E:6955i S:36163 M:9888848 (Ack:117487416)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:32.854,141] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:32.863,345] <inf> chip: [EM]<<< [E:6955i S:36163 M:117487417 (Ack:9888848)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:32.922,514] <inf> chip: [EM]<<< [E:6956i S:36163 M:117487418] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:08:32.956,963] <inf> chip: [EM]??1 [E:6956i S:36163 M:117487418] (S) Msg Retransmission to 1:00000000086809DB scheduled for 340ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:33.010,827] <inf> chip: [EM]>>> [E:6956i S:36163 M:9888849 (Ack:117487418)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:33.028,773] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:33.037,584] <inf> chip: [EM]<<< [E:6956i S:36163 M:117487419 (Ack:9888849)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:35.344,740] <inf> chip: [EM]>>> [E:54556r S:36163 M:9888850] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:35.362,451] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:35.371,366] <inf> chip: [EM]<<< [E:54556r S:36163 M:117487420 (Ack:9888850)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:35.408,069] <inf> chip: [EM]??1 [E:54556r S:36163 M:117487420] (S) Msg Retransmission to 1:00000000086809DB scheduled for 380ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:35.461,438] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:35.470,619] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:35.477,403] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:08:35.484,025] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:35.490,832] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:08:35.499,016] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:35.509,003] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:08:35.516,964] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:08:35.524,020] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:08:35.531,094] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:08:35.538,884] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:35.553,961] <inf> chip: [EM]<<< [E:6957i S:36163 M:117487421] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:08:35.588,395] <inf> chip: [EM]??1 [E:6957i S:36163 M:117487421] (S) Msg Retransmission to 1:00000000086809DB scheduled for 386ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:35.642,023] <inf> chip: [EM]>>> [E:54556r S:36163 M:9888851 (Ack:117487420)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:35.662,238] <inf> chip: [EM]>>> [E:6957i S:36163 M:9888852 (Ack:117487421)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:35.680,188] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:35.689,001] <inf> chip: [EM]<<< [E:6957i S:36163 M:117487422 (Ack:9888852)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:43.368,425] <inf> chip: [EM]>>> [E:54557r S:36163 M:9888853] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:43.386,136] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:08:43.393,785] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:08:43.400,932] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:08:43.410,514] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:08:43.418,477] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:08:43.425,448] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:08:43.432,585] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:43.443,041] <inf> chip: [EM]<<< [E:54557r S:36163 M:117487423 (Ack:9888853)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:43.479,770] <inf> chip: [EM]??1 [E:54557r S:36163 M:117487423] (S) Msg Retransmission to 1:00000000086809DB scheduled for 367ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:43.538,227] <inf> chip: [EM]<<< [E:6958i S:36163 M:117487424] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:08:43.572,675] <inf> chip: [EM]??1 [E:6958i S:36163 M:117487424] (S) Msg Retransmission to 1:00000000086809DB scheduled for 373ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:43.625,912] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:43.635,274] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:43.642,062] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:08:43.648,858] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:43.656,541] <inf> chip: [EM]>>> [E:54557r S:36163 M:9888854 (Ack:117487423)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:43.676,347] <inf> chip: [EM]>>> [E:6958i S:36163 M:9888855 (Ack:117487424)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:43.694,293] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:43.703,111] <inf> chip: [EM]<<< [E:6958i S:36163 M:117487425 (Ack:9888855)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:43.762,265] <inf> chip: [EM]<<< [E:6959i S:36163 M:117487426] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:08:43.796,721] <inf> chip: [EM]??1 [E:6959i S:36163 M:117487426] (S) Msg Retransmission to 1:00000000086809DB scheduled for 347ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:43.851,846] <inf> chip: [EM]>>> [E:6959i S:36163 M:9888856 (Ack:117487426)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:43.869,790] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:43.878,601] <inf> chip: [EM]<<< [E:6959i S:36163 M:117487427 (Ack:9888856)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:46.037,310] <inf> chip: [EM]>>> [E:54558r S:36163 M:9888857] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:46.055,009] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:46.063,921] <inf> chip: [EM]<<< [E:54558r S:36163 M:117487428 (Ack:9888857)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:46.100,618] <inf> chip: [EM]??1 [E:54558r S:36163 M:117487428] (S) Msg Retransmission to 1:00000000086809DB scheduled for 391ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:46.153,914] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:46.163,098] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:46.169,886] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:08:46.176,510] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:46.183,318] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:08:46.191,504] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:46.201,477] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:08:46.209,446] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:08:46.216,499] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:08:46.223,571] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:08:46.231,358] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:46.246,844] <inf> chip: [EM]<<< [E:6960i S:36163 M:117487429] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:08:46.281,270] <inf> chip: [EM]??1 [E:6960i S:36163 M:117487429] (S) Msg Retransmission to 1:00000000086809DB scheduled for 343ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:46.316,570] <inf> chip: [EM]>>> [E:54558r S:36163 M:9888858 (Ack:117487428)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:46.356,018] <inf> chip: [EM]>>> [E:6960i S:36163 M:9888859 (Ack:117487429)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:46.373,970] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:46.382,778] <inf> chip: [EM]<<< [E:6960i S:36163 M:117487430 (Ack:9888859)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:51.924,911] <inf> chip: [EM]>>> [E:54559r S:36163 M:9888860] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:51.942,615] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:08:51.969,541] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:08:51.994,375] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:08:52.054,933] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:08:52.063,786] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:08:52.070,754] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:08:52.077,889] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:52.088,338] <inf> chip: [EM]<<< [E:54559r S:36163 M:117487431 (Ack:9888860)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:52.125,034] <inf> chip: [EM]??1 [E:54559r S:36163 M:117487431] (S) Msg Retransmission to 1:00000000086809DB scheduled for 370ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:52.201,181] <inf> chip: [EM]<<< [E:6961i S:36163 M:117487432] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:08:52.235,621] <inf> chip: [EM]??1 [E:6961i S:36163 M:117487432] (S) Msg Retransmission to 1:00000000086809DB scheduled for 389ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:52.288,819] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:52.298,170] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:52.304,954] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:08:52.311,748] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:52.321,586] <inf> chip: [EM]>>> [E:54559r S:36163 M:9888861 (Ack:117487431)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:52.341,938] <inf> chip: [EM]>>> [E:6961i S:36163 M:9888862 (Ack:117487432)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:52.359,886] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:52.368,700] <inf> chip: [EM]<<< [E:6961i S:36163 M:117487433 (Ack:9888862)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:52.427,979] <inf> chip: [EM]<<< [E:6962i S:36163 M:117487434] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:08:52.462,422] <inf> chip: [EM]??1 [E:6962i S:36163 M:117487434] (S) Msg Retransmission to 1:00000000086809DB scheduled for 366ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:52.516,374] <inf> chip: [EM]>>> [E:6962i S:36163 M:9888863 (Ack:117487434)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:52.534,319] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:52.543,127] <inf> chip: [EM]<<< [E:6962i S:36163 M:117487435 (Ack:9888863)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:54.379,618] <inf> chip: [EM]>>> [E:54560r S:36163 M:9888864] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:08:54.397,315] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:54.460,882] <inf> chip: [EM]<<< [E:54560r S:36163 M:117487436 (Ack:9888864)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:08:54.497,572] <inf> chip: [EM]??1 [E:54560r S:36163 M:117487436] (S) Msg Retransmission to 1:00000000086809DB scheduled for 378ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:54.550,899] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:08:54.560,078] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:08:54.566,861] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:08:54.573,481] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:08:54.580,284] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:08:54.588,465] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:08:54.598,443] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:08:54.606,405] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:08:54.630,948] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:08:54.638,708] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:08:54.646,495] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:08:54.661,713] <inf> chip: [EM]<<< [E:6963i S:36163 M:117487437] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:08:54.696,149] <inf> chip: [EM]??1 [E:6963i S:36163 M:117487437] (S) Msg Retransmission to 1:00000000086809DB scheduled for 335ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:08:54.749,731] <inf> chip: [EM]>>> [E:54560r S:36163 M:9888865 (Ack:117487436)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:08:54.769,915] <inf> chip: [EM]>>> [E:6963i S:36163 M:9888866 (Ack:117487437)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:08:54.787,859] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:08:54.796,666] <inf> chip: [EM]<<< [E:6963i S:36163 M:117487438 (Ack:9888866)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:09.986,187] <inf> chip: [EM]>>> [E:54561r S:36163 M:9888867] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:10.003,890] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:09:10.011,539] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:09:10.018,686] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:09:10.028,270] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:09:10.036,233] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:09:10.043,203] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:09:10.050,337] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:10.060,790] <inf> chip: [EM]<<< [E:54561r S:36163 M:117487439 (Ack:9888867)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:10.097,495] <inf> chip: [EM]??1 [E:54561r S:36163 M:117487439] (S) Msg Retransmission to 1:00000000086809DB scheduled for 383ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:10.155,896] <inf> chip: [EM]<<< [E:6964i S:36163 M:117487440] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:09:10.190,341] <inf> chip: [EM]??1 [E:6964i S:36163 M:117487440] (S) Msg Retransmission to 1:00000000086809DB scheduled for 345ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:10.243,568] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:10.252,920] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:10.259,705] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:09:10.266,500] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:10.274,185] <inf> chip: [EM]>>> [E:54561r S:36163 M:9888868 (Ack:117487439)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:10.293,984] <inf> chip: [EM]>>> [E:6964i S:36163 M:9888869 (Ack:117487440)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:10.311,923] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:10.320,739] <inf> chip: [EM]<<< [E:6964i S:36163 M:117487441 (Ack:9888869)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:10.380,313] <inf> chip: [EM]<<< [E:6965i S:36163 M:117487442] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:09:10.414,755] <inf> chip: [EM]??1 [E:6965i S:36163 M:117487442] (S) Msg Retransmission to 1:00000000086809DB scheduled for 368ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:10.468,663] <inf> chip: [EM]>>> [E:6965i S:36163 M:9888870 (Ack:117487442)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:10.486,609] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:10.495,415] <inf> chip: [EM]<<< [E:6965i S:36163 M:117487443 (Ack:9888870)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:12.107,880] <inf> chip: [EM]>>> [E:54562r S:36163 M:9888871] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:12.125,587] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:12.134,502] <inf> chip: [EM]<<< [E:54562r S:36163 M:117487444 (Ack:9888871)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:12.171,196] <inf> chip: [EM]??1 [E:54562r S:36163 M:117487444] (S) Msg Retransmission to 1:00000000086809DB scheduled for 346ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:12.224,545] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:12.233,728] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:12.240,512] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:09:12.247,134] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:12.253,940] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:09:12.262,125] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:12.272,089] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:09:12.280,059] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:09:12.287,115] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:09:12.294,214] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:09:12.302,004] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:12.317,486] <inf> chip: [EM]<<< [E:6966i S:36163 M:117487445] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:09:12.351,929] <inf> chip: [EM]??1 [E:6966i S:36163 M:117487445] (S) Msg Retransmission to 1:00000000086809DB scheduled for 402ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:12.405,576] <inf> chip: [EM]>>> [E:54562r S:36163 M:9888872 (Ack:117487444)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:12.425,380] <inf> chip: [EM]>>> [E:6966i S:36163 M:9888873 (Ack:117487445)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:12.443,330] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:12.452,141] <inf> chip: [EM]<<< [E:6966i S:36163 M:117487446 (Ack:9888873)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:15.111,695] <dbg> chip: LogV: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: State change 1 --> 2
uart:~$ [8D[J[00:09:15.135,871] <inf> chip: [DIS]Lookup started for CCC10070EA402D1F-00000000BCDA9022
uart:~$ [8D[J[00:09:15.163,173] <inf> chip: [IM]Skip resuming live subscriptionId 4047205492
uart:~$ [8D[J[00:09:15.328,230] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 205 ms
uart:~$ [8D[J[00:09:17.812,291] <inf> chip: [EM]>>> [E:54563r S:36163 M:9888874] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:17.829,994] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:09:17.837,641] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:09:17.844,787] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:09:17.854,373] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:09:17.862,334] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:09:17.869,303] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:09:17.876,437] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:17.886,889] <inf> chip: [EM]<<< [E:54563r S:36163 M:117487447 (Ack:9888874)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:17.923,598] <inf> chip: [EM]??1 [E:54563r S:36163 M:117487447] (S) Msg Retransmission to 1:00000000086809DB scheduled for 393ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:17.981,635] <inf> chip: [EM]<<< [E:6967i S:36163 M:117487448] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:09:18.016,084] <inf> chip: [EM]??1 [E:6967i S:36163 M:117487448] (S) Msg Retransmission to 1:00000000086809DB scheduled for 389ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:18.069,321] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:18.078,680] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:18.085,465] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:09:18.092,262] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:18.099,945] <inf> chip: [EM]>>> [E:54563r S:36163 M:9888875 (Ack:117487447)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:18.120,149] <inf> chip: [EM]>>> [E:6967i S:36163 M:9888876 (Ack:117487448)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:18.138,089] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:18.146,893] <inf> chip: [EM]<<< [E:6967i S:36163 M:117487449 (Ack:9888876)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:18.237,112] <inf> chip: [EM]<<< [E:6968i S:36163 M:117487450] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:09:18.271,557] <inf> chip: [EM]??1 [E:6968i S:36163 M:117487450] (S) Msg Retransmission to 1:00000000086809DB scheduled for 342ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:18.325,455] <inf> chip: [EM]>>> [E:6968i S:36163 M:9888877 (Ack:117487450)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:18.343,393] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:18.352,200] <inf> chip: [EM]<<< [E:6968i S:36163 M:117487451 (Ack:9888877)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:20.459,618] <inf> chip: [EM]>>> [E:54564r S:36163 M:9888878] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:20.477,319] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:20.486,231] <inf> chip: [EM]<<< [E:54564r S:36163 M:117487452 (Ack:9888878)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:20.522,935] <inf> chip: [EM]??1 [E:54564r S:36163 M:117487452] (S) Msg Retransmission to 1:00000000086809DB scheduled for 370ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:20.576,315] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:20.585,496] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:20.592,282] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:09:20.598,903] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:20.605,711] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:09:20.613,894] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:20.641,383] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:09:20.649,349] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:09:20.656,405] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:09:20.663,478] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:09:20.671,271] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:20.686,342] <inf> chip: [EM]<<< [E:6969i S:36163 M:117487453] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:09:20.720,790] <inf> chip: [EM]??1 [E:6969i S:36163 M:117487453] (S) Msg Retransmission to 1:00000000086809DB scheduled for 373ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:20.774,868] <inf> chip: [EM]>>> [E:54564r S:36163 M:9888879 (Ack:117487452)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:20.794,857] <inf> chip: [EM]>>> [E:6969i S:36163 M:9888880 (Ack:117487453)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:20.812,799] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:20.821,606] <inf> chip: [EM]<<< [E:6969i S:36163 M:117487454 (Ack:9888880)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:28.068,740] <inf> chip: [EM]>>> [E:54565r S:36163 M:9888881] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:28.086,436] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:09:28.094,078] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:09:28.101,224] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:09:28.110,802] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:09:28.118,766] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:09:28.125,735] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:09:28.132,870] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:28.143,321] <inf> chip: [EM]<<< [E:54565r S:36163 M:117487455 (Ack:9888881)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:28.180,017] <inf> chip: [EM]??1 [E:54565r S:36163 M:117487455] (S) Msg Retransmission to 1:00000000086809DB scheduled for 368ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:28.220,071] <inf> chip: [EM]<<< [E:6970i S:36163 M:117487456] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:09:28.272,161] <inf> chip: [EM]??1 [E:6970i S:36163 M:117487456] (S) Msg Retransmission to 1:00000000086809DB scheduled for 342ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:28.325,895] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:28.335,245] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:28.342,029] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:09:28.348,826] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:28.356,510] <inf> chip: [EM]>>> [E:54565r S:36163 M:9888882 (Ack:117487455)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:28.376,317] <inf> chip: [EM]>>> [E:6970i S:36163 M:9888883 (Ack:117487456)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:28.394,268] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:28.439,782] <inf> chip: [EM]<<< [E:6970i S:36163 M:117487457 (Ack:9888883)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:28.499,330] <inf> chip: [EM]<<< [E:6971i S:36163 M:117487458] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:70)
uart:~$ [8D[J[00:09:28.533,773] <inf> chip: [EM]??1 [E:6971i S:36163 M:117487458] (S) Msg Retransmission to 1:00000000086809DB scheduled for 405ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:28.587,844] <inf> chip: [EM]>>> [E:6971i S:36163 M:9888884 (Ack:117487458)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:28.605,792] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:28.669,925] <inf> chip: [EM]<<< [E:6971i S:36163 M:117487459 (Ack:9888884)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:30.708,263] <inf> chip: [EM]>>> [E:54566r S:36163 M:9888885] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:30.725,971] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:30.735,293] <inf> chip: [EM]<<< [E:54566r S:36163 M:117487460 (Ack:9888885)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:30.771,991] <inf> chip: [EM]??1 [E:54566r S:36163 M:117487460] (S) Msg Retransmission to 1:00000000086809DB scheduled for 347ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:30.807,006] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:30.816,183] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:30.822,969] <inf> app: Setting brightness level to 1
uart:~$ [8D[J[00:09:30.829,589] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:30.836,395] <inf> chip: [ZCL]Setting on/off to OFF due to level change
uart:~$ [8D[J[00:09:30.844,575] <inf> chip: [ZCL]Toggle ep1 on/off from state 1 to 0
uart:~$ [8D[J[00:09:30.854,538] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 0
uart:~$ [8D[J[00:09:30.862,500] <inf> app: Turn Off Action has been initiated
uart:~$ [8D[J[00:09:30.869,554] <inf> app: Turn Off Action has been completed
uart:~$ [8D[J[00:09:30.876,626] <inf> chip: [ZCL]Off completed. reset OnTime to  0
uart:~$ [8D[J[00:09:30.902,765] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:30.917,795] <inf> chip: [EM]<<< [E:6972i S:36163 M:117487461] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:95)
uart:~$ [8D[J[00:09:30.952,231] <inf> chip: [EM]??1 [E:6972i S:36163 M:117487461] (S) Msg Retransmission to 1:00000000086809DB scheduled for 333ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:31.005,854] <inf> chip: [EM]>>> [E:54566r S:36163 M:9888886 (Ack:117487460)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:31.025,657] <inf> chip: [EM]>>> [E:6972i S:36163 M:9888887 (Ack:117487461)] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:01 (IM:StatusResponse) (B:42)
uart:~$ [8D[J[00:09:31.043,602] <inf> chip: [IM]Received status response, status is 0x00
uart:~$ [8D[J[00:09:31.052,412] <inf> chip: [EM]<<< [E:6972i S:36163 M:117487462 (Ack:9888887)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:34.732,135] <inf> chip: [EM]>>> [E:54567r S:36163 M:9888888] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:34.821,936] <inf> chip: [ZCL]Toggle ep1 on/off from state 0 to 1
uart:~$ [8D[J[00:09:34.830,533] <inf> chip: [ZCL]On Command - OffWaitTime :  0
uart:~$ [8D[J[00:09:34.837,676] <inf> chip: [ZCL]On/Toggle Command - Stop Timer
uart:~$ [8D[J[00:09:34.847,261] <inf> chip: [ZCL]Cluster OnOff: attribute OnOff set to 1
uart:~$ [8D[J[00:09:34.855,230] <inf> app: Turn On Action has been initiated
uart:~$ [8D[J[00:09:34.862,197] <inf> app: Turn On Action has been completed
uart:~$ [8D[J[00:09:34.869,341] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
uart:~$ [8D[J[00:09:34.879,790] <inf> chip: [EM]<<< [E:54567r S:36163 M:117487463 (Ack:9888888)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
uart:~$ [8D[J[00:09:34.903,623] <inf> chip: [EM]??1 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB scheduled for 375ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:34.925,844] <inf> chip: [EM]<<< [E:6973i S:36163 M:117487464] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0001:05 (IM:ReportData) (B:69)
uart:~$ [8D[J[00:09:34.947,419] <inf> chip: [EM]??1 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB scheduled for 377ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:34.964,897] <inf> chip: [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
uart:~$ [8D[J[00:09:34.974,252] <inf> app: Level Action has been initiated
uart:~$ [8D[J[00:09:34.981,035] <inf> app: Setting brightness level to 254
uart:~$ [8D[J[00:09:34.987,829] <inf> app: Level Action has been completed
uart:~$ [8D[J[00:09:35.278,920] <inf> chip: [EM]<<1 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:35.290,932] <inf> chip: [EM]??2 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB scheduled for 380ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:35.324,404] <inf> chip: [EM]<<1 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:35.336,335] <inf> chip: [EM]??2 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB scheduled for 395ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:35.670,750] <inf> chip: [EM]<<2 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:35.682,759] <inf> chip: [EM]??3 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB scheduled for 631ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:35.732,226] <inf> chip: [EM]<<2 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:35.744,152] <inf> chip: [EM]??3 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB scheduled for 592ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:36.003,506] <dbg> chip: LogV: [IN]Received a duplicate message with MessageCounter:9888888 on exchange 54567r
uart:~$ [8D[J[00:09:36.015,196] <inf> chip: [EM]>>> [E:54567r S:36163 M:9888888] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:36.032,993] <inf> chip: [EM]<<< [E:54567r S:36163 M:117487465 (Ack:9888888)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:36.314,203] <inf> chip: [EM]<<3 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:36.326,212] <inf> chip: [EM]??4 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB scheduled for 857ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:36.343,509] <inf> chip: [EM]<<3 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:36.355,426] <inf> chip: [EM]??4 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB scheduled for 908ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:37.183,953] <inf> chip: [EM]<<4 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:37.195,962] <inf> chip: [EM]??5 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB scheduled for 1430ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:37.263,496] <inf> chip: [EM]<<4 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB
uart:~$ [8D[J[00:09:37.275,432] <inf> chip: [EM]??5 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB scheduled for 1353ms from now [State:Active II:500 AI:300 AT:4000]
uart:~$ [8D[J[00:09:37.361,981] <dbg> chip: LogV: [IN]Received a duplicate message with MessageCounter:9888888 on exchange 54567r
uart:~$ [8D[J[00:09:37.373,671] <inf> chip: [EM]>>> [E:54567r S:36163 M:9888888] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:37.391,464] <inf> chip: [EM]<<< [E:54567r S:36163 M:117487466 (Ack:9888888)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:38.625,670] <err> chip: [EM]<<5 [E:54567r S:36163 M:117487463] (S) Msg Retransmission to 1:00000000086809DB failure (max retries:4)
uart:~$ [8D[J[00:09:38.639,163] <dbg> chip: LogV: [IN]SecureSession[0x200107a8]: MarkAsDefunct Type:2 LSID:36163
uart:~$ [8D[J[00:09:38.667,400] <inf> chip: [SC]SecureSession[0x200107a8, LSID:36163]: State change 'kActive' --> 'kDefunct'
uart:~$ [8D[J[00:09:38.697,091] <err> chip: [EM]<<5 [E:6973i S:36163 M:117487464] (S) Msg Retransmission to 1:00000000086809DB failure (max retries:4)
uart:~$ [8D[J[00:09:39.291,708] <dbg> chip: LogV: [IN]Received a duplicate message with MessageCounter:9888888 on exchange 54567r
uart:~$ [8D[J[00:09:39.303,297] <inf> chip: [SC]SecureSession[0x200107a8, LSID:36163]: State change 'kDefunct' --> 'kActive'
uart:~$ [8D[J[00:09:39.314,540] <inf> chip: [EM]>>> [E:54567r S:36163 M:9888888] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:39.332,754] <inf> chip: [EM]<<< [E:54567r S:36163 M:117487467 (Ack:9888888)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:42.428,503] <dbg> chip: LogV: [IN]Received a duplicate message with MessageCounter:9888888 on exchange 54567r
uart:~$ [8D[J[00:09:42.440,197] <inf> chip: [EM]>>> [E:54567r S:36163 M:9888888] (S) Msg RX from 1:00000000086809DB [2D1F] to 00000000D74E3E31 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
uart:~$ [8D[J[00:09:42.458,006] <inf> chip: [EM]<<< [E:54567r S:36163 M:117487468 (Ack:9888888)] (S) Msg TX from 00000000D74E3E31 to 1:00000000086809DB [2D1F] [UDP:[fdc0:cb91:39c6:4fe0:1087:30bc:fda3:1755]:63359] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
uart:~$ [8D[J[00:09:46.184,070] <err> chip: [DIS]Timeout waiting for mDNS resolution.
uart:~$ [8D[J[00:09:55.235,617] <dbg> chip: LogV: [IN]SecureSession[0x200107a8]: MarkAsDefunct Type:2 LSID:36163
uart:~$ [8D[J[00:09:55.245,726] <inf> chip: [SC]SecureSession[0x200107a8, LSID:36163]: State change 'kActive' --> 'kDefunct'
uart:~$ [8D[J[00:09:55.256,869] <err> chip: [DMG]Time out! failed to receive status response from Exchange: 6973i
uart:~$ [8D[J[00:09:55.267,065] <inf> chip: [DMG]Subscription id 0xf13b7474 from node <00000000086809DB, 1> torn down
uart:~$ [8D[J[00:09:55.278,087] <inf> chip: [IM]Schedule subscription resumption when failing to establish session, Retries: 4
uart:~$ [8D[J[00:10:00.123,168] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 45001 ms
uart:~$ [8D[J[00:10:00.134,765] <err> chip: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: operational discovery failed: 32
uart:~$ [8D[J[00:10:00.146,088] <err> chip: [DMG]Failed to establish CASE for subscription-resumption with error '32'
uart:~$ [8D[J[00:11:35.277,860] <dbg> chip: LogV: [DIS]OperationalSessionSetup[1:00000000BCDA9022]: State change 1 --> 2
uart:~$ [8D[J[00:11:35.302,011] <inf> chip: [DIS]Lookup started for CCC10070EA402D1F-00000000BCDA9022
uart:~$ [8D[J[00:11:35.329,321] <dbg> chip: LogV: [DIS]OperationalSessionSetup[1:00000000086809DB]: State change 1 --> 2
uart:~$ [8D[J[00:11:35.353,782] <inf> chip: [DIS]Lookup started for CCC10070EA402D1F-00000000086809DB
uart:~$ [8D[J[00:11:35.489,019] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 200 ms
uart:~$ [8D[J[00:11:35.500,448] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000086809DB after 160 ms
uart:~$ [8D[J[00:11:35.511,815] <inf> chip: [DIS]Keeping DNSSD lookup active
uart:~$ [8D[J[00:11:35.541,085] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000BCDA9022 after 252 ms
uart:~$ [8D[J[00:11:35.552,513] <inf> chip: [DIS]Checking node lookup status for CCC10070EA402D1F-00000000086809DB after 212 ms
