Closed sundq closed 6 years ago
mradulan
commented
6 years ago Hi @sundq , if the aim is to play audio when the wakeword is detected, you can implement it by MediaPlayer. AudioPlayer is only interface for managing and controlling audio playback. It plays the audio through MediaPlayer.
mradulan
commented
6 years ago SDK requires to acquire focus in Listening state so that other audio is in background when listening is in process. One thing to be careful in your implementation is that playing audio while listening may cause the played sound to get picked up by the microphone and passed up as part of the recognize request.
frozenberg
commented
6 years ago Hi @sundq,
We haven't heard from you in a while, so I'm going to close this issue. Feel free to reopen if you have any more concerns.
Fede
platform: arm SDK version: 1.2.0 OS: linux
I want to play a alert tone when alexa wake up to tell user that the alexa have entering Listening state， and the user can ask his question.
so I add these code to
KeywordObserver::onKeyWordDetectedmethod to play this audio file。for play the local alert tone, I implement myselfAudioPlayerrefer the sdkAudioPlayer.cpp, this is metioned in issue #126 . But the question is It can not play the audio filename because my audiplayer priority is bigger than other player, so my AudioPlayer can not get focus.my question is why AVSSDK acquire focus when entering Listening state,
this is the log:
^C[ 3849.755661] <0>-(0)[8284:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger AWB cmd = 0
root@aud8516-slc:/data# ./tl_avs_sample Running app with log level: DEBUG9 2017-12-28 07:34:23.831 [ 1] I ConfigurationNode:initializeSuccess [ 3850.546613] <0>.(0)[9113:tl_avs_sample]unix: [mtk_net][socket]unix_stream_connect[34092 ]: connect [/var/run/dbus/system_bus_socket] other[7042] [ 3850.554151] <1>.(1)[9113:tl_avs_sample]get the card succ [ 3850.554975] <0>.(0)[9114:tl_avs_sample]unix: [mtk_net][socket]unix_dgram_connect[34095]:connect [/dev/log] other:[6627] [2017-12-28 07:34:23.849] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:273] message process thread start [2017-12-28 07:34:23.853] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/LedDisplay/src/LedDisplay.cpp:335] Tonly led mode loop start [2017-12-28 07:34:23.860] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: org.freedesktop.DBus [2017-12-28 07:34:23.864] [tonly_avs_app] [WARNING] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:219] there is no observer for message: org.freedesktop.DBus [2017-12-28 07:34:23.864] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: org.freedesktop.DBus [2017-12-28 07:34:23.864] [tonly_avs_app] [WARNING] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:219] there is no observer for message: org.freedesktop.DBus [2017-12-28 07:34:23.867] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:68] add message observer for: message.type.application [2017-12-28 07:34:23.871] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:68] add message observer for: message.type.robot 2017-12-28 07:34:23.871 [ 1] 9 MediaPlayer:createCalled [2017-12-28 07:34:23.889] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/LedDisplay/src/LedDisplay.cpp:312] Tonly led display loop start 2017-12-28 07:34:23.974 [ 1] 9 MediaPlayer:createCalled 2017-12-28 07:34:23.976 [ 1] 9 MediaPlayer:createCalled 2017-12-28 07:34:23.979 [ 1] 9 MediaPlayer:createCalled 2017-12-28 07:34:23.988 [ 1] 9 MediaPlayer:setObserverCalled 2017-12-28 07:34:23.988 [ 2] 0 MediaPlayer:handleSetObserverCalled 2017-12-28 07:34:23.989 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x6265c8 2017-12-28 07:34:23.989 [ 1] 9 MediaPlayer:setObserverCalled 2017-12-28 07:34:23.989 [ 2] 0 MediaPlayer:handleSetObserverCalled 2017-12-28 07:34:23.990 [ 1] 9 MediaPlayer:setObserverCalled 2017-12-28 07:34:23.990 [ 2] 0 MediaPlayer:handleSetObserverCalled 2017-12-28 07:34:23.990 [ 1] 9 MediaPlayer:setObserverCalled 2017-12-28 07:34:23.990 [ 2] 0 MediaPlayer:handleSetObserverCalled #####################################
Client not connected!
#####################################
2017-12-28 07:34:24.007 [ 1] 9 AlertScheduler:setTimerForNextAlertLocked 2017-12-28 07:34:24.007 [ 1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do. 2017-12-28 07:34:24.007 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2017-12-28 07:34:24.012 [ 1] 9 MediaPlayer:getSpeakerTypeCalled 2017-12-28 07:34:24.013 [ 1] 9 MediaPlayer:getSpeakerTypeCalled 2017-12-28 07:34:24.014 [ 1] 9 MediaPlayer:getSpeakerTypeCalled 2017-12-28 07:34:24.014 [ 1] 0 SpeakerManager:mapCreated:numAvsSynced=3,numLocal=0 2017-12-28 07:34:24.014 [ 1] 1 AudioPlayer:addObserver 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x63dfd0,policy=BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x63efc0,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x63efc0,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x63efc0,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x63d0e0,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x63d0e0,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x640b50,policy=NON_BLOCKING 2017-12-28 07:34:24.015 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x640b50,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handl[ 3850.736197] <1>.(1)[9113:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_hw_params AWB rate=16000, format=2, channels=1, period_size=128, buffer_size=32768, buffer_bytes=65536 er=0x669f30,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x63cd58,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x668180,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x668180,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x668180,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] 9 TemplateRuntime:getConfiguration 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x669970,policy=NON_BLOCKING 2017-12-28 07:34:24.016 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x669970,policy=NON_BLOCKING [[ 3850.749731] <1>-(1)[9149:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger AWB cmd = 1 2017-12-28 07:34:24.018] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/Microphone/src/MicrophoneWrapper.cpp:40] m_format=SND_PCM_FORMAT_S16_LE m_frames=32 m_channels=1 m_sampleRate=16000 m_alsaDeviceName=hw:0,3 [2017-12-28 07:34:24.042] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/Microphone/src/MicrophoneWrapper.cpp:242] alsa micro phone loop start [2017-12-28 07:34:24.058] [tonly_avs_app] [INFO] [src/TonlyKeyWordsDetector.cpp:76] detect loop thread created: maxSamplesPerPush=160 [ 3850.839767] <3>.(3)[9151:tl_avs_sample]Dump cpuinfo [ 3851.480550] <3>.(3)[451:rx_thread]TCP: [mtk_net][tcp_finish_connect] inode = 33469; sport = 48808; dport = 443 2017-12-28 07:34:26.250 [ 4] 0 HttpPost:doPostSucceeded:code=200 2017-12-28 07:34:26.250 [ 4] 0 AuthDelegate:handleLwaResponseSucceeded:expiresInSeconds=3600 2017-12-28 07:34:26.251 [ 4] 0 AuthDelegate:onAuthStateChangeCalled:state=1,error=0 2017-12-28 07:34:26.251 [ 1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING 2017-12-28 07:34:26.252 [ 1] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1 #############################
Connecting...
#############################
2017-12-28 07:34:26.253 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST 2017-12-28 07:34:26.254 [ 5] 9 PostConnectSynchronize:Entering postConnectLoop thread 2017-12-28 07:34:26.254 [ 6] 9 SpeakerManager:provideStateCalled 2017-12-28 07:34:26.255 [ 6] 0 AudioPlayer:provideState:stateRequestToken=1 2017-12-28 07:34:26.255 [ 6] 9 SpeechSynthesizer:provideState:token=1 2017-12-28 07:34:26.255 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1 2017-12-28 07:34:26.255 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" \: ""},namespace=SpeechRecognizer,name=RecognizerState 2017-12-28 07:34:26.255 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState 2017-12-28 07:34:26.255 [ a] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1 2017-12-28 07:34:26.255 [ a] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"},namespace=AudioPlayer,name=PlaybackState 2017-12-28 07:34:26.255 [ 7] 9 SpeakerManager:executeProvideStateCalled 2017-12-28 07:34:26.256 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:26.256 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled 2017-12-28 07:34:26.257 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:26.257 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled 2017-12-28 07:34:26.257 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:26.257 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled 2017-12-28 07:34:26.257 [ 7] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true 2017-12-28 07:34:26.257 [ 7] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false 2017-12-28 07:34:26.257 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume"\:100\,"muted"\:false},namespace=Speaker,name=VolumeState 2017-12-28 07:34:26.258 [ 6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]} 2017-12-28 07:34:26.258 [ 6] 0 EventBuilder:buildJsonEventString:messageId=45b2f044-badf-429d-8f86-0bb7b455c0ac,namespace=System,name=SynchronizeState 2017-12-28 07:34:26.259 [ 6] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport 2017-12-28 07:34:26.259 [ 6] 9 HTTP2Transport:enqueueRequest
Trying 54.239.26.171...
TCP_NODELAY set [ 3853.651283] <1>.(1)[451:rx_thread]TCP: [mtk_net][tcp_finish_connect] inode = 33474; sport = 59094; dport = 443
Connected to avs-alexa-na.amazon.com (54.239.26.171) port 443 (#0)
ALPN, offering h2
ALPN, offering http/1.1
Cipher selection: ALL:!EXPORT:!EXPORT40:!EXPORT56:!aNULL:!LOW:!RC4:@STRENGTH
successfully set certificate verify locations:
CAfile: none CApath: /etc/certificates
SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384
ALPN, server accepted to use h2
Server certificate:
subject: C=US; ST=Washington; L=Seattle; O=Amazon.com, Inc.; CN=latinum.amazon.com
start date: Aug 23 00:00:00 2017 GMT
expire date: May 21 23:59:59 2018 GMT
issuer: C=US; O=Symantec Corporation; OU=Symantec Trust Network; CN=Symantec Class 3 Secure Server CA - G4
SSL certificate verify result: unable to get local issuer certificate (20), continuing anyway.
Using HTTP2, server supports multi-use
Connection state changed (HTTP/2 confirmed)
Copying HTTP/2 data in stream buffer to connection buffer after upgrade: len=0
Using Stream ID: 1 (easy handle 0x6ae020)
Connection state changed (MAX_CONCURRENT_STREAMS updated)! < HTTP/2 200 < access-control-allow-origin: * < x-amzn-requestid: 0e300efffe3798d6-000061ae-0000df84-eb3bed7730832005-3728d8a5-1 < content-type: multipart/related; boundary=------abcde123; type=application/json < 2017-12-28 07:34:28.708 [ b] 0 HTTP2Transport:processNextOutgoingMessage 2017-12-28 07:34:28.708 [ b] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2 2017-12-28 07:34:28.708 [ b] 9 HTTP2Transport:insertActiveStream:handle=0x7f64026b40
Found bundle for host avs-alexa-na.amazon.com: 0x7f64001760 [can multiplex]
Conn: 0 (0x7f64000bb0) Receive pipe weight: (-1/0), penalized: FALSE
Multiplexed connection found!
Found connection 0, with requests in the pipe (1)
Re-using existing connection! (#0) with host avs-alexa-na.amazon.com
Using Stream ID: 3 (easy handle 0x7f64026b40)
< HTTP/2 204 < access-control-allow-origin: * < x-amzn-requestid: 0e300efffe3798d6-000061ae-0000df84-eb3bed7730832005-3728d8a5-3 < 2017-12-28 07:34:30.256 [ b] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS_NO_CONTENT 2017-12-28 07:34:30.256 [ b] 0 PostConnectSynchronize:onConnected() 2017-12-28 07:34:30.256 [ b] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED 2017-12-28 07:34:30.256 [ 5] 9 PostConnectSynchronize:Exiting postConnectLoop thread ########################################
Alexa is currently idle!
########################################
2017-12-28 07:34:30.258 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST [2017-12-28 07:34:30.260] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:68] 2017-12-28 07:34:30.262 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=204 2017-12-28 07:34:30.262 [ b] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1 2017-12-28 07:34:30.262 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT add message observer for: tonly.key.message [2017-12-28 07:34:30.264] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:30.268] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:30.272] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_enter_idle [2017-12-28 07:34:30.276] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:30.280] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_enter_idle [2017-12-28 07:34:30.283] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_connected_server_success [2017-12-28 07:34:30.283] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:43] The audio /usr/share/voice/amazon_connected_success.mp3 will be played [2017-12-28 07:34:30.283] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_connected_server_success [2017-12-28 07:34:30.287] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:83] current activity is: IDLE 2017-12-28 07:34:30.287 [ c] 1 FocusManager:acquireChannel:channelName=LocalAudioChannel,activityId=LocalTonlyAlertAudioPlayer.Play 2017-12-28 07:34:30.287 [ d] I TonlyAudioPlayer:onFocusChanged:newFocus=FOREGROUND [2017-12-28 07:34:30.291] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:156] executeOnFocusChanged from NONE to FOREGROUND [2017-12-28 07:34:30.291] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:172] executeOnFocusChanged: action=playNextItem 2017-12-28 07:34:30.291 [ c] 9 MediaPlayer:setSourceCalled:sourceType=istream 2017-12-28 07:34:30.292 [ 2] 0 MediaPlayer:handleSetSourceCalled 2017-12-28 07:34:30.292 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements ####### # # # #####
2017-12-28 07:34:30.329 [ c] 9 MediaPlayer:playCalled 2017-12-28 07:34:30.329 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=1,currentId=1 2017-12-28 07:34:30.330 [ 2] 0 MediaPlayer:handlePlay:attemptBuffering=0 2017-12-28 07:34:30.381 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.381 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:30.382 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.383 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:30.384 [ e] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:30.384 [ 2] 9 BaseStreamSource:handleNeedDataCalled 2017-12-28 07:34:30.384 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=15 2017-12-28 07:34:30.385 [ 2] 9 IStreamSource:read:size=4096,pos=4096,eof=false 2017-12-28 07:34:30.386 [ 2] 9 IStreamSource:read:size=4096,pos=8192,eof=false 2017-12-28 07:34:30.386 [ 2] 9 IStreamSource:read:size=1454,pos=-1,eof=true 2017-12-28 07:34:30.386 [ 2] 9 IStreamSource:read:size=0,pos=-1,eof=true 2017-12-28 07:34:30.386 [ 2] 9 BaseStreamSource:signalEndOfDataCalled 2017-12-28 07:34:30.386 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=15 2017-12-28 07:34:30.406 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.406 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.413 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.414 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.424 [ e] 9 MediaPlayer:onPadAddedCalled 2017-12-28 07:34:30.425 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled [ 3857.144373] <1>.(1)[9171:src:src]<tas5760_hw_params,262> ####### 2017-12-28 07:34:30.441 [ 2] 9 [ 3857.145711] <1>.(1)[9171:src:src]<tas5760_hw_params,266> ####### shoutdown gpio set high MediaPlayer:mess[ 3857.146809] <1>.(1)[9171:src:src]<tas5760_hw_params,270> ####### sleep gpio set low ageReceived:mess[ 3857.147982] <1>.(1)[9171:src:src]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_hw_params HDMI rate=22050, format=2, channels=1, period_size=232, buffer_size=4408, buffer_bytes=8816 ageType=state-changed 2017-12-2[ 3857.150527] <1>.(1)[9171:src:src]<tas5760_hw_prepare,279> ####### 8 07:34:30.441 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start 2017-12-28 07:34:30.456 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:30.456 [ 2] 9 MediaPlayer:messageReceived:messageType=latency 2017-12-28 07:34:30.457 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:30.457 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:30.457] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=1 [ 3857.165312] <0>-(0)[9174:src:src]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger HDMI cmd = 1 2017-12-28 07:34:30.462 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:30.462 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:30.462] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=1 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock 2017-12-28 07:34:30.463 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:30.464 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:30.464] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=1 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.464 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.467 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.468 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.468 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.468 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.468 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:30.468 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING 2017-12-28 07:34:30.468 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=1 [2017-12-28 07:34:30.468] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:260] onPlaybackStarted: id=1 2017-12-28 07:34:30.469 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:30.469 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:30.469] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=1 2017-12-28 07:34:32.398 [ e] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:32.398 [ 2] 9 BaseStreamSource:handleNeedDataCalled 2017-12-28 07:34:32.817 [ 2] 9 MediaPlayer:messageReceived:messageType=eos 2017-12-28 07:34:32.817 [ 2] 9 BaseStreamSource:~BaseStreamSource 2017-12-28 07:34:32.817 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=1 [2017-12-28 07:34:32.817] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:272] onPlaybackFinished: id=1 2017-12-28 07:34:32.817 [ 2] 9 MediaPlayer:tearDownTransientPipel[ 3859.525565] <2>-(2)[9121:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger HDMI cmd = 0 ineElements 201[ 3859.527206] <2>.(2)[9121:tl_avs_sample]<tas5760_hw_prepare,279> ####### 7-12-28 07:34:32[ 3859.528066] <2>.(2)[9121:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_hdmi_prepare prepared already .820 [ c] 1 FocusManager:releaseChannel:channelName=LocalAudioChannel 2017-12-28 07:34:32.820 [ d] I TonlyAudioPlayer:onFocusChanged:newFocus=NONE [2017-12-28 07:34:32.821] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:156] executeOnFocusChanged from FOREGROUND to NONE [ 3859.547003] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,287> ####### [ 3859.547883] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,291> ####### shoutdown gpio set low [ 3859.552221] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,295> ####### sleep gpio set high; gpio is 388 [2017-12-28 07:34:37.057] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/KeywordsDetector/src/SensoryKeywordDetector.cpp:112] keyword detected [2017-12-28 07:34:37.057] [tonly_avs_app] [INFO] [src/KeywordObserver.cpp:54] wake up by key word [2017-12-28 07:34:37.061] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot 2017-12-28 07:34:37.067 [ 9] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING 2017-12-28 07:34:37.067 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING [2017-12-28 07:34:37.067] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] 2017-12-28 07:34:37.067 [ 6] 9 SpeakerManager:provideStateCalled get message: type:message.type.robot content:robot_wakeup_from_ui ############################
Listening...
############################
[2017-12-28 07:34:37.067] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:43] The audio /usr/share/voice/ful_ui_wakesound.mp3 will be played 2017-12-28 07:34:37.068 [ 7] 9 SpeakerManager:executeProvideStateCalled 2017-12-28 07:34:37.068 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:37.068 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled 2017-12-28 07:34:37.068 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:37.069 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled 2017-12-28 07:34:37.069 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled 2017-12-28 07:34:37.069 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled [2017-12-28 07:34:37.069] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_wakeup_from_ui [2017-12-28 07:34:37.069] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:83] current activity is: FINISHED 2017-12-28 07:34:37.070 [ c] 1 FocusManager:acquireChannel:channelName=LocalAudioChannel,activityId=LocalTonlyAlertAudioPlayer.Play 2017-12-28 07:34:37.070 [ d] I TonlyAudioPlayer:onFocusChanged:newFocus=FOREGROUND [2017-12-28 07:34:37.070] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:156] executeOnFocusChanged from NONE to FOREGROUND [2017-12-28 07:34:37.070] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:172] executeOnFocusChanged: action=playNextItem 2017-12-28 07:34:37.070 [ c] 9 MediaPlayer:setSourceCalled:sourceType=istream [2017-12-28 07:34:37.071] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:37.071] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_start_capture [2017-12-28 07:34:37.072] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:37.072] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_start_capture 2017-12-28 07:34:37.072 [ 7] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true 2017-12-28 07:34:37.072 [ 7] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false 2017-12-28 07:34:37.073 [ 2] 0 MediaPlayer:handleSetSourceCalled 2017-12-28 07:34:37.073 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2017-12-28 07:34:37.074 [ c] 9 MediaPlayer:playCalled 2017-12-28 07:34:37.074 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume"\:100\,"muted"\:false},namespace=Speaker,name=VolumeState 2017-12-28 07:34:37.074 [ 6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]} 2017-12-28 07:34:37.074 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=2,currentId=2 2017-12-28 07:34:37.074 [ 2] 0 MediaPlayer:handlePlay:attemptBuffering=0 2017-12-28 07:34:37.074 [ 9] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]} 2017-12-28 07:34:37.077 [ 9] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize 2017-12-28 07:34:37.077 [ 9] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=d6e90b4b-b31b-4cb5-adf7-f30b6d387c6b 2017-12-28 07:34:37.077 [ d] I TonlyAudioPlayer:onFocusChanged:newFocus=BACKGROUND 2017-12-28 07:34:37.088 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC 2017-12-28 07:34:37.089 [ 10] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.118 [ 9] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId [2017-12-28 07:34:37.119] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:156] 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed executeOnFocusChanged from FOREGROUND to BACKGROUND 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed [2017-12-28 07:34:37.119] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:218] 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed executeOnFocusChanged: action=pauseMediaPlayer 2017-12-28 07:34:37.119 [ c] 9 MediaPlayer:pausedCalled 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.119 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:37.120 [ 2] 9 BaseStreamSource:handleNeedDataCalled 2017-12-28 07:34:37.120 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=25 2017-12-28 07:34:37.120 [ 2] 0 MediaPlayer:handlePauseCalled:idPassed=2,currentId=2 2017-12-28 07:34:37.120 [ 2] 9 MediaPlayer:handlePauseCalled:info=playOrResumePending 2017-12-28 07:34:37.119 [ 9] 0 EventBuilder:bu[ 3863.851401] <1>.(1)[9175:src:src]<tas5760_hw_params,262> ####### ildJsonEventStri[ 3863.852268] <1>.(1)[9175:src:src]<tas5760_hw_params,266> ####### shoutdown gpio set high ng:messageId=e2c[ 3863.853531] <1>.(1)[9175:src:src]<tas5760_hw_params,270> ####### sleep gpio set low 089e1-6bf8-464c-[ 3863.854630] <1>.(1)[9175:src:src]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_hw_params HDMI rate=48000, format=2, channels=2, period_size=480, buffer_size=9600, buffer_bytes=38400 8727-9e06b10ea5ee,namespace=Spee[ 3863.857170] <1>.(1)[9175:src:src]<tas5760_hw_prepare,279> ####### chRecognizer,name=Recognize 2017-12-28 07:34:37.121 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND 2017-12-28 07:34:37.121 [ 9] 9 HTTP2Transport:enqueueRequest 2017-12-28 07:34:37.121 [ 2] 9 IStreamSource:read:size=4096,pos=4096,eof=false 2017-12-28 07:34:37.121 [ 2] 9 IStreamSource:read:size=4096,pos=8192,eof=false 2017-12-28 07:34:37.121 [ 2] 9 IStreamSource:read:size=4096,pos=12288,eof=false 2017-12-28 07:34:37.121 [ 2] 9 IStreamSource:read:size=4096,pos=16384,eof=false 2017-12-28 07:34:37.122 [ 2] 9 IStreamSource:read:size=4096,pos=20480,eof=false 2017-12-28 07:34:37.122 [ 2] 9 IStreamSource:read:size=4096,pos=24576,eof=false 2017-12-28 07:34:37.122 [ 2] 9 IStreamSource:read:size=3309,pos=-1,eof=true 2017-12-28 07:34:37.122 [ 2] 9 IStreamSource:read:size=0,pos=-1,eof=true 2017-12-28 07:34:37.122 [ 2] 9 BaseStreamSource:signalEndOfDataCalled 2017-12-28 07:34:37.122 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=25 2017-12-28 07:34:37.125 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.125 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.127 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.127 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.129 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.129 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.130 [ 2] 9 MediaPlayer:messageReceived:messageType=duration-changed 2017-12-28 07:34:37.132 [ 10] 9 MediaPlayer:onPadAddedCalled 2017-12-28 07:34:37.132 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled 2017-12-28 07:34:37.139 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.139 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start 2017-12-28 07:34:37.156 [ 2] 9 MediaPlayer:messageReceived:messageType=latency 2017-12-28 07:34:37.156 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:37.157 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:37.157 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:37.157] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=2 2017-12-28 07:34:37.157 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:37.157 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:37.157] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=2 2017-12-28 07:34:37.158 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:37.158 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:37.158] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=2 2017-12-28 07:34:37.158 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.158 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.158 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:37.158 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=VOID_PENDING 2017-12-28 07:34:37.158 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=2 [2017-12-28 07:34:37.158] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:260] onPlaybackStarted: id=2 2017-12-28 07:34:37.158 [ 2] 0 MediaPlayer:callingOnPlaybackPaused:currentId=2 [2017-12-28 07:34:37.159] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:284] onPlaybackPaused: id=2 2017-12-28 07:34:37.161 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done 2017-12-28 07:34:37.179 [ b] 0 HTTP2Transport:processNextOutgoingMessage 2017-12-28 07:34:37.179 [ b] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=2 2017-12-28 07:34:37.179 [ b] 9 HTTP2Transport:insertActiveStream:handle=0x7f64026b40
2017-12-28 07:34:41.370 [ 11] 0 MessageInterpreter:receive:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0:No dialogRequestId attached to message. 2017-12-28 07:34:41.370 [ 11] I DirectiveSequencer:onDirective:directive={"namespace\:"SpeechRecognizer"\,name\:"StopCapture"\,messageId\:"edde523c-36a9-46ff-95f7-bb5d20539fd0"\,dialogRequestId\:""} 2017-12-28 07:34:41.371 [ 12] I DirectiveRouter:preHandleDirective:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0,action=calling 2017-12-28 07:34:41.371 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0 2017-12-28 07:34:41.371 [ 13] I DirectiveRouter:handleDirective:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0,action=calling 2017-12-28 07:34:41.371 [ 9] 0 AudioInputProcessor:stopCapture:stopImmediately=true 2017-12-28 07:34:41.371 [ 9] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY 2017-12-28 07:34:41.371 [ 9] 0 DirectiveProcessor:onHandlingCompeted:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0,directiveBeingPreHandled=(nullptr) 2017-12-28 07:34:41.371 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING 2017-12-28 07:34:41.371 [ 9] 0 CapabilityAgent:removingMessageIdFromMap:messageId=edde523c-36a9-46ff-95f7-bb5d20539fd0 ###########################
Thinking...
###########################
[2017-12-28 07:34:41.372] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:41.373] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_start_recognizing [2017-12-28 07:34:41.373] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:41.373] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_start_recognizing 2017-12-28 07:34:41.380 [ b] I InProcessAttachmentReader:readFailed:reason=SDS is closed < HTTP/2 200 < access-control-allow-origin: * < x-amzn-requestid: 0e300efffe3798d6-000061ae-0000df84-eb3bed7730832005-3728d8a5-5 < content-type: multipart/related;boundary=------abcde123;start=metadata.1514407861362;type="application/json" < 2017-12-28 07:34:42.899 [ 11] I DirectiveSequencer:onDirective:directive={"namespace\:"SpeechSynthesizer"\,name\:"Speak"\,messageId\:"2c80211f-b6e4-4d58-9bec-87719f07a9cc"\,dialogRequestId\:"d6e90b4b-b31b-4cb5-adf7-f30b6d387c6b"} 2017-12-28 07:34:42.900 [ 12] I DirectiveRouter:preHandleDirective:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc,action=calling 2017-12-28 07:34:42.900 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:42.901 [ 12] 9 SpeechSynthesizer:preHandleDirective:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:42.901 [ 8] 0 SpeechSynthesizer:executePreHandle:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:42.901 [ 13] I DirectiveRouter:handleDirective:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc,action=calling 2017-12-28 07:34:42.901 [ 13] 9 SpeechSynthesizer:handleDirective:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:42.906 [ 8] 0 SpeechSynthesizer:executeHandle:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:42.906 [ 8] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak 2017-12-28 07:34:42.907 [ d] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND 2017-12-28 07:34:42.907 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE 2017-12-28 07:34:42.907 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus 2017-12-28 07:34:42.908 [ 9] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE 2017-12-28 07:34:42.908 [ b] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS 2017-12-28 07:34:42.907 [ 8] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING 2017-12-28 07:34:42.908 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=5,result=200 2017-12-28 07:34:42.909 [ 8] 9 SpeechSynthesizer:startPlaying 2017-12-28 07:34:42.909 [ 8] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader 2017-12-28 07:34:42.909 [ b] 0 HTTP2StreamPool:releaseStream:streamId=5,numAcquiredStreams=1 2017-12-28 07:34:42.909 [ 2] 0 MediaPlayer:handleSetSourceCalled 2017-12-28 07:34:42.909 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2017-12-28 07:34:42.910 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE ########################################
Alexa is currently idle!
########################################
[2017-12-28 07:34:42.912] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:42.913] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_enter_idle [2017-12-28 07:34:42.914] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:42.914] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_enter_idle 2017-12-28 07:34:42.915 [ 8] 9 MediaPlayer:playCalled 2017-12-28 07:34:42.916 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=3,currentId=3 2017-12-28 07:34:42.916 [ 2] 0 MediaPlayer:handlePlay:attemptBuffering=0 2017-12-28 07:34:42.927 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=warning 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.928 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.929 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:42.929 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.929 [ 14] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:42.929 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status 2017-12-28 07:34:42.929 [ 2] 9 BaseStreamSource:handleNeedDataCalled 2017-12-28 07:34:42.929 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=30 2017-12-28 07:34:42.929 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0 2017-12-28 07:34:42.930 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0 2017-12-28 07:34:42.930 [ 2] 9 AttachmentReaderSource:read:size=205,status=0 2017-12-28 07:34:42.930 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed 2017-12-28 07:34:42.930 [ 2] 9 AttachmentReaderSource:read:size=0,status=3 2017-12-28 07:34:42.930 [ 2] 9 BaseStreamSource:signalEndOfDataCalled 2017-12-28 07:34:42.930 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=30 2017-12-28 07:34:42.934 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.934 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.938 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.938 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.939 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.939 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.941 [ 14] 9 MediaPlayer:onPadAddedCalled 2017-12-28 07:34:42.942 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled 2017-12-28 07:34:42.945 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.945 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:42.946 [ 2] 0 MediaPlayer:callingOnTags 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:42.946 [ 2] 0 MediaPlayer:callingOnTags 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.946 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING 2017-12-28 07:34:42.947 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done 2017-12-28 07:34:42.947 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock 2017-12-28 07:34:42.948 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.948 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:42.949 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING 2017-12-28 07:34:42.949 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=3 2017-12-28 07:34:42.949 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=3 2017-12-28 07:34:42.949 [ 8] 0 SpeechSynthesizer:executePlaybackStarted 2017-12-28 07:34:42.949 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 2017-12-28 07:34:42.950 [ 8] 9 MediaPlayer:getOffsetCalled 2017-12-28 07:34:42.950 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3 2017-12-28 07:34:42.992 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Knowledge#ACRI#16ce3398-acf5-48cb-8152-7812465dafb5#Alexa3P\:1.0/2017/12/28/07/bfa59807668d446da584ad0cef5dc462/34\:37\:\:TNIH_2V.0eeb303a-00b6-4499-abf7-6d4e5898dd44ZXV/0"\,"offsetInMilliseconds"\:44\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState 2017-12-28 07:34:42.992 [ 8] 0 EventBuilder:buildJsonEventString:messageId=a771b16c-3843-41df-bb49-46c9658857e6,namespace=SpeechSynthesizer,name=SpeechStarted 2017-12-28 07:34:42.992 [ 8] 9 HTTP2Transport:enqueueRequest 2017-12-28 07:34:42.993 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=SPEAKING ###########################
Speaking...
###########################
2017-12-28 07:34:42.994 [ d] 9 SpeechSynthesizer:onFocusChangedSuccess [2017-12-28 07:34:42.994] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:42.999] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_start_speeching [2017-12-28 07:34:43.002] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:43.004] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_start_speeching 2017-12-28 07:34:43.010 [ b] 0 HTTP2Transport:processNextOutgoingMessage 2017-12-28 07:34:43.010 [ b] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2 2017-12-28 07:34:43.010 [ b] 9 HTTP2Transport:insertActiveStream:handle=0x7f64026b40
2017-12-28 07:34:43.092 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:43.092 [ 2] 0 MediaPlayer:callingOnTags < HTTP/2 204 < access-control-allow-origin: * < x-amzn-requestid: 0e300efffe3798d6-000061ae-0000df84-eb3bed7730832005-3728d8a5-7 < 2017-12-28 07:34:43.370 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204 2017-12-28 07:34:43.371 [ b] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1 2017-12-28 07:34:43.371 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT 2017-12-28 07:34:44.268 [ 14] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:44.268 [ 2] 9 BaseStreamSource:handleNeedDataCalled 2017-12-28 07:34:44.316 [ 2] 9 MediaPlayer:messageReceived:messageType=eos 2017-12-28 07:34:44.316 [ 2] 9 BaseStreamSource:~BaseStreamSource 2017-12-28 07:34:44.316 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=3 2017-12-28 07:34:44.316 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=3 2017-12-28 07:34:44.316 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2017-12-28 07:34:44.317 [ 8] 0 SpeechSynthesizer:executePlaybackFinished 2017-12-28 07:34:44.317 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 2017-12-28 07:34:44.317 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Knowledge#ACRI#16ce3398-acf5-48cb-8152-7812465dafb5#Alexa3P\:1.0/2017/12/28/07/bfa59807668d446da584ad0cef5dc462/34\:37\:\:TNIH_2V.0eeb303a-00b6-4499-abf7-6d4e5898dd44ZXV/0"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState 2017-12-28 07:34:44.317 [ 8] 9 SpeechSynthesizer:releaseForegroundFocus 2017-12-28 07:34:44.317 [ 8] 1 FocusManager:releaseChannel:channelName=Dialog 2017-12-28 07:34:44.318 [ 8] 0 EventBuilder:buildJsonEventString:messageId=a215bce5-ed16-484d-a184-a03daa12c1a9,namespace=SpeechSynthesizer,name=SpeechFinished 2017-12-28 07:34:44.318 [ 8] 9 HTTP2Transport:enqueueRequest 2017-12-28 07:34:44.318 [ 8] 9 SpeechSynthesizer:setHandlingCompleted 2017-12-28 07:34:44.318 [ 8] 0 DirectiveProcessor:onHandlingCompeted:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc,directiveBeingPreHandled=(nullptr) 2017-12-28 07:34:44.318 [ 8] 0 CapabilityAgent:removingMessageIdFromMap:messageId=2c80211f-b6e4-4d58-9bec-87719f07a9cc 2017-12-28 07:34:44.319 [ d] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE 2017-12-28 07:34:44.319 [ d] I TonlyAudioPlayer:onFocusChanged:newFocus=FOREGROUND [2017-12-28 07:34:44.320] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:156] executeOnFocusChanged from BACKGROUND to FOREGROUND [2017-12-28 07:34:44.320] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:178] executeOnFocusChanged: action=resumeMediaPlayer 2017-12-28 07:34:44.321 [ c] 9 MediaPlayer:resumeCalled 2017-12-28 07:34:44.324 [ 2] 0 MediaPlayer:handleResumeCalled:idPass[ 3871.045207] <1>-(1)[9195:src:src]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger HDMI cmd = 1 ed=2,currentId=2 2017-12-28 07:34:44.327 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock 2017-12-28 07:34:44.327 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.327 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.328 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.328 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.328 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.328 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.329 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.329 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.329 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.329 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.329 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed 2017-12-28 07:34:44.330 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING 2017-12-28 07:34:44.337 [ 2] 9 MediaPlayer:messageReceived:messageType=tag 2017-12-28 07:34:44.337 [ 2] 0 MediaPlayer:callingOnTags [2017-12-28 07:34:44.337] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:308] onTags: id=2 2017-12-28 07:34:44.374 [ b] 0 HTTP2Transport:processNextOutgoingMessage 2017-12-28 07:34:44.374 [ b] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2 2017-12-28 07:34:44.374 [ b] 9 HTTP2Transport:insertActiveStream:handle=0x7f64026b40
2017-12-28 07:34:44.548 [ f] 0 DialogUXStateAggregator:setState:from=FINISHED,to=IDLE ########################################
Alexa is currently idle!
########################################
[2017-12-28 07:34:44.550] [tonly_avs_app] [INFO] [/home/sundq/workspace/tonly_t8c/tonlyUtils/MessageWrapper/src/DbusMessageWrapper.cpp:160] msg interface is: message.type.robot [2017-12-28 07:34:44.551] [tonly_avs_app] [INFO] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:27] get message: type:message.type.robot content:robot_enter_idle [2017-12-28 07:34:44.551] [tonly_avs_app] [ERR] [MessageObserver/src/LocalAuidoPlayMessageObserver.cpp:48] json exception: message:[json.exception.type_error.302] type must be string, but is null id:302 [2017-12-28 07:34:44.552] [tonly_avs_app] [INFO] [MessageObserver/src/LedMessageObserver.cpp:33] led recv message:robot_enter_idle 2017-12-28 07:34:44.592 [ 10] 9 BaseStreamSource:onNeedDataCalled:size=4096 2017-12-28 07:34:44.593 [ 2] 9 BaseStreamSource:handleNeedDataCalled < HTTP/2 204 < access-control-allow-origin: * < x-amzn-requestid: 0e300efffe3798d6-000061ae-0000df84-eb3bed7730832005-3728d8a5-9 < 2017-12-28 07:34:44.802 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204 2017-12-28 07:34:44.802 [ b] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1 2017-12-28 07:34:44.802 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT 2017-12-28 07:34:45.009 [ 2] 9 MediaPlayer:messageReceived:messageType=eos 2017-12-28 07:34:45.009 [ 2] 9 BaseStreamSource:~BaseStreamSource 2017-12-28 07:34:45.009 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=2 [2017-12-28 07:34:45.009] [tonly_avs_app] [INFO] [src/TonlyAudioPlayer.cpp:272] onPlaybackFinished: id=2 2017-12-28 07:34:45.012 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2017-12-28 07:34:45.012 [ c] E TonlyAudioPlayer:executeOnPlaybackFinishedFailed:reason=notPlaying,m_currentActivity=PAUSED [ 3871.726793] <1>-(1)[9121:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger HDMI cmd = 0 [ 3871.728135] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_prepare,279> ####### [ 3871.734079] <1>.(1)[9121:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_hdmi_prepare prepared already [ 3871.753684] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,287> ####### [ 3871.754564] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,291> ####### shoutdown gpio set low [ 3871.755686] <1>.(1)[9121:tl_avs_sample]<tas5760_hw_shutdown,295> ####### sleep gpio set high; gpio is 388
^C[ 3963.597624] <1>-(1)[9151:tl_avs_sample]mtk-afe-pcm 11140000.audio-controller: mt8167_afe_dais_trigger AWB cmd = 0
root@aud8516-slc:/data# wlan0: WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP] [WIFI_MW] Received event: <3>WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP]
wlan0: WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP] [WIFI_MW] Received event: <3>WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP]
wlan0: WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP] [WIFI_MW] Received event: <3>WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP]
wlan0: WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP] [WIFI_MW] Received event: <3>WPA: Group rekeying completed with 60:45:cb:65:bf:c0 [GTK=CCMP]