Created
November 9, 2017 12:44
-
-
Save boyce-xx/5936c8d69865d1caf635d2a753d77407 to your computer and use it in GitHub Desktop.
Can't get dialogRequestId in directive when playing shopping list
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| 2017-11-09 12:37:59.443 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:00.444 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:01.445 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:02.445 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:03.446 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:04.447 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:05.448 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:06.448 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:07.449 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| *** testKeyWordObserverDebug:: onKeyWordDetected | |
| openFMDuringTest : isFMOpened = 0 | |
| stopMediaPlayer 11111 | |
| stopLocalMediaPlayer 222 | |
| 2017-11-09 12:38:07.470 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:07.470 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:07.470 [ 2] E MediaPlayer:handleStopFailed | |
| stopSpeakMediaPlayer 33333 | |
| 2017-11-09 12:38:07.471 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:07.471 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:07.471 [ 2] E MediaPlayer:handleStopFailed | |
| stopMediaPlayer m_rendererMediaPlayer | |
| 2017-11-09 12:38:07.471 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:07.472 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:07.472 [ 2] E MediaPlayer:handleStopFailed | |
| stopMediaPlayer 44444 | |
| Nut2AVS King:: testKeyWordObserverDebug::onKeyWordDetected.....beginIndex = 413760, endIndex = 423360, | |
| ___________________________________AIP isExpectDirectStatus: 0 | |
| --> handleOneSpeechBySensory::readOffset = 287232 | |
| --> RingBufferConsumer m_read_offset = 287232 | |
| exitAVS = 0, m_wisStatusChanged->isStopCaptureFlag = 0, isRecordingFinish = 0 | |
| Start to open < Listening > LED --> 2017-11-09 20:38:07:478 | |
| consumer is done | |
| ##### Start recording! | |
| Nut2AVS King:: wakeWordTrigger::onKeyWordDetected.....beginIndex = 1546560, endIndex = 1555920, | |
| Nut2AVS King:: wakeWordTrigger::Sensory recognize.....beginIndex = 1546560, endIndex = 1555920 | |
| *** AudioInputProcessor::executeRecognize initiatorPayloadJson="wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17360} | |
| 2017-11-09 12:38:07.677 [ 9] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING | |
| m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:07.677 [ c] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING | |
| 5 | |
| current mediaPlayer voluem value: 5 --> 2017-11-09 20:38:07:739 | |
| 2017-11-09 12:38:07.739 [ 6] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume"\:50\,"muted"\:0},namespace=Speaker,name=VolumeState | |
| 2017-11-09 12:38:07.740 [ 6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#2163f4da-847a-30d2-9b44-fdfd7dcbd3cc14ffd184-87c1-4d47-bf36-f48ce898fa4c"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:50\,"muted"\:0}}]} | |
| 2017-11-09 12:38:07.741 [ 9] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#2163f4da-847a-30d2-9b44-fdfd7dcbd3cc14ffd184-87c1-4d47-bf36-f48ce898fa4c"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:50\,"muted"\:0}}]} | |
| 2017-11-09 12:38:07.741 [ 9] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=b7bd2cf1-2319-4e3f-bd01-292ffd9229ab,newValue=9e81da2a-ac77-4108-b7a6-57deb0329fae | |
| 2017-11-09 12:38:07.741 [ 9] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=b7bd2cf1-2319-4e3f-bd01-292ffd9229ab | |
| 2017-11-09 12:38:07.742 [ 9] 0 EventBuilder:buildJsonEventString:messageId=8100d373-161c-4ce6-91ab-1f55f0d42e67,namespace=SpeechRecognizer,name=Recognize | |
| AudioInputProcessor::executeOnFocusChanged*********************** | |
| 2017-11-09 12:38:07.743 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND | |
| AVSConnectionManager::sendMessage {"context":[{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#2163f4da-847a-30d2-9b44-fdfd7dcbd3cc14ffd184-87c1-4d47-bf36-f48ce898fa4c","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}},{"header":{"namespace":"Speaker","name":"VolumeState"},"payload":{"volume":50,"muted":0}}],"event":{"header":{"namespace":"SpeechRecognizer","name":"Recognize","messageId":"8100d373-161c-4ce6-91ab-1f55f0d42e67","dialogRequestId":"9e81da2a-ac77-4108-b7a6-57deb0329fae"},"payload":{"profile":"NEAR_FIELD","format":"AUDIO_L16_RATE_16000_CHANNELS_1","initiator":{"type":"WAKEWORD","payload":{"wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17360}}}}}} | |
| 2017-11-09 12:38:07.822 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:07.822 [ 14] 0 HTTP2StreamPool:getStream:streamId=269,numAcquiredStreams=2 | |
| 2017-11-09 12:38:08.492 [ 32] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:11.107 [ e] 0 MessageInterpreter:receive:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:11.108 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451"}\,"payload"\:{}}} | |
| ...onDirectiveArrived... | |
| onDirectiveArrived: To stop recording... | |
| 2017-11-09 12:38:11.109 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451"}\,"payload"\:{}}} | |
| 2017-11-09 12:38:11.109 [ f] I DirectiveRouter:preHandleDirective:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451,action=calling | |
| End Recording!!!!!!! --> 2017-11-09 20:38:11:109 | |
| closing input, handle(2457) | |
| 2017-11-09 12:38:11.109 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451 | |
| closing input | |
| 2017-11-09 12:38:11.110 [ 10] I DirectiveRouter:handleDirective:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451,action=calling | |
| AudioInputProcessor::handleDirective --------StopCapture | |
| 2017-11-09 12:38:11.111 [ 9] 0 AudioInputProcessor:stopCapture:stopImmediately=true | |
| 2017-11-09 12:38:11.111 [ 9] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY | |
| 2017-11-09 12:38:11.112 [ 9] 0 DirectiveProcessor:onHandlingCompeted:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:11.112 [ 9] 0 CapabilityAgent:removingMessageIdFromMap:messageId=968c2dbd-fc0f-43d1-ae1b-2a1ab90c8451 | |
| 2017-11-09 12:38:11.112 [ c] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING | |
| 2017-11-09 12:38:11.118 [ 14] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| The audio recording has been saved to /data/nut2_avs/hal_rec_test_rec_original.wav. Please use adb pull to get the file and play it using audacity. The audio data has the following characteristics: | |
| Sample rate: 16000 | |
| Format: 1 | |
| Num channels: 1, handle(2457) | |
| Done with hal record test | |
| producer is done | |
| Start to open < Thinking > LED --> 2017-11-09 20:38:11:185 | |
| ---> Get Directive start! --> 2017-11-09 20:38:11:205 | |
| ##### End recording! | |
| 2017-11-09 12:38:11.794 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-233 | |
| 2017-11-09 12:38:12.069 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"Stop"\,"messageId"\:"31ce2937-5622-4efb-8eab-9610f0e18f39"\,"dialogRequestId"\:"9e81da2a-ac77-4108-b7a6-57deb0329fae"}\,"payload"\:{}}} | |
| ...onDirectiveArrived... | |
| AudioPlayer Stop directive | |
| 2017-11-09 12:38:12.070 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"Stop"\,"messageId"\:"31ce2937-5622-4efb-8eab-9610f0e18f39"\,"dialogRequestId"\:"9e81da2a-ac77-4108-b7a6-57deb0329fae"}\,"payload"\:{}}} | |
| 2017-11-09 12:38:12.071 [ f] I DirectiveRouter:preHandleDirective:messageId=31ce2937-5622-4efb-8eab-9610f0e18f39,action=calling | |
| 2017-11-09 12:38:12.072 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=31ce2937-5622-4efb-8eab-9610f0e18f39 | |
| 2017-11-09 12:38:12.072 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"fea4a343-4eca-4944-9844-03b1eb340692"\,"dialogRequestId"\:"9e81da2a-ac77-4108-b7a6-57deb0329fae"}\,"payload"\:{"url"\:"cid\:NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56_502137127"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:12.073 [ 10] I DirectiveRouter:handleDirective:messageId=31ce2937-5622-4efb-8eab-9610f0e18f39,action=calling | |
| 2017-11-09 12:38:12.073 [ 10] E AudioPlayer:handleDirective:name=Stop,messageId=31ce2937-5622-4efb-8eab-9610f0e18f39 | |
| AudioPlayer::handleDirective getName = Stop | |
| 2017-11-09 12:38:12.074 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"fea4a343-4eca-4944-9844-03b1eb340692"\,"dialogRequestId"\:"9e81da2a-ac77-4108-b7a6-57deb0329fae"}\,"payload"\:{"url"\:"cid\:NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56_502137127"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"}}} | |
| 2017-11-09 12:38:12.075 [ 10] E AudioPlayer:handleStopDirective | |
| 2017-11-09 12:38:12.075 [ 10] 0 DirectiveProcessor:onHandlingCompeted:messageId=31ce2937-5622-4efb-8eab-9610f0e18f39,directiveBeingPreHandled=fea4a343-4eca-4944-9844-03b1eb340692 | |
| 2017-11-09 12:38:12.075 [ f] I DirectiveRouter:preHandleDirective:messageId=fea4a343-4eca-4944-9844-03b1eb340692,action=calling | |
| 2017-11-09 12:38:12.076 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=fea4a343-4eca-4944-9844-03b1eb340692 | |
| 2017-11-09 12:38:12.076 [ 10] 0 CapabilityAgent:removingMessageIdFromMap:messageId=31ce2937-5622-4efb-8eab-9610f0e18f39 | |
| 2017-11-09 12:38:12.076 [ 8] E AudioPlayer:executeStop:playNextItem=false,m_currentActivity=IDLE | |
| 2017-11-09 12:38:12.077 [ 10] I DirectiveRouter:handleDirective:messageId=fea4a343-4eca-4944-9844-03b1eb340692,action=calling | |
| 2017-11-09 12:38:12.077 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=fea4a343-4eca-4944-9844-03b1eb340692 | |
| 2017-11-09 12:38:12.081 [ 14] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS | |
| 2017-11-09 12:38:12.081 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=269,result=200 | |
| 2017-11-09 12:38:12.081 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=fea4a343-4eca-4944-9844-03b1eb340692 | |
| 2017-11-09 12:38:12.082 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=269,numAcquiredStreams=1 | |
| AudioInputProcessor::executeOnFocusChanged*********************** | |
| 2017-11-09 12:38:12.082 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:12.083 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:12.083 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus | |
| executeResetState m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:12.083 [ 9] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE | |
| 2017-11-09 12:38:12.083 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:12.084 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| 2017-11-09 12:38:12.084 [ c] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:12.088 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=54,currentId=54 | |
| 2017-11-09 12:38:12.089 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:12.092 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:12.119 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:12.120 [ 2] 0 MediaPlayer:callingOnTags | |
| ---> Get Directive end! --> 2017-11-09 20:38:12:120 | |
| --> To handle the 0 directive: SpeechSynthesizer - Speak | |
| Wait for get response audio!!!!!!! --> 2017-11-09 20:38:12:120 | |
| 2017-11-09 12:38:12.130 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:12.132 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=54 | |
| 2017-11-09 12:38:12.132 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:12.133 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:12.134 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=54,currentId=54 | |
| 2017-11-09 12:38:12.135 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"\,"offsetInMilliseconds"\:14\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:12.136 [ 7] 0 EventBuilder:buildJsonEventString:messageId=ff26d557-6d17-417b-a795-5cf750fcb638,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"ff26d557-6d17-417b-a795-5cf750fcb638"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:12.183 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:12.183 [ 14] 0 HTTP2StreamPool:getStream:streamId=271,numAcquiredStreams=2 | |
| TestSpeechSynthesizerObserver::waitForNext------finished | |
| Playing AVS response audio via gstreamer..... --> 2017-11-09 20:38:12:229 | |
| Start to open < Speaking > LED --> 2017-11-09 20:38:12:229 | |
| Waiting for Mediaplayer finish1.................... currentSpeechSynthesizerState: 0 | |
| Media Player has finished.................... | |
| ^_^^^_^^^^_^^^^^^_^^^^^^_^^^^^^^^_^^^^ testCycleIndex: 1 | |
| executeResetState m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:12.556 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-235 | |
| 2017-11-09 12:38:12.557 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=271,result=204 | |
| 2017-11-09 12:38:12.558 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=271,numAcquiredStreams=1 | |
| 2017-11-09 12:38:12.558 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:12.600 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:13.131 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:14.132 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| Start execute handleOneSpeechBySensory() --> 2017-11-09 20:38:14:319 | |
| ___________________________________AIP isExpectDirectStatus: 0 | |
| ********** start hal_rec_test recording ********* | |
| -----> For Vick: qahw_load_module... | |
| start first record thread | |
| qahw_open_input_stream...... --> 2017-11-09 20:38:14:321 | |
| input opened, buffer 0xa62e1000, size 640, handle(2457) | |
| Please speak into the microphone for 15.000000 seconds, handle(2457) | |
| Start Recording!!!!!!! Start Recording!!!!!!! Start Recording!!!!!!! --> 2017-11-09 20:38:14:326 | |
| 2017-11-09 12:38:14.329 [ 33] I SensoryKeywordDetector:License expires on 01 Feb 2018 00:00:00 GMT | |
| 2017-11-09 12:38:14.331 [ 33] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days. | |
| ACDB -> send_audio_cal, acdb_id = 125, path = 1, app id = 0x11132, sample rate = 16000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| Failed to fetch the lookup information of the device 0000007D | |
| Error: ACDB AudProc vol returned = -19 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 16 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 125, Topology Id 112fb | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 0000007D | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 125 path = 1 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:14.796 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=54 | |
| 2017-11-09 12:38:14.796 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:14.797 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:14.797 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:14.799 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:14.799 [ 7] 0 EventBuilder:buildJsonEventString:messageId=032bb1b2-8db8-4d7a-b9f2-1d6e67afb772,namespace=SpeechSynthesizer,name=SpeechFinished | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"032bb1b2-8db8-4d7a-b9f2-1d6e67afb772"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#NamedListIntroductionWithSizePrompt.v1ba3dea1d-b59d-40b2-b79d-c4be51bede56"}}} | |
| 2017-11-09 12:38:14.800 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=fea4a343-4eca-4944-9844-03b1eb340692,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:14.801 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=fea4a343-4eca-4944-9844-03b1eb340692 | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:14.874 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:14.874 [ 14] 0 HTTP2StreamPool:getStream:streamId=273,numAcquiredStreams=2 | |
| 2017-11-09 12:38:14.989 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:14.989 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:14.990 [ 2] E MediaPlayer:handleStopFailed | |
| 2017-11-09 12:38:15.133 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:15.473 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-237 | |
| 2017-11-09 12:38:16.082 [ e] 0 MessageInterpreter:receive:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:16.082 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"6b4086e2-5d17-4f3d-8cc4-384f2208142d"}\,"payload"\:{"url"\:"cid\:82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df_1486976022"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:16.082 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"6b4086e2-5d17-4f3d-8cc4-384f2208142d"}\,"payload"\:{"url"\:"cid\:82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df_1486976022"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"}}} | |
| 2017-11-09 12:38:16.083 [ f] I DirectiveRouter:preHandleDirective:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d,action=calling | |
| 2017-11-09 12:38:16.083 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d | |
| 2017-11-09 12:38:16.083 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d | |
| 2017-11-09 12:38:16.083 [ 10] I DirectiveRouter:handleDirective:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d,action=calling | |
| 2017-11-09 12:38:16.089 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d | |
| 2017-11-09 12:38:16.090 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:16.090 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=273,result=200 | |
| 2017-11-09 12:38:16.091 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=273,numAcquiredStreams=1 | |
| 2017-11-09 12:38:16.091 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:16.092 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:16.096 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=55,currentId=55 | |
| 2017-11-09 12:38:16.098 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:16.100 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:16.124 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:16.125 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:16.134 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:16.136 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=55 | |
| 2017-11-09 12:38:16.137 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:16.138 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:16.138 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=55,currentId=55 | |
| 2017-11-09 12:38:16.139 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"\,"offsetInMilliseconds"\:13\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:16.140 [ 7] 0 EventBuilder:buildJsonEventString:messageId=7d8bc8a0-4cd8-45fe-99d6-c515f2336254,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"7d8bc8a0-4cd8-45fe-99d6-c515f2336254"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| 2017-11-09 12:38:16.192 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:16.192 [ 14] 0 HTTP2StreamPool:getStream:streamId=275,numAcquiredStreams=2 | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:16.470 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:16.562 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-239 | |
| 2017-11-09 12:38:16.562 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=275,result=204 | |
| 2017-11-09 12:38:16.562 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=275,numAcquiredStreams=1 | |
| 2017-11-09 12:38:16.562 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:17.135 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:18.136 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:19.136 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:19.550 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=55 | |
| 2017-11-09 12:38:19.550 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:19.550 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:19.551 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:19.551 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:19.551 [ 7] 0 EventBuilder:buildJsonEventString:messageId=67e433e7-77ef-4766-ae16-af17ff9f2256,namespace=SpeechSynthesizer,name=SpeechFinished | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"67e433e7-77ef-4766-ae16-af17ff9f2256"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"}}} | |
| 2017-11-09 12:38:19.552 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:19.552 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=6b4086e2-5d17-4f3d-8cc4-384f2208142d | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:19.573 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:19.573 [ 14] 0 HTTP2StreamPool:getStream:streamId=277,numAcquiredStreams=2 | |
| 2017-11-09 12:38:19.734 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:19.734 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:19.734 [ 2] E MediaPlayer:handleStopFailed | |
| *** testKeyWordObserverDebug:: onKeyWordDetected | |
| openFMDuringTest : isFMOpened = 0 | |
| stopMediaPlayer 11111 | |
| stopLocalMediaPlayer 222 | |
| 2017-11-09 12:38:20.100 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:20.100 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:20.100 [ 2] E MediaPlayer:handleStopFailed | |
| stopSpeakMediaPlayer 33333 | |
| 2017-11-09 12:38:20.101 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:20.101 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:20.101 [ 2] E MediaPlayer:handleStopFailed | |
| stopMediaPlayer m_rendererMediaPlayer | |
| 2017-11-09 12:38:20.101 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:20.101 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:20.102 [ 2] E MediaPlayer:handleStopFailed | |
| stopMediaPlayer 44444 | |
| Nut2AVS King:: testKeyWordObserverDebug::onKeyWordDetected.....beginIndex = 78480, endIndex = 87360, | |
| ___________________________________AIP isExpectDirectStatus: 0 | |
| --> handleOneSpeechBySensory::readOffset = 140960 | |
| --> RingBufferConsumer m_read_offset = 140960 | |
| exitAVS = 0, m_wisStatusChanged->isStopCaptureFlag = 0, isRecordingFinish = 0 | |
| Start to open < Listening > LED --> 2017-11-09 20:38:20:119 | |
| consumer is done | |
| ##### Start recording! | |
| Nut2AVS King:: wakeWordTrigger::onKeyWordDetected.....beginIndex = 1623840, endIndex = 1632960, | |
| Nut2AVS King:: wakeWordTrigger::Sensory recognize.....beginIndex = 1623840, endIndex = 1632960 | |
| *** AudioInputProcessor::executeRecognize initiatorPayloadJson="wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17120} | |
| 2017-11-09 12:38:20.306 [ 9] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING | |
| m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:20.307 [ c] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING | |
| 5 | |
| current mediaPlayer voluem value: 5 --> 2017-11-09 20:38:20:365 | |
| 2017-11-09 12:38:20.365 [ 6] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume"\:50\,"muted"\:0},namespace=Speaker,name=VolumeState | |
| 2017-11-09 12:38:20.366 [ 6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:50\,"muted"\:0}}]} | |
| 2017-11-09 12:38:20.367 [ 9] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:50\,"muted"\:0}}]} | |
| 2017-11-09 12:38:20.367 [ 9] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=9e81da2a-ac77-4108-b7a6-57deb0329fae,newValue=e43ebc40-67d5-412c-807c-25cab33ad606 | |
| 2017-11-09 12:38:20.367 [ 9] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=9e81da2a-ac77-4108-b7a6-57deb0329fae | |
| 2017-11-09 12:38:20.368 [ 9] 0 EventBuilder:buildJsonEventString:messageId=ea551ffd-2f11-4fde-a94b-2ace3a9133d7,namespace=SpeechRecognizer,name=Recognize | |
| AudioInputProcessor::executeOnFocusChanged*********************** | |
| 2017-11-09 12:38:20.369 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND | |
| AVSConnectionManager::sendMessage {"context":[{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#82a2b433-087a-34b1-a0a9-abd57085434236e0664a-a4dc-417f-91b1-ec6de1c4e0df","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}},{"header":{"namespace":"Speaker","name":"VolumeState"},"payload":{"volume":50,"muted":0}}],"event":{"header":{"namespace":"SpeechRecognizer","name":"Recognize","messageId":"ea551ffd-2f11-4fde-a94b-2ace3a9133d7","dialogRequestId":"e43ebc40-67d5-412c-807c-25cab33ad606"},"payload":{"profile":"NEAR_FIELD","format":"AUDIO_L16_RATE_16000_CHANNELS_1","initiator":{"type":"WAKEWORD","payload":{"wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17120}}}}}} | |
| 2017-11-09 12:38:20.802 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-241 | |
| 2017-11-09 12:38:20.803 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:20.803 [ 14] 0 HTTP2StreamPool:getStream:streamId=279,numAcquiredStreams=3 | |
| 2017-11-09 12:38:21.117 [ 34] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:21.589 [ e] 0 MessageInterpreter:receive:messageId=2dca958c-4016-4616-aadf-01c61f9ae173:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:21.590 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"2dca958c-4016-4616-aadf-01c61f9ae173"}\,"payload"\:{"url"\:"cid\:598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac_1731580980"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:21.590 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"2dca958c-4016-4616-aadf-01c61f9ae173"}\,"payload"\:{"url"\:"cid\:598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac_1731580980"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"}}} | |
| 2017-11-09 12:38:21.590 [ f] I DirectiveRouter:preHandleDirective:messageId=2dca958c-4016-4616-aadf-01c61f9ae173,action=calling | |
| 2017-11-09 12:38:21.590 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=2dca958c-4016-4616-aadf-01c61f9ae173 | |
| 2017-11-09 12:38:21.591 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=2dca958c-4016-4616-aadf-01c61f9ae173 | |
| 2017-11-09 12:38:21.591 [ 10] I DirectiveRouter:handleDirective:messageId=2dca958c-4016-4616-aadf-01c61f9ae173,action=calling | |
| 2017-11-09 12:38:21.597 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=2dca958c-4016-4616-aadf-01c61f9ae173 | |
| AudioInputProcessor::executeOnFocusChanged*********************** | |
| 2017-11-09 12:38:21.597 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:21.598 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:21.598 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus | |
| executeResetState m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:21.598 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:21.598 [ 9] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=IDLE | |
| 2017-11-09 12:38:21.598 [ c] 0 DialogUXStateAggregator:setState:from=LISTENING,to=IDLE | |
| 2017-11-09 12:38:21.598 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:21.602 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=56,currentId=56 | |
| 2017-11-09 12:38:21.604 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:21.922 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=277,result=200 | |
| 2017-11-09 12:38:21.924 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=277,numAcquiredStreams=2 | |
| 2017-11-09 12:38:21.928 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:21.944 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:21.946 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:21.959 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=56 | |
| 2017-11-09 12:38:21.960 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:21.960 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:21.961 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=56,currentId=56 | |
| 2017-11-09 12:38:21.962 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"\,"offsetInMilliseconds"\:15\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:21.963 [ 7] 0 EventBuilder:buildJsonEventString:messageId=82b757e3-b021-4b51-b584-24d6bd75cd0e,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"82b757e3-b021-4b51-b584-24d6bd75cd0e"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:22.302 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:22.335 [ 14] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:23.774 [ e] 0 MessageInterpreter:receive:messageId=64abf435-6529-4a95-a283-55f0673ebad3:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:23.774 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"64abf435-6529-4a95-a283-55f0673ebad3"}\,"payload"\:{}}} | |
| ...onDirectiveArrived... | |
| onDirectiveArrived: To stop recording... | |
| 2017-11-09 12:38:23.774 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"64abf435-6529-4a95-a283-55f0673ebad3"}\,"payload"\:{}}} | |
| 2017-11-09 12:38:23.775 [ f] I DirectiveRouter:preHandleDirective:messageId=64abf435-6529-4a95-a283-55f0673ebad3,action=calling | |
| 2017-11-09 12:38:23.775 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=64abf435-6529-4a95-a283-55f0673ebad3 | |
| End Recording!!!!!!! --> 2017-11-09 20:38:23:785 | |
| closing input, handle(2457) | |
| closing input | |
| ---> Get Directive start! --> 2017-11-09 20:38:23:826 | |
| The audio recording has been saved to /data/nut2_avs/hal_rec_test_rec_original.wav. Please use adb pull to get the file and play it using audacity. The audio data has the following characteristics: | |
| Sample rate: 16000 | |
| Format: 1 | |
| Num channels: 1, handle(2457) | |
| Done with hal record test | |
| producer is done | |
| Start to open < Thinking > LED --> 2017-11-09 20:38:23:837 | |
| ##### End recording! | |
| 2017-11-09 12:38:24.068 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-243 | |
| 2017-11-09 12:38:24.069 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:24.070 [ 14] 0 HTTP2StreamPool:getStream:streamId=281,numAcquiredStreams=3 | |
| 2017-11-09 12:38:24.399 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c"\,"dialogRequestId"\:"e43ebc40-67d5-412c-807c-25cab33ad606"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f_1677073169"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:24.401 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c"\,"dialogRequestId"\:"e43ebc40-67d5-412c-807c-25cab33ad606"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f_1677073169"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"}}} | |
| 2017-11-09 12:38:24.402 [ f] I DirectiveRouter:preHandleDirective:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c,action=calling | |
| 2017-11-09 12:38:24.402 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c | |
| 2017-11-09 12:38:24.403 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c | |
| 2017-11-09 12:38:24.418 [ 14] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS | |
| 2017-11-09 12:38:24.419 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=279,result=200 | |
| 2017-11-09 12:38:24.419 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=279,numAcquiredStreams=2 | |
| 2017-11-09 12:38:24.446 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-245 | |
| 2017-11-09 12:38:24.447 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=281,result=204 | |
| 2017-11-09 12:38:24.447 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=281,numAcquiredStreams=1 | |
| 2017-11-09 12:38:24.447 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| ---> Get Directive end! --> 2017-11-09 20:38:24:451 | |
| --> To handle the 0 directive: SpeechSynthesizer - Speak | |
| Wait for get response audio!!!!!!! --> 2017-11-09 20:38:24:451 | |
| Playing AVS response audio via gstreamer..... --> 2017-11-09 20:38:24:451 | |
| Start to open < Speaking > LED --> 2017-11-09 20:38:24:451 | |
| Waiting for Mediaplayer finish1.................... currentSpeechSynthesizerState: 0 | |
| Media Player has finished.................... | |
| ^_^^^_^^^^_^^^^^^_^^^^^^_^^^^^^^^_^^^^ testCycleIndex: 1 | |
| executeResetState m_expectingSpeechTimer.stop... | |
| 2017-11-09 12:38:24.743 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:25.547 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=56 | |
| 2017-11-09 12:38:25.549 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:25.549 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:25.550 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:25.552 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:25.558 [ 7] 0 EventBuilder:buildJsonEventString:messageId=aff84109-fd82-4ba2-a814-74b5c634912c,namespace=SpeechSynthesizer,name=SpeechFinished | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"aff84109-fd82-4ba2-a814-74b5c634912c"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#598a5cd1-ae73-396c-bc33-6070c37c1de5939c66f6-d67b-44e5-83f9-f38bb901b2ac"}}} | |
| 2017-11-09 12:38:25.569 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=2dca958c-4016-4616-aadf-01c61f9ae173,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:25.575 [ 10] I DirectiveRouter:handleDirective:messageId=64abf435-6529-4a95-a283-55f0673ebad3,action=calling | |
| AudioInputProcessor::handleDirective --------StopCapture | |
| 2017-11-09 12:38:25.576 [ 10] I DirectiveRouter:handleDirective:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c,action=calling | |
| 2017-11-09 12:38:25.576 [ 9] 0 DirectiveProcessor:onHandlingFailed:messageId=64abf435-6529-4a95-a283-55f0673ebad3,directiveBeingPreHandled=(nullptr),description=StopCapture only allowed in RECOGNIZING state. | |
| 2017-11-09 12:38:25.576 [ 9] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId | |
| 2017-11-09 12:38:25.576 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=2dca958c-4016-4616-aadf-01c61f9ae173 | |
| 2017-11-09 12:38:25.577 [ 9] 0 CapabilityAgent:removingMessageIdFromMap:messageId=64abf435-6529-4a95-a283-55f0673ebad3 | |
| 2017-11-09 12:38:25.577 [ 9] E AudioInputProcessor:executeStopCaptureFailed:reason=invalidState,expectedState=RECOGNIZING,state=IDLE | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 1 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:25.655 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:25.655 [ 14] 0 HTTP2StreamPool:getStream:streamId=283,numAcquiredStreams=2 | |
| 2017-11-09 12:38:25.737 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:25.738 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:25.738 [ 2] E MediaPlayer:handleStopFailed | |
| 2017-11-09 12:38:25.738 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c | |
| 2017-11-09 12:38:25.739 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:25.739 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:25.740 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:25.743 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:25.744 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=57,currentId=57 | |
| 2017-11-09 12:38:25.745 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:25.748 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:25.772 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:25.773 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:25.786 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=57 | |
| 2017-11-09 12:38:25.786 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:25.787 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:25.787 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=57,currentId=57 | |
| 2017-11-09 12:38:25.788 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"\,"offsetInMilliseconds"\:15\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:25.789 [ 7] 0 EventBuilder:buildJsonEventString:messageId=edbeb179-744a-41a4-a217-e384c9b17d9e,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"edbeb179-744a-41a4-a217-e384c9b17d9e"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:26.132 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:26.218 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-247 | |
| 2017-11-09 12:38:26.219 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:26.220 [ 14] 0 HTTP2StreamPool:getStream:streamId=285,numAcquiredStreams=3 | |
| Start execute handleOneSpeechBySensory() --> 2017-11-09 20:38:26:529 | |
| ___________________________________AIP isExpectDirectStatus: 0 | |
| ********** start hal_rec_test recording ********* | |
| -----> For Vick: qahw_load_module... | |
| start first record thread | |
| qahw_open_input_stream...... --> 2017-11-09 20:38:26:532 | |
| input opened, buffer 0xa62b8200, size 640, handle(2457) | |
| Please speak into the microphone for 15.000000 seconds, handle(2457) | |
| Start Recording!!!!!!! Start Recording!!!!!!! Start Recording!!!!!!! --> 2017-11-09 20:38:26:536 | |
| 2017-11-09 12:38:26.538 [ 35] I SensoryKeywordDetector:License expires on 01 Feb 2018 00:00:00 GMT | |
| 2017-11-09 12:38:26.539 [ 35] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days. | |
| ACDB -> send_audio_cal, acdb_id = 125, path = 1, app id = 0x11132, sample rate = 16000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| Failed to fetch the lookup information of the device 0000007D | |
| Error: ACDB AudProc vol returned = -19 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 16 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 125, Topology Id 112fb | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 0000007D | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 125 path = 1 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:26.744 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:26.809 [ e] 0 MessageInterpreter:receive:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:26.810 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"0947388d-fe1e-4eef-a53b-02914935d6e1"}\,"payload"\:{"url"\:"cid\:93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f_390978051"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:26.812 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"0947388d-fe1e-4eef-a53b-02914935d6e1"}\,"payload"\:{"url"\:"cid\:93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f_390978051"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"}}} | |
| 2017-11-09 12:38:26.812 [ f] I DirectiveRouter:preHandleDirective:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1,action=calling | |
| 2017-11-09 12:38:26.813 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1 | |
| 2017-11-09 12:38:26.814 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1 | |
| 2017-11-09 12:38:26.830 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=283,result=200 | |
| 2017-11-09 12:38:26.830 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=283,numAcquiredStreams=2 | |
| 2017-11-09 12:38:26.878 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-249 | |
| 2017-11-09 12:38:26.878 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=285,result=204 | |
| 2017-11-09 12:38:26.878 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=285,numAcquiredStreams=1 | |
| 2017-11-09 12:38:26.879 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:27.448 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=57 | |
| 2017-11-09 12:38:27.448 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:27.448 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:27.449 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:27.449 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:27.449 [ 7] 0 EventBuilder:buildJsonEventString:messageId=1630f668-0426-49c3-9692-3fafcb66b6bd,namespace=SpeechSynthesizer,name=SpeechFinished | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"1630f668-0426-49c3-9692-3fafcb66b6bd"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:NotificationsV4#ACRI#DeviceTTSRendererV4_95b50cf2-dd28-4930-b21b-3cc1cdaad82f"}}} | |
| 2017-11-09 12:38:27.450 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:27.450 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=36a9e7df-cc41-4e86-a3a5-04b28c3d4a3c | |
| 2017-11-09 12:38:27.451 [ 10] I DirectiveRouter:handleDirective:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1,action=calling | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:27.481 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:27.481 [ 14] 0 HTTP2StreamPool:getStream:streamId=287,numAcquiredStreams=2 | |
| 2017-11-09 12:38:27.631 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:27.631 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:27.632 [ 2] E MediaPlayer:handleStopFailed | |
| 2017-11-09 12:38:27.632 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1 | |
| 2017-11-09 12:38:27.633 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:27.633 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:27.633 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:27.637 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=58,currentId=58 | |
| 2017-11-09 12:38:27.638 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:27.640 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:27.662 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:27.663 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:27.675 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=58 | |
| 2017-11-09 12:38:27.675 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:27.676 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:27.676 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=58,currentId=58 | |
| 2017-11-09 12:38:27.677 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"\,"offsetInMilliseconds"\:13\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:27.677 [ 7] 0 EventBuilder:buildJsonEventString:messageId=6ba80e48-3b91-4ffa-8838-0878b9c9395e,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"6ba80e48-3b91-4ffa-8838-0878b9c9395e"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| 2017-11-09 12:38:27.745 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:27.920 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-251 | |
| 2017-11-09 12:38:27.922 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=287,result=204 | |
| 2017-11-09 12:38:27.924 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=287,numAcquiredStreams=1 | |
| 2017-11-09 12:38:27.925 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:27.926 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:27.928 [ 14] 0 HTTP2StreamPool:getStream:streamId=289,numAcquiredStreams=2 | |
| 2017-11-09 12:38:28.006 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:28.294 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-253 | |
| 2017-11-09 12:38:28.294 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=289,result=204 | |
| 2017-11-09 12:38:28.294 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=289,numAcquiredStreams=1 | |
| 2017-11-09 12:38:28.295 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:28.745 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:29.746 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:30.746 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:31.006 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=58 | |
| 2017-11-09 12:38:31.007 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:31.007 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:31.007 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:31.008 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:31.008 [ 7] 0 EventBuilder:buildJsonEventString:messageId=1a6bbc14-8b0e-440a-ac01-5d52410ddad9,namespace=SpeechSynthesizer,name=SpeechFinished | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"1a6bbc14-8b0e-440a-ac01-5d52410ddad9"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#93927e42-a3fe-3c7c-8c52-d08fe2864b60cf128a15-7e5c-404f-84c2-6244dd171c2f"}}} | |
| 2017-11-09 12:38:31.009 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1,directiveBeingPreHandled=(nullptr) | |
| 2017-11-09 12:38:31.010 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=0947388d-fe1e-4eef-a53b-02914935d6e1 | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:31.110 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:31.110 [ 14] 0 HTTP2StreamPool:getStream:streamId=291,numAcquiredStreams=2 | |
| 2017-11-09 12:38:31.202 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:31.202 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:31.203 [ 2] E MediaPlayer:handleStopFailed | |
| 2017-11-09 12:38:31.747 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:32.272 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-255 | |
| 2017-11-09 12:38:32.747 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:32.816 [ e] 0 MessageInterpreter:receive:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461:No dialogRequestId attached to message. | |
| 2017-11-09 12:38:32.817 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"16dbf3ab-f015-4bf4-a00c-7091118d7461"}\,"payload"\:{"url"\:"cid\:6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85_1260922811"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85"}}} | |
| ...onDirectiveArrived... | |
| ----------------- default directive | |
| 2017-11-09 12:38:32.817 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"16dbf3ab-f015-4bf4-a00c-7091118d7461"}\,"payload"\:{"url"\:"cid\:6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85_1260922811"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Lists#ACRI#6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85"}}} | |
| 2017-11-09 12:38:32.817 [ f] I DirectiveRouter:preHandleDirective:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461,action=calling | |
| 2017-11-09 12:38:32.817 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461 | |
| 2017-11-09 12:38:32.818 [ 7] 0 SpeechSynthesizer:executePreHandle:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461 | |
| 2017-11-09 12:38:32.818 [ 10] I DirectiveRouter:handleDirective:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461,action=calling | |
| 2017-11-09 12:38:32.823 [ 7] 0 SpeechSynthesizer:executeHandle:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461 | |
| 2017-11-09 12:38:32.824 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND | |
| 2017-11-09 12:38:32.824 [ 7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING | |
| 2017-11-09 12:38:32.824 [ 2] 0 MediaPlayer:handleSetSourceCalled | |
| >>>>>>>>>>>MediaPlayer::play 1 | |
| >>>>>>>>>>>MediaPlayer::play 2 | |
| 2017-11-09 12:38:32.828 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=59,currentId=59 | |
| 2017-11-09 12:38:32.829 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC | |
| 2017-11-09 12:38:33.747 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:34.748 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:34.824 [ 11] E SpeechSynthesizer:onFocusChangeFailed:reason=stateChangeTimeout,messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461 | |
| 2017-11-09 12:38:34.826 [ 11] 0 EventBuilder:buildJsonEventString:messageId=ea70df35-29c5-44c4-a8ff-093e44863603,namespace=System,name=ExceptionEncountered | |
| AVSConnectionManager::sendMessage {"context":[],"event":{"header":{"namespace":"System","name":"ExceptionEncountered","messageId":"ea70df35-29c5-44c4-a8ff-093e44863603"},"payload":{"unparsedDirective":"{\"directive\":{\"header\":{\"namespace\":\"SpeechSynthesizer\",\"name\":\"Speak\",\"messageId\":\"16dbf3ab-f015-4bf4-a00c-7091118d7461\"},\"payload\":{\"url\":\"cid:6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85_1260922811\",\"format\":\"AUDIO_MPEG\",\"token\":\"amzn1.as-ct.v1.Domain:Application:Lists#ACRI#6158ca44-7900-3750-ad08-cfa1c380ae8b47687c4c-20e6-4c7b-80ba-e5ab0c032e85\"}}}","error":{"type":"INTERNAL_ERROR","message":"stateChangeTimeout"}}}} | |
| 2017-11-09 12:38:34.827 [ 11] 0 DirectiveProcessor:onHandlingFailed:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461,directiveBeingPreHandled=(nullptr),description=stateChangeTimeout | |
| 2017-11-09 12:38:34.827 [ 11] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId | |
| 2017-11-09 12:38:34.827 [ 11] 0 CapabilityAgent:removingMessageIdFromMap:messageId=16dbf3ab-f015-4bf4-a00c-7091118d7461 | |
| 2017-11-09 12:38:34.832 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:34.832 [ 14] 0 HTTP2StreamPool:getStream:streamId=293,numAcquiredStreams=3 | |
| 2017-11-09 12:38:34.840 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=291,result=200 | |
| 2017-11-09 12:38:34.840 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=291,numAcquiredStreams=2 | |
| 2017-11-09 12:38:34.842 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed | |
| 2017-11-09 12:38:34.848 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:34.848 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:34.861 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=59 | |
| 2017-11-09 12:38:34.862 [ 7] 0 SpeechSynthesizer:executePlaybackStarted | |
| 2017-11-09 12:38:34.863 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:34.863 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=59,currentId=59 | |
| 2017-11-09 12:38:34.864 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:15\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState | |
| 2017-11-09 12:38:34.865 [ 7] 0 EventBuilder:buildJsonEventString:messageId=499db1fd-1f02-45a1-bdfa-123dd17bdeb5,namespace=SpeechSynthesizer,name=SpeechStarted | |
| AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"499db1fd-1f02-45a1-bdfa-123dd17bdeb5"},"payload":{"token":""}}} | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Speaking > LED | |
| 2017-11-09 12:38:35.117 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-257 | |
| 2017-11-09 12:38:35.117 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=293,result=204 | |
| 2017-11-09 12:38:35.117 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=293,numAcquiredStreams=1 | |
| 2017-11-09 12:38:35.117 [ 14] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT | |
| 2017-11-09 12:38:35.118 [ 14] 0 HTTP2Transport:processNextOutgoingMessage | |
| 2017-11-09 12:38:35.118 [ 14] 0 HTTP2StreamPool:getStream:streamId=295,numAcquiredStreams=2 | |
| ACDB -> send_audio_cal, acdb_id = 135, path = 0, app id = 0x11130, sample rate = 48000 | |
| ACDB -> send_asm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID | |
| ACDB -> send_adm_topology | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID | |
| ACDB -> send_audtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE | |
| ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE | |
| ACDB -> AUDIO_SET_AUDPROC_CAL | |
| ACDB -> send_audvoltable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0 | |
| ACDB -> AUDIO_SET_VOL_CAL cal type = 12 | |
| ACDB -> send_audstrmtable | |
| ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2 | |
| ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456 | |
| ACDB -> send_afe_topology | |
| ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID | |
| ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 135, Topology Id 112fc | |
| ACDB -> send_afe_cal | |
| ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE | |
| Failed to fetch the lookup information of the device 00000087 | |
| Error: ACDB AFE returned = -19 | |
| ACDB -> AUDIO_SET_AFE_CAL | |
| ACDB -> send_hw_delay : acdb_id = 135 path = 0 | |
| ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY | |
| 2017-11-09 12:38:35.292 [ 2] 0 MediaPlayer:callingOnTags | |
| 2017-11-09 12:38:35.454 [ 14] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 127da9fffe68993a-00006ac8-0004f17a-afc16b8d8879f52b-1ed044aa-259 | |
| 2017-11-09 12:38:35.455 [ 14] E MessageRequest:onExceptionReceived:exception={"header"\:{"namespace"\:"System"\,"name"\:"Exception"\,"messageId"\:"75c137aa-f883-4545-b47d-b56362982e20"}\,"payload"\:{"code"\:"INVALID_REQUEST_EXCEPTION"\,"description"\:"Bad Request"}} | |
| 2017-11-09 12:38:35.455 [ 14] 0 HTTP2Transport:cleanupFinishedStream:streamId=295,result=400 | |
| 2017-11-09 12:38:35.455 [ 14] 0 HTTP2StreamPool:releaseStream:streamId=295,numAcquiredStreams=1 | |
| 2017-11-09 12:38:35.748 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:36.748 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:37.749 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:38.412 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=59 | |
| 2017-11-09 12:38:38.413 [ 7] 0 SpeechSynthesizer:executePlaybackFinished | |
| 2017-11-09 12:38:38.413 [ 7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0 | |
| 2017-11-09 12:38:38.413 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState | |
| TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1 | |
| *** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0 | |
| openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0 | |
| *** onSpeechSynthesizerObserverStateChanged:: Start to open < Default > LED | |
| 2017-11-09 12:38:38.414 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE | |
| 2017-11-09 12:38:38.686 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=0,currentId=0 | |
| 2017-11-09 12:38:38.687 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet | |
| 2017-11-09 12:38:38.687 [ 2] E MediaPlayer:handleStopFailed | |
| 2017-11-09 12:38:38.750 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:39.751 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:40.751 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| handleOneSpeechBySensory::waitForWakeword------ | |
| 2017-11-09 12:38:41.752 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:42.753 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:43.754 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:44.754 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:45.755 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:46.755 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:47.756 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut | |
| 2017-11-09 12:38:48.757 [ 4] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment