Skip to content

Instantly share code, notes, and snippets.

@boyce-xx

boyce-xx/sports_update_error.txt

Created November 14, 2017 10:30
Show Gist options
  • Save boyce-xx/3cfe898ab8c8f8644cd9aa54964e8a38 to your computer and use it in GitHub Desktop.
Save boyce-xx/3cfe898ab8c8f8644cd9aa54964e8a38 to your computer and use it in GitHub Desktop.
Download ZIP
I can't hear the response when I ask a question after playing sports update.
Raw
sports_update_error.txt
*** testKeyWordObserverDebug:: onKeyWordDetected
openFMDuringTest : isFMOpened = 0
stopMediaPlayer 11111
stopLocalMediaPlayer 222
2017-11-14 10:17:08.162 [ 21] E MediaPlayer:stopCalled
2017-11-14 10:17:08.162 [ 2] E MediaPlayer:handleStopCalled
2017-11-14 10:17:08.162 [ 2] E MediaPlayer:doStopSuccess:reason=alreadyStopped
2017-11-14 10:17:08.162 [ 2] E MediaPlayer:doStopSuccess
stopSpeakMediaPlayer 33333
2017-11-14 10:17:08.163 [ 21] E MediaPlayer:stopCalled
2017-11-14 10:17:08.163 [ 2] E MediaPlayer:handleStopCalled
2017-11-14 10:17:08.163 [ 2] E MediaPlayer:doStopFailed:reason=gstElementGetStateFailed
stopMediaPlayer m_rendererMediaPlayer
2017-11-14 10:17:08.164 [ 21] E MediaPlayer:stopCalled
2017-11-14 10:17:08.164 [ 2] E MediaPlayer:handleStopCalled
(Nut2AVS:1878): GStreamer-CRITICAL **: gst_element_get_state: assertion 'GST_IS_ELEMENT (element)' failed
2017-11-14 10:17:08.164 [ 2] E MediaPlayer:doStopFailed:reason=gstElementGetStateFailed
stopMediaPlayer 44444
Nut2AVS King:: testKeyWordObserverDebug::onKeyWordDetected.....beginIndex = 5968320, endIndex = 5977679,
___________________________________AIP isExpectDirectStatus: 0
--> handleOneSpeechBySensory::readOffset = 386304
--> RingBufferConsumer m_read_offset = 386304
exitAVS = 0, m_wisStatusChanged->isStopCaptureFlag = 0, isRecordingFinish = 0
start:: turn on LED
Start to open < LISTENING > LED --> 2017-11-14 18:17:08:182
consumer is done
end:: turn on LED
##### Start recording!
Nut2AVS King:: wakeWordTrigger::onKeyWordDetected.....beginIndex = 509760, endIndex = 519120,
Nut2AVS King:: wakeWordTrigger::Sensory recognize.....beginIndex = 509760, endIndex = 519120
*** AudioInputProcessor::executeRecognize initiatorPayloadJson="wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17360}
2017-11-14 10:17:08.366 [ 7] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
m_expectingSpeechTimer.stop...
2017-11-14 10:17:08.367 [ d] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
7
current mediaPlayer voluem value: 7 --> 2017-11-14 18:17:08:425
2017-11-14 10:17:08.425 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume"\:70\,"muted"\:0},namespace=Speaker,name=VolumeState
2017-11-14 10:17:08.426 [ 8] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Sports#ACRI#ssml#ACRI#0539490b-2bf9-4eb9-8877-f899e086dd5c.a749b519-9de5-415f-9a29-358c0a0db51d.4-0"\,"offsetInMilliseconds"\:24\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Weather#ACRI#b79c96df-3c69-47bd-8d72-29c16754d01b#Alexa3P\:1.0/2017/11/14/10/cde91d05256247118acf8f9319bbb558/03\:25\:\:TNIH_2V.b1258d32-baa5-4897-bf7e-5ca3fc027ae3ZXV/0"\,"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"\:70\,"muted"\:0}}]}
2017-11-14 10:17:08.427 [ 7] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Sports#ACRI#ssml#ACRI#0539490b-2bf9-4eb9-8877-f899e086dd5c.a749b519-9de5-415f-9a29-358c0a0db51d.4-0"\,"offsetInMilliseconds"\:24\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:"amzn1.as-ct.v1.Domain\:Application\:Weather#ACRI#b79c96df-3c69-47bd-8d72-29c16754d01b#Alexa3P\:1.0/2017/11/14/10/cde91d05256247118acf8f9319bbb558/03\:25\:\:TNIH_2V.b1258d32-baa5-4897-bf7e-5ca3fc027ae3ZXV/0"\,"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"\:70\,"muted"\:0}}]}
2017-11-14 10:17:08.427 [ 7] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=3bdeabf7-74de-4884-8e52-7560686143f1,newValue=eb03050e-cfaa-41ad-b6ad-4f568e1ead27
2017-11-14 10:17:08.427 [ 7] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=3bdeabf7-74de-4884-8e52-7560686143f1
2017-11-14 10:17:08.427 [ 10] I DirectiveRouter:cancelDirective:messageId=827ff353-b04b-4e9f-b566-d3f0a541be2d,action=calling
2017-11-14 10:17:08.428 [ 6] 0 SpeechSynthesizer:executeCancel:messageId=827ff353-b04b-4e9f-b566-d3f0a541be2d
2017-11-14 10:17:08.428 [ 7] 0 EventBuilder:buildJsonEventString:messageId=86015369-713b-4896-876b-7d0aeb428db2,namespace=SpeechRecognizer,name=Recognize
AudioInputProcessor::executeOnFocusChanged***********************
2017-11-14 10:17:08.429 [ 6] 0 CapabilityAgent:removingMessageIdFromMap:messageId=827ff353-b04b-4e9f-b566-d3f0a541be2d
2017-11-14 10:17:08.429 [ 7] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
AVSConnectionManager::sendMessage {"context":[{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Sports#ACRI#ssml#ACRI#0539490b-2bf9-4eb9-8877-f899e086dd5c.a749b519-9de5-415f-9a29-358c0a0db51d.4-0","offsetInMilliseconds":24,"playerActivity":"FINISHED"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#b79c96df-3c69-47bd-8d72-29c16754d01b#Alexa3P:1.0/2017/11/14/10/cde91d05256247118acf8f9319bbb558/03:25::TNIH_2V.b1258d32-baa5-4897-bf7e-5ca3fc027ae3ZXV/0","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":70,"muted":0}}],"event":{"header":{"namespace":"SpeechRecognizer","name":"Recognize","messageId":"86015369-713b-4896-876b-7d0aeb428db2","dialogRequestId":"eb03050e-cfaa-41ad-b6ad-4f568e1ead27"},"payload":{"profile":"NEAR_FIELD","format":"AUDIO_L16_RATE_16000_CHANNELS_1","initiator":{"type":"WAKEWORD","payload":{"wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":17360}}}}}}
2017-11-14 10:17:08.524 [ 9] 0 HTTP2StreamPool:getStream:streamId=61,numAcquiredStreams=2
2017-11-14 10:17:11.704 [ e] 0 MessageInterpreter:receive:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18:No dialogRequestId attached to message.
2017-11-14 10:17:11.705 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"8886c53a-e0ca-4a31-8410-6e6c1c131d18"}\,"payload"\:{}}}
...onDirectiveArrived...
onDirectiveArrived: To stop recording...
2017-11-14 10:17:11.705 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"8886c53a-e0ca-4a31-8410-6e6c1c131d18"}\,"payload"\:{}}}
2017-11-14 10:17:11.706 [ f] I DirectiveRouter:preHandleDirective:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18,action=calling
2017-11-14 10:17:11.706 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18
2017-11-14 10:17:11.706 [ 10] I DirectiveRouter:handleDirective:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18,action=calling
AudioInputProcessor::handleDirective --------StopCapture
2017-11-14 10:17:11.706 [ 7] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2017-11-14 10:17:11.707 [ 7] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2017-11-14 10:17:11.707 [ 7] 0 DirectiveProcessor:onHandlingCompeted:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18,directiveBeingPreHandled=(nullptr)
2017-11-14 10:17:11.707 [ d] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2017-11-14 10:17:11.707 [ 7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=8886c53a-e0ca-4a31-8410-6e6c1c131d18
2017-11-14 10:17:11.715 [ 9] I InProcessAttachmentReader:readFailed:reason=SDS is closed
End Recording!!!!!!! --> 2017-11-14 18:17:11:721
closing input, handle(2457)
closing input
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:: turn on LED
Start to open < THINKING > LED --> 2017-11-14 18:17:11:773
---> Get Directive start! --> 2017-11-14 18:17:11:799
end:: turn on LED
##### End recording!
2017-11-14 10:17:12.243 [ 9] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a4ac8fffe8c7904-000054ee-0000d034-4b942e090f7e6c46-a957ea23-61
2017-11-14 10:17:12.922 [ e] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"132a266f-1490-429c-b181-a9198507d955"\,"dialogRequestId"\:"eb03050e-cfaa-41ad-b6ad-4f568e1ead27"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_2c1ba85d-0da8-4ad9-9c4b-e5f2686d5f29_19202016"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_2c1ba85d-0da8-4ad9-9c4b-e5f2686d5f29"}}}
...onDirectiveArrived...
----------------- default directive
2017-11-14 10:17:12.923 [ f] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"132a266f-1490-429c-b181-a9198507d955"\,"dialogRequestId"\:"eb03050e-cfaa-41ad-b6ad-4f568e1ead27"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_2c1ba85d-0da8-4ad9-9c4b-e5f2686d5f29_19202016"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_2c1ba85d-0da8-4ad9-9c4b-e5f2686d5f29"}}}
2017-11-14 10:17:12.924 [ f] I DirectiveRouter:preHandleDirective:messageId=132a266f-1490-429c-b181-a9198507d955,action=calling
2017-11-14 10:17:12.925 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=132a266f-1490-429c-b181-a9198507d955
2017-11-14 10:17:12.926 [ 6] 0 SpeechSynthesizer:executePreHandle:messageId=132a266f-1490-429c-b181-a9198507d955
2017-11-14 10:17:12.927 [ 10] I DirectiveRouter:handleDirective:messageId=132a266f-1490-429c-b181-a9198507d955,action=calling
2017-11-14 10:17:12.935 [ 6] 0 SpeechSynthesizer:executeHandle:messageId=132a266f-1490-429c-b181-a9198507d955
2017-11-14 10:17:12.936 [ 9] 0 HTTP2Transport:cleanupFinishedStream:streamId=61,result=200
2017-11-14 10:17:12.937 [ 9] 0 HTTP2StreamPool:releaseStream:streamId=61,numAcquiredStreams=1
---> Get Directive end! --> 2017-11-14 18:17:12:973
--> To handle the 0 directive: SpeechSynthesizer - Speak
Wait for get response audio!!!!!!! --> 2017-11-14 18:17:12:973
2017-11-14 10:17:16.711 [ d] 0 DialogUXStateAggregator:transitionFromThinkingTimedOut
2017-11-14 10:17:16.712 [ d] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE
2017-11-14 10:17:16.713 [ 7] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
AudioInputProcessor::executeOnFocusChanged***********************
2017-11-14 10:17:16.716 [ 7] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2017-11-14 10:17:16.716 [ 7] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
executeResetState m_expectingSpeechTimer.stop...
TestSpeechSynthesizerObserver::waitForNext------state:
mkdir: cannot create directory '/data/nut2_avs/recording': File exists
get recording file count: 10
To execute copy command: cp /data/nut2_avs/hal_rec_test_rec_send.wav /data/nut2_avs/recording/recording_20171114101723.wav
*** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 1
openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0
start:: turn on LED
Start to open < DEFAULT > LED --> 2017-11-14 18:17:23:82
end:: turn on LED
2017-11-14 10:17:23.165 [ 3] E MediaPlayer:stopCalled
2017-11-14 10:17:23.166 [ 2] E MediaPlayer:handleStopCalled
2017-11-14 10:17:23.166 [ 2] E MediaPlayer:doStopFailed:reason=gstElementGetStateFailed
^_^^^_^^^^_^^^^^^_^^^^^^_^^^^^^^^_^^^^ testCycleIndex: 1
executeResetState m_expectingSpeechTimer.stop...
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment