Log分析说明
flowVAD
该模块主要涉及唤醒跟识别，正常流程如下:
```
[VERBOSE] 11-05 18:22:24.376922 [dispatcher/init.lua 368] onVoiceEvent: ID17 START|30.0 0.0 1.0
[VERBOSE] 11-05 18:22:24.377467 [manager/init.lua 423] manager.handleEvent onVoiceEvent
[VERBOSE] 11-05 18:22:24.378077 [manager/init.lua 429] controlManager.handleEvent onVoiceEvent
[VERBOSE] 11-05 18:22:24.378743 [manager/init.lua 445] nil handleEvent
[DEBUG] 11-05 18:22:24.381353 [scene/flow/init.lua 31]❗️ @@@ 启动: flow-flow.VAD
[VERBOSE] 11-05 18:22:24.382194 [flowVAD.lua 25] ⛱ 开始 VAD识别 流程 flow.VAD
[VERBOSE] 11-05 18:22:24.382828 [flowVAD.lua 29] ⛱ ① onVoiceEvent START
[VERBOSE] 11-05 18:22:24.383255 [lua/comp/light.lua 63] light setAsr
[VERBOSE] 11-05 18:22:24.396506 [rkqqmusic.cc 250] qq_sdk: [2018-11-05 18:22:23 396][Main:2746][CAIAudioChannelUp:166]: [CAIAudioChannelUp::OnTimer] uId=4322, m_cookie:10006.
[VERBOSE] 11-05 18:22:24.463900 [RKLuaMsgQueue.cpp 56] push message func onTimerCallback in activation count 1
[VERBOSE] 11-05 18:22:24.464378 [RKLuaMsgQueue.cpp 89] pop message func onTimerCallback in activation count 0
[VERBOSE] 11-05 18:22:24.469735 [RKLuaVolume.cpp 97] get stream tts(1) vol 10
[VERBOSE] 11-05 18:22:24.470120 [core/animator.lua 26] dowhat 10 20 10
[VERBOSE] 11-05 18:22:24.470402 [core/animator.lua 29] to 10 finished
[VERBOSE] 11-05 18:22:24.722261 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.TmpResult, asr = 若琪
[VERBOSE] 11-05 18:22:24.722889 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 1
[VERBOSE] 11-05 18:22:24.723973 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:24.724873 [dispatcher/init.lua 384] onIntermediateResult: ID17 BEGIN|50.0 若琪
[VERBOSE] 11-05 18:22:24.725371 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.725534 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.725704 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:24.726006 [flowVAD.lua 177] low.onIntermediateResult(id 50 若琪
[VERBOSE] 11-05 18:22:24.726206 [flowVAD.lua 44] ⛱ ③ onIntermediateResult BEGIN
[VERBOSE] 11-05 18:22:24.922967 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onVoiceEvent.Accept, extra = {"activation":"accept"}
[VERBOSE] 11-05 18:22:24.923294 [RKLuaMsgQueue.cpp 56] push message func onVoiceEvent in activation count 1
[VERBOSE] 11-05 18:22:24.923608 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.TmpResult, asr = 若琪
[VERBOSE] 11-05 18:22:24.923788 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 2
[VERBOSE] 11-05 18:22:24.923956 [RKLuaMsgQueue.cpp 89] pop message func onVoiceEvent in activation count 1
[VERBOSE] 11-05 18:22:24.924489 [dispatcher/init.lua 368] onVoiceEvent: ID17 ACCEPT|40.0 0.0 1.0
[VERBOSE] 11-05 18:22:24.924809 [manager/init.lua 423] manager.handleEvent onVoiceEvent
[VERBOSE] 11-05 18:22:24.924997 [manager/init.lua 429] controlManager.handleEvent onVoiceEvent
[VERBOSE] 11-05 18:22:24.925316 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:24.925727 [flowVAD.lua 39] ⛱ ② onVoiceEvent ACCEPT
[VERBOSE] 11-05 18:22:24.925934 [lua/comp/siren.lua 207] ⛎ Siren: AWAKE 1.0
[VERBOSE] 11-05 18:22:24.927758 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:24.928447 [dispatcher/init.lua 384] onIntermediateResult: ID17 BEGIN|50.0 若琪
[VERBOSE] 11-05 18:22:24.928707 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.928879 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.929157 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:24.929354 [flowVAD.lua 177] low.onIntermediateResult(id 50 若琪
[VERBOSE] 11-05 18:22:24.929497 [flowVAD.lua 44] ⛱ ③ onIntermediateResult BEGIN
[VERBOSE] 11-05 18:22:24.943638 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> (46), extra = {"oneshot":"false"}
[ERROR] 11-05 18:22:24.943940 [RKLuaTuren.cpp 66]❌ <17> (46), extra = {"oneshot":"false"}
[VERBOSE] 11-05 18:22:24.944282 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.TmpResult, asr = 若琪
[VERBOSE] 11-05 18:22:24.944499 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 1
[VERBOSE] 11-05 18:22:24.944693 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:24.945183 [dispatcher/init.lua 384] onIntermediateResult: ID17 BEGIN|50.0 若琪
[VERBOSE] 11-05 18:22:24.945665 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.945972 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:24.946401 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:24.946727 [flowVAD.lua 177] low.onIntermediateResult(id 50 若琪
[VERBOSE] 11-05 18:22:24.946975 [flowVAD.lua 44] ⛱ ③ onIntermediateResult BEGIN
[VERBOSE] 11-05 18:22:25.269832 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.TmpResult, asr = 继续
[VERBOSE] 11-05 18:22:25.270122 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 1
[VERBOSE] 11-05 18:22:25.270405 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:25.271248 [dispatcher/init.lua 384] onIntermediateResult: ID17 BEGIN|50.0 继续
[VERBOSE] 11-05 18:22:25.271641 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:25.271920 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:25.272385 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:25.272714 [flowVAD.lua 177] low.onIntermediateResult(id 50 继续
[VERBOSE] 11-05 18:22:25.272980 [flowVAD.lua 44] ⛱ ③ onIntermediateResult BEGIN
[VERBOSE] 11-05 18:22:25.750492 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.TmpResult, asr = 继续播放
[VERBOSE] 11-05 18:22:25.750727 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 1
[VERBOSE] 11-05 18:22:25.750971 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:25.751358 [dispatcher/init.lua 384] onIntermediateResult: ID17 BEGIN|50.0 继续播放
[VERBOSE] 11-05 18:22:25.751658 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:25.751924 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:25.752352 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:25.752536 [flowVAD.lua 177] low.onIntermediateResult(id 50 继续播放
[VERBOSE] 11-05 18:22:25.752662 [flowVAD.lua 44] ⛱ ③ onIntermediateResult BEGIN
[VERBOSE] 11-05 18:22:26.307233 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onAsr.EndResult, asr = 继续播放
[VERBOSE] 11-05 18:22:26.307552 [RKLuaMsgQueue.cpp 56] push message func onIntermediateResult in activation count 1
[VERBOSE] 11-05 18:22:26.307722 [RKLuaMsgQueue.cpp 89] pop message func onIntermediateResult in activation count 0
[VERBOSE] 11-05 18:22:26.308277 [dispatcher/init.lua 384] onIntermediateResult: ID17 END|51.0 继续播放
[VERBOSE] 11-05 18:22:26.308642 [manager/init.lua 423] manager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:26.308848 [manager/init.lua 429] controlManager.handleEvent onIntermediateResult
[VERBOSE] 11-05 18:22:26.308969 [R2Turen.cpp 223] [SOCKET] Client Received Event: (22) <17> onVoiceCommand.NLP, nlp = {"cloud":false,"intent":"ROKID.INTENT.RESUME","slots":{},"pattern":"继续播放","asr":"继续播放","appId":"RC3CA6A6C04C4513B1F54191F9E749A9","appName":"系统技能控制指令"}, action = {"version":"2.0.0","startWithActiveWord":false,"appId":"RC3CA6A6C04C4513B1F54191F9E749A9","session":{},"response":{"action":{"version":"2.0.0","type":"NORMAL","form":"service","shouldEndSession":false,"directives":[]},"resType":"INTENT","respId":"040c84d8b55d4e2f679908fe759bdcfd"}}
[VERBOSE] 11-05 18:22:26.309098 [manager/init.lua 445] nil handleEvent
[VERBOSE] 11-05 18:22:26.309198 [RKLuaMsgQueue.cpp 56] push message func onVoiceCommand in activation count 1
[VERBOSE] 11-05 18:22:26.309285 [flowVAD.lua 177] low.onIntermediateResult(id 51 继续播放
[VERBOSE] 11-05 18:22:26.309371 [flowVAD.lua 47] ⛱ ④ onIntermediateResult END
[VERBOSE] 11-05 18:22:26.309516 [lua/comp/light.lua 63] light setPause
[VERBOSE] 11-05 18:22:26.310243 [RKLuaMsgQueue.cpp 89] pop message func onVoiceCommand in activation count 0
[VERBOSE] 11-05 18:22:26.310782 [dispatcher/init.lua 426] onVoiceCommand {"cloud":false,"intent":"ROKID.INTENT.RESUME","slots":{},"pattern":"继续播放","asr":"继续播放","appId":"RC3CA6A6C04C4513B1F54191F9E749A9","appName":"系统技能控制指令"} {"version":"2.0.0","startWithActiveWord":false,"appId":"RC3CA6A6C04C4513B1F54191F9E749A9","session":{},"response":{"action":{"version":"2.0.0","type":"NORMAL","form":"service","shouldEndSession":false,"directives":[]},"resType":"INTENT","respId":"040c84d8b55d4e2f679908fe759bdcfd"}}
[VERBOSE] 11-05 18:22:26.312223 [dispatcher/init.lua 450] onVoiceCommand: ID17 MEDIA_CONTROL {"cloud":false,"intent":"ROKID.INTENT.RESUME","slots":{},"pattern":"继续播放","asr":"继续播放","appId":"RC3CA6A6C04C4513B1F54191F9E749A9","appName":"系统技能控制指令"} {"version":"2.0.0","startWithActiveWord":false,"appId":"RC3CA6A6C04C4513B1F54191F9E749A9","session":{},"response":{"action":{"version":"2.0.0","type":"NORMAL","form":"service","shouldEndSession":false,"directives":[]},"resType":"INTENT","respId":"040c84d8b55d4e2f679908fe759bdcfd"}}
[VERBOSE] 11-05 18:22:26.312416 [flowVAD.lua 73] ⛱ ⑤ (OK) onVoiceCommand
[VERBOSE] 11-05 18:22:26.312636 [lua/comp/light.lua 63] light setExec
[VERBOSE] 11-05 18:22:26.313146 [flowVAD.lua 156] ⛱ ▉ (关闭) VAD识别流程 flow.VAD
```
