Skip to content
This repository has been archived by the owner on Jan 16, 2024. It is now read-only.

Alexa gets cut off when asking the weather in SDK V1.4.0 #477

Closed
boyce-xx opened this issue Jan 26, 2018 · 8 comments
Closed

Alexa gets cut off when asking the weather in SDK V1.4.0 #477

boyce-xx opened this issue Jan 26, 2018 · 8 comments

Comments

@boyce-xx
Copy link

Hi,
When I ask Alexa a question "What's the weather", it will gets cut off for the answer, but the other questions is works fine, below are the test logs, I have marked with bold font for the issue point:

*** wakeWordTrigger:: onKeyWordDetected
*** AudioInputProcessor::executeRecognize initiatorPayloadJson="wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":14479}
2018-01-26 11:09:29.566 [ b] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
DialogUXStateAggregator::onStateChanged state = 2
m_expectingSpeechTimer.stop...
2018-01-26 11:09:29.566 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
##### Start recording!
2018-01-26 11:09:29.566 [ 8] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=25
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 1, newState = 1
Start to open < LISTENING > LED --> 2018-01-26 19:09:29:567
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 1
2018-01-26 11:09:29.567 [ 5] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=25
2018-01-26 11:09:29.576 [ 5] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-01-26 11:09:29.578 [ 8] 0 ContextManager:buildContextSuccessful
2018-01-26 11:09:29.579 [ b] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context":[{"header":{"namespace":"Speaker","name":"VolumeState"},"payload":{"volume":36,"muted":false}},{"header":{"namespace":"Notifications","name":"IndicatorState"},"payload":{"isEnabled":false,"isVisualIndicatorPersisted":false}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Knowledge#ACRI#862e9df5-bf4e-4383-90a4-5299be70fcee#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/08:47::TNIH_2V.061d749e-d0d1-44ca-b060-a3599136c729ZXV/1","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}}]}
2018-01-26 11:09:29.580 [ b] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-01-26 11:09:29.580 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-01-26 11:09:29.580 [ b] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=43af457d-0b54-4c88-b39b-3e3bd40f5f98,newValue=acc50d8a-8a6e-4093-9aae-7a897b41e1f0
2018-01-26 11:09:29.581 [ b] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=43af457d-0b54-4c88-b39b-3e3bd40f5f98
2018-01-26 11:09:29.582 [ b] 0 EventBuilder:buildJsonEventString:messageId=c1f34a18-c2ea-416e-8a08-7500bbf65071,namespace=SpeechRecognizer,name=Recognize
AudioInputProcessor::executeOnFocusChanged***********************
2018-01-26 11:09:29.584 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
AVSConnectionManager::sendMessage {"context":[{"header":{"namespace":"Speaker","name":"VolumeState"},"payload":{"volume":36,"muted":false}},{"header":{"namespace":"Notifications","name":"IndicatorState"},"payload":{"isEnabled":false,"isVisualIndicatorPersisted":false}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Knowledge#ACRI#862e9df5-bf4e-4383-90a4-5299be70fcee#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/08:47::TNIH_2V.061d749e-d0d1-44ca-b060-a3599136c729ZXV/1","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}}],"event":{"header":{"namespace":"SpeechRecognizer","name":"Recognize","messageId":"c1f34a18-c2ea-416e-8a08-7500bbf65071","dialogRequestId":"acc50d8a-8a6e-4093-9aae-7a897b41e1f0"},"payload":{"profile":"NEAR_FIELD","format":"AUDIO_L16_RATE_16000_CHANNELS_1","initiator":{"type":"WAKEWORD","payload":{"wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":14479}}}}}}
2018-01-26 11:09:29.585 [ b] 9 HTTP2Transport:enqueueRequest
2018-01-26 11:09:29.615 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-26 11:09:29.615 [ c] 0 HTTP2StreamPool:getStream:streamId=129,numAcquiredStreams=2
2018-01-26 11:09:29.616 [ c] 9 HTTP2Transport:insertActiveStream:handle=0xa0708020
2018-01-26 11:09:31.942 [ 11] 0 MessageInterpreter:receive:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e:No dialogRequestId attached to message.
2018-01-26 11:09:31.943 [ 11] I DirectiveSequencer:onDirective:UnparsedDirective={"directive":{"header":{"namespace":"SpeechRecognizer","name":"StopCapture","messageId":"601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e"},"payload":{}}}
2018-01-26 11:09:31.943 [ 12] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive":{"header":{"namespace":"SpeechRecognizer","name":"StopCapture","messageId":"601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e"},"payload":{}}}
2018-01-26 11:09:31.943 [ 12] I DirectiveRouter:preHandleDirective:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e,action=calling
2018-01-26 11:09:31.943 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e
2018-01-26 11:09:31.944 [ 13] I DirectiveRouter:handleDirective:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e,action=calling
2018-01-26 11:09:31.944 [ b] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-01-26 11:09:31.945 [ b] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
DialogUXStateAggregator::onStateChanged state = 3
2018-01-26 11:09:31.945 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e,directiveBeingPreHandled=(nullptr)
2018-01-26 11:09:31.945 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=601f8bb8-44cc-4c64-8cb8-4fc1c40bc64e
2018-01-26 11:09:31.945 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 2, newState = 2
Start to open < THINKING > LED --> 2018-01-26 19:09:31:945
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 2
End Recording!!!!!!! --> 2018-01-26 19:09:31:952
2018-01-26 11:09:31.953 [ c] I InProcessAttachmentReader:readFailed:reason=SDS is closed
##### End recording!
---> Recognize done! --> 2018-01-26 19:09:32:157
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ testCycleIndex: 1, m_testTotalCycles = 24
Start Recording!!!!!!! Start Recording!!!!!!! Start Recording!!!!!!! --> 2018-01-26 19:09:32:228
ACDB -> send_audio_cal, acdb_id = 4, 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
Failed to fetch the lookup information of the device 00000004
Error: ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE Returned = -19
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
Failed to fetch the lookup information of the device 00000004
Error: ACDB audproc returned = -19
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 00000004
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 4, Topology Id 112fb
ACDB -> send_afe_cal
ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
Failed to fetch the lookup information of the device 00000004
Error: ACDB AFE returned = -19
ACDB -> AUDIO_SET_AFE_CAL
ACDB -> send_hw_delay : acdb_id = 4 path = 1
ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
2018-01-26 11:09:32.406 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 12c41afffe988582-00007933-00014679-842dd24e5608bcc1-ddf03da1-7
consume_wakewordDetect:: start read data from RingBuffer ...
2018-01-26 11:09:32.681 [ 11] I DirectiveSequencer:onDirective:UnparsedDirective={"directive":{"header":{"namespace":"SpeechSynthesizer","name":"Speak","messageId":"d258b2b8-d7da-48af-8367-a3a3b5dd228e","dialogRequestId":"acc50d8a-8a6e-4093-9aae-7a897b41e1f0"},"payload":{"url":"cid:2972270b-5f4c-444d-917f-3d51cfc94e1f#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/09:30::TNIH_2V.d56b03bd-6b16-4303-9112-92e92c96d591ZXV/1_1251683887","format":"AUDIO_MPEG","token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#2972270b-5f4c-444d-917f-3d51cfc94e1f#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/09:30::TNIH_2V.d56b03bd-6b16-4303-9112-92e92c96d591ZXV/1"}}}
...onDirectiveArrived...
2018-01-26 11:09:32.683 [ 12] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive":{"header":{"namespace":"SpeechSynthesizer","name":"Speak","messageId":"d258b2b8-d7da-48af-8367-a3a3b5dd228e","dialogRequestId":"acc50d8a-8a6e-4093-9aae-7a897b41e1f0"},"payload":{"url":"cid:2972270b-5f4c-444d-917f-3d51cfc94e1f#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/09:30::TNIH_2V.d56b03bd-6b16-4303-9112-92e92c96d591ZXV/1_1251683887","format":"AUDIO_MPEG","token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#2972270b-5f4c-444d-917f-3d51cfc94e1f#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/09:30::TNIH_2V.d56b03bd-6b16-4303-9112-92e92c96d591ZXV/1"}}}
2018-01-26 11:09:32.684 [ 12] I DirectiveRouter:preHandleDirective:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e,action=calling
2018-01-26 11:09:32.684 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:32.685 [ 12] 9 SpeechSynthesizer:preHandleDirective:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:32.686 [ a] 0 SpeechSynthesizer:executePreHandle:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:32.686 [ 13] I DirectiveRouter:handleDirective:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e,action=calling
2018-01-26 11:09:32.687 [ 13] 9 SpeechSynthesizer:handleDirective:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:32.700 [ a] 0 SpeechSynthesizer:executeHandle:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:32.701 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-01-26 11:09:32.701 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-01-26 11:09:32.702 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
AudioInputProcessor::executeOnFocusChanged***********************
2018-01-26 11:09:32.702 [ 10] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-01-26 11:09:32.702 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-01-26 11:09:32.703 [ b] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
executeResetState m_expectingSpeechTimer.stop...
2018-01-26 11:09:32.703 [ b] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
DialogUXStateAggregator::onStateChanged state = 0
2018-01-26 11:09:32.703 [ a] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-01-26 11:09:32.704 [ a] 9 SpeechSynthesizer:startPlaying
2018-01-26 11:09:32.704 [ a] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-01-26 11:09:32.705 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-01-26 11:09:32.705 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-26 11:09:32.705 [ 11] I DirectiveSequencer:onDirective:UnparsedDirective={"directive":{"header":{"namespace":"TemplateRuntime","name":"RenderTemplate","messageId":"43c58219-134f-4779-abf4-e3c1a955590f","dialogRequestId":"acc50d8a-8a6e-4093-9aae-7a897b41e1f0"},"payload":{"type":"WeatherTemplate","token":"f735b77a-3df2-4185-9bf3-3a345d0c2c49","lowTemperature":{"value":"41°","arrow":{"contentDescription":"Down arrow","sources":[{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/down_bb.png","size":"SMALL","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/down.png"},{"widthPixels":256,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/down_bb.png","size":"MEDIUM","heightPixels":233,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/down.png"},{"widthPixels":305,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/down_bb.png","size":"LARGE","heightPixels":278,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/down.png"}]}},"title":{"subTitle":"Friday, January 26, 2018","mainTitle":"Westlake, WA"},"currentWeatherIcon":{"contentDescription":"Partly cloudy night","sources":[{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/partly_cloudy_night_bb.png","size":"SMALL","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/partly_cloudy_night.png"},{"widthPixels":256,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/partly_cloudy_night_bb.png","size":"MEDIUM","heightPixels":233,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/partly_cloudy_night.png"},{"widthPixels":305,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/partly_cloudy_night_bb.png","size":"LARGE","heightPixels":278,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/partly_cloudy_night.png"}]},"weatherForecast":[{"highTemperature":"49°","date":"Jan 27","lowTemperature":"45°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Sat"},{"highTemperature":"54°","date":"Jan 28","lowTemperature":"47°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Sun"},{"highTemperature":"52°","date":"Jan 29","lowTemperature":"41°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Mon"},{"highTemperature":"47°","date":"Jan 30","lowTemperature":"39°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Tue"},{"highTemperature":"49°","date":"Jan 31","lowTemperature":"36°","image":{"contentDescription":"Partly cloudy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/partly_cloudy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/partly_cloudy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/partly_cloudy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/partly_cloudy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/partly_cloudy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/partly_cloudy.png"}]},"day":"Wed"},{"highTemperature":"49°","date":"Feb 1","lowTemperature":"42°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Thu"},{"highTemperature":"50°","date":"Feb 2","lowTemperature":"38°","image":{"contentDescription":"Rainy","sources":[{"widthPixels":44,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy_bb.png","size":"SMALL","heightPixels":40,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/weatherForecast/rainy.png"},{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy_bb.png","size":"MEDIUM","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/weatherForecast/rainy.png"},{"widthPixels":132,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy_bb.png","size":"LARGE","heightPixels":123,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/weatherForecast/rainy.png"}]},"day":"Fri"}],"description":"Mainly cloudy with a couple of showers","highTemperature":{"value":"45°","arrow":{"contentDescription":"Up arrow","sources":[{"widthPixels":88,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/up_bb.png","size":"SMALL","heightPixels":80,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/small/currentWeatherIcon/up.png"},{"widthPixels":256,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/up_bb.png","size":"MEDIUM","heightPixels":233,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/medium/currentWeatherIcon/up.png"},{"widthPixels":305,"darkBackgroundUrl":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/up_bb.png","size":"LARGE","heightPixels":278,"url":"https://images-na.ssl-images-amazon.com/images/G/01/alexa/avs/gui/large/currentWeatherIcon/up.png"}]}},"currentWeather":"40°"}}}
2018-01-26 11:09:32.706 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-26 11:09:32.709 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-26 11:09:32.709 [ 2] I MediaPlayer:****** MediaPlayer::setupPipeline() ******
2018-01-26 11:09:32.709 [ 12] I DirectiveRouter:handleDirectiveWithPolicyHandleImmediately:messageId=43c58219-134f-4779-abf4-e3c1a955590f,action=calling
2018-01-26 11:09:32.709 [ 12] 9 TemplateRuntime:handleDirectiveImmediately
2018-01-26 11:09:32.710 [ 12] 9 TemplateRuntime:preHandleDirective
2018-01-26 11:09:32.710 [ 12] 9 TemplateRuntime:handleRenderTemplateDirective
2018-01-26 11:09:32.711 [ 2] I MediaPlayer:noOutputConversion
2018-01-26 11:09:32.711 [ 14] 0 TemplateRuntime:handleRenderTemplateDirectiveInExecutor
2018-01-26 11:09:32.714 [ 2] 9 BaseStreamSource:Creating decodebin for URL
2018-01-26 11:09:32.717 [ a] 9 MediaPlayer:playCalled
2018-01-26 11:09:32.718 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=25,currentId=25
2018-01-26 11:09:32.720 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-01-26 11:09:32.721 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-01-26 11:09:32.721 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-01-26 11:09:32.721 [ 25] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-26 11:09:32.722 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-01-26 11:09:32.722 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-01-26 11:09:32.723 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-01-26 11:09:32.723 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-01-26 11:09:32.723 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-01-26 11:09:32.724 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-01-26 11:09:32.724 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-01-26 11:09:32.724 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-01-26 11:09:32.725 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-01-26 11:09:32.725 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-01-26 11:09:32.725 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-01-26 11:09:32.726 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-26 11:09:32.726 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=214
2018-01-26 11:09:32.726 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:32.727 [ 2] 9 AttachmentReaderSource:read:size=1043,status=0
2018-01-26 11:09:32.745 [ 25] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-26 11:09:32.911 [ f] 0 DialogUXStateAggregator:transitionFromThinkingTimedOut
2018-01-26 11:09:32.914 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 0, newState = 0
Start to open < DEFAULT > LED --> 2018-01-26 19:09:32:916
2018-01-26 11:09:32.916 [ a] 1 FocusManager:releaseChannel:channelName=Dialog
2018-01-26 11:09:32.952 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:32.953 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux23
2018-01-26 11:09:32.953 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux23
2018-01-26 11:09:32.953 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:32.954 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-26 11:09:32.954 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:32.954 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:32.962 [ 25] 9 MediaPlayer:onPadAddedCalled
2018-01-26 11:09:33.284 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.285 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse24
2018-01-26 11:09:33.286 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse24
2018-01-26 11:09:33.286 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-24
2018-01-26 11:09:33.287 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-24
2018-01-26 11:09:33.287 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.288 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-01-26 11:09:33.293 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.293 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-01-26 11:09:33.294 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
mWisAvs.m_connectionStatusObserver->getConnectionStatus: status = 2, AVSConnectionManager::isEnabled = 1, AVSConnectionManager::isConnected = 1
start to delay......
ACDB -> send_audio_cal, acdb_id = 14, 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 14, Topology Id 112fa
ACDB -> send_afe_cal
ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
ACDB -> AUDIO_SET_AFE_CAL
ACDB -> send_hw_delay : acdb_id = 14 path = 0
ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
2018-01-26 11:09:33.544 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.545 [ 2] 9 MediaPlayer:messageReceived:type=stream-start,source=audio-pipeline
2018-01-26 11:09:33.546 [ 2] 9 MediaPlayer:messageReceived:type=latency,source=audio_sink
2018-01-26 11:09:33.547 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink
2018-01-26 11:09:33.546 [ c] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-01-26 11:09:33.547 [ 2] 0 MediaPlayer:callingOnTags
2018-01-26 11:09:33.549 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink
2018-01-26 11:09:33.550 [ 2] 0 MediaPlayer:callingOnTags
2018-01-26 11:09:33.550 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-01-26 11:09:33.551 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-01-26 11:09:33.551 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-01-26 11:09:33.551 [ 2] 9 MediaPlayer:messageReceived:type=async-done,source=audio-pipeline
2018-01-26 11:09:33.551 [ 2] 9 MediaPlayer:messageReceived:type=new-clock,source=audio-pipeline
2018-01-26 11:09:33.552 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-01-26 11:09:33.552 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-01-26 11:09:33.552 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-24
2018-01-26 11:09:33.553 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse24
2018-01-26 11:09:33.553 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux23
2018-01-26 11:09:33.553 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-01-26 11:09:33.554 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-01-26 11:09:33.548 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=129,result=200
2018-01-26 11:09:33.555 [ c] 0 HTTP2StreamPool:releaseStream:streamId=129,numAcquiredStreams=1
2018-01-26 11:09:33.554 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-01-26 11:09:33.556 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-01-26 11:09:33.556 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-01-26 11:09:33.556 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=25
2018-01-26 11:09:33.556 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=25
2018-01-26 11:09:33.557 [ a] 0 SpeechSynthesizer:executePlaybackStarted
2018-01-26 11:09:33.557 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-01-26 11:09:33.557 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-26 11:09:33.557 [ a] 9 MediaPlayer:getOffsetCalled
2018-01-26 11:09:33.558 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.558 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.558 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=25,currentId=25
2018-01-26 11:09:33.559 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-26 11:09:33.559 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-01-26 11:09:33.559 [ 2] 9 AttachmentReaderSource:read:size=1050,status=0
2018-01-26 11:09:33.560 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-26 11:09:33.560 [ 10] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-01-26 11:09:33.560 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=SPEAKING
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 3, newState = 3
Start to open < SPEAKING > LED --> 2018-01-26 19:09:33:561
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 3
2018-01-26 11:09:33.561 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-01-26 11:09:33.560 [ a] 0 EventBuilder:buildJsonEventString:messageId=0c6fefef-0c08-4835-a34c-796b1f32b712,namespace=SpeechSynthesizer,name=SpeechStarted
2018-01-26 11:09:33.560 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"0c6fefef-0c08-4835-a34c-796b1f32b712"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#2972270b-5f4c-444d-917f-3d51cfc94e1f#Alexa3P:1.0/2018/01/26/11/28c7c5417efd4481bf041c1cbee116d8/09:30::TNIH_2V.d56b03bd-6b16-4303-9112-92e92c96d591ZXV/1"}}}
2018-01-26 11:09:33.570 [ 2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-01-26 11:09:33.570 [ a] 9 HTTP2Transport:enqueueRequest
2018-01-26 11:09:33.570 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2018-01-26 11:09:33.570 [ 2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-01-26 11:09:33.571 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=214
2018-01-26 11:09:33.573 [ a] 0 SpeechSynthesizer:executeStateChange:newState=FINISHED
2018-01-26 11:09:33.573 [ a] 9 SpeechSynthesizer:stopPlaying
2018-01-26 11:09:33.574 [ a] 9 MediaPlayer:stopCalled
2018-01-26 11:09:33.574 [ 2] 0 MediaPlayer:handleStopCalled:idPassed=25,currentId=25
2018-01-26 11:09:33.658 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-26 11:09:33.659 [ c] 0 HTTP2StreamPool:getStream:streamId=131,numAcquiredStreams=2
2018-01-26 11:09:33.659 [ c] 9 HTTP2Transport:insertActiveStream:handle=0xa0708020
2018-01-26 11:09:33.859 [ 2] 0 MediaPlayer:callingOnPlaybackStopped:currentId=25
2018-01-26 11:09:33.860 [ 2] 9 SpeechSynthesizer:onPlaybackStopped:callbackSourceId=25
2018-01-26 11:09:33.860 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=25
2018-01-26 11:09:33.860 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-26 11:09:33.861 [ a] 0 SpeechSynthesizer:executePlaybackFinished
2018-01-26 11:09:33.861 [ 2] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-01-26 11:09:33.861 [ 2] 9 BaseStreamSource:~BaseStreamSource
2018-01-26 11:09:33.861 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-01-26 11:09:33.861 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-26 11:09:33.861 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-26 11:09:33.862 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2
2018-01-26 11:09:33.862 [ 2] E MediaPlayer:handleStopFailed:reason=alreadyStopped
2018-01-26 11:09:33.862 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 3
2018-01-26 11:09:33.862 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-01-26 11:09:33.863 [ a] 9 SpeechSynthesizer:setHandlingCompleted
2018-01-26 11:09:33.863 [ 10] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-01-26 11:09:33.863 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e,directiveBeingPreHandled=(nullptr)
2018-01-26 11:09:33.863 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=d258b2b8-d7da-48af-8367-a3a3b5dd228e
2018-01-26 11:09:33.864 [ 2] 9 MediaPlayer:resetPipeline
2018-01-26 11:09:33.864 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-26 11:09:34.014 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 12c41afffe988582-00007933-00014a02-5e895b888f329bfd-f8ccce39-131
2018-01-26 11:09:34.015 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=131,result=204
2018-01-26 11:09:34.015 [ c] 0 HTTP2StreamPool:releaseStream:streamId=131,numAcquiredStreams=1
2018-01-26 11:09:34.015 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-26 11:09:34.065 [ f] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 0, newState = 0
Start to open < DEFAULT > LED --> 2018-01-26 19:09:34:65
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 0
AudioInputProcessor::executeOnDialogUXStateChanged newState2 = 0

@bandkenamazoncom
Copy link
Contributor

Try this: #469

@boyce-xx
Copy link
Author

Hi @bandkenamazoncom ,

I increased the SLEEP_AFTER_END_OF_AUDIO value to 800, but the issue still exist.
I found some code tips during Alexa setup process.

--[Situation A]---
With the code below to initial(followed the SampleApp), the issue was happened(in one cycle).

mWisAvs.m_connectionManager = acl::AVSConnectionManager::create(m_messageRouter, false, {mWisAvs.m_connectionStatusObserver}, {m_dialogUXStateAggregator});
m_exceptionSender = ExceptionEncounteredSender::create(mWisAvs.m_connectionManager);
m_directiveSequencer = DirectiveSequencer::create(m_exceptionSender);
m_messageInterpreter = std::make_shared(m_exceptionSender, m_directiveSequencer, m_attachmentManager);


--[Situation B]---
With the code below to initial(followed the IntegrationTest), the issue was not happened(in 20 cycles).

m_exceptionSender = std::make_shared<TestExceptionEncounteredSender>();
m_directiveSequencer = DirectiveSequencer::create(m_exceptionSender);
m_messageInterpreter = std::make_shared(m_exceptionSender, m_directiveSequencer, m_attachmentManager);
mWisAvs.m_connectionManager = acl::AVSConnectionManager::create(m_messageRouter, false, {mWisAvs.m_connectionStatusObserver}, {m_messageInterpreter});


Could you help analyze what's the difference between both.
Thanks.

@bandkenamazoncom
Copy link
Contributor

The top one is instantiating the actual ExceptionSender that is needed, the bottom snippet is instantiating a Fake ExceptionSender for test purposes.

What was the difference that you saw? Did the speech get cut off in the SampleApp but not the Integration test?

@boyce-xx
Copy link
Author

Hi @bandkenamazoncom ,
Yes, I can't see the issue in the IntegrationTest, but in the SampleApp, it's so strange that it's only for the question "What's the weather?", other questions works well. Can you help to try it?

BTW, in my comment above, there have another difference for the last parameter in AVSConnectionManager initializing:

mWisAvs.m_connectionManager = acl::AVSConnectionManager::create(m_messageRouter, false, {mWisAvs.m_connectionStatusObserver}, {m_dialogUXStateAggregator});

mWisAvs.m_connectionManager = acl::AVSConnectionManager::create(m_messageRouter, false, {mWisAvs.m_connectionStatusObserver}, {m_messageInterpreter});

Did it have any effect?

@boyce-xx
Copy link
Author

boyce-xx commented Feb 6, 2018

Hi @bandkenamazoncom ,
Do you have any update on it? is it a known issue in SDK?

@mradulan
Copy link
Contributor

mradulan commented Feb 6, 2018

Hi @boyce-xx , this is not a known issue in SDK. It looks similar to #474 . Can you check if the symptoms are similar.

@zeusshuang
Copy link

Hi @mradulan ,
I also got the same issue since SDK 1.4. And I also check #474, seems not the same case.
This case is very strange. While I asked "what's the weather in Hong Kong" which is from Amazon's test audio, it will play one or two words and cut off. Sometimes, it will play okay, often failed.
While I asked "what is the weather", it will play the weather for WA, often okay, but sometime failed.

I put my log here. First test is for "what's the weather in Hong Kong", it only say: "In Hong Kong," then cut off.
Send test is for "what's the weather", it only say "Curretly", then cut off.

1.log

@mradulan
Copy link
Contributor

mradulan commented Feb 7, 2018

Hi, this issue has been reproduced. We will provide a fix for this in 1.5

sanjayrd added a commit that referenced this issue Mar 9, 2018
Changes in this update:

**Enhancements**
* `rapidJson` is now included with "make install".
* Updated the `TemplateRuntimeObserverInterface` to support clearing of `displayCards`.
* Added Windows SDK support, along with an installation script (MinGW-w64).
* Updated `ContextManager` to ignore context reported by a state provider.
* The `SharedDataStream` object is now associated by playlist, rather than by URL.
* Added the `RegistrationManager` component. Now, when a user logs out all persistent user-specific data is cleared from the SDK. The log out functionality can be exercised in the sample app with the new command: `k`.

**Bug Fixes**
* [Issue 400](#400) Fixed a bug where the alert reminder did not iterate as intended after loss of network connection.
* [Issue 477](#477) Fixed a bug in which Alexa's weather response was being truncated.
* Fixed an issue in which there were reports of instability related to the Sensory engine. To correct this, the `portAudio` [`suggestedLatency`](https://github.com/alexa/avs-device-sdk/blob/master/Integration/AlexaClientSDKConfig.json#L62) value can now be configured.

**Known Issues**
* The `ACL` may encounter issues if audio attachments are received but not consumed.
* `SpeechSynthesizerState` currently uses `GAINING_FOCUS` and `LOSING_FOCUS` as a workaround for handling intermediate state. These states may be removed in a future release.
* Music playback doesn't immediately stop when a user barges-in on iHeartRadio.
* The Windows sample app sometimes hangs on exit.
* GDP receives a `SIGPIPE` when troubleshooting the Windows sample app.
padillag pushed a commit to padillag/avs-device-sdk-intel-speech-enabling-kit that referenced this issue May 11, 2018
Changes in this update:

**Enhancements**
* `rapidJson` is now included with "make install".
* Updated the `TemplateRuntimeObserverInterface` to support clearing of `displayCards`.
* Added Windows SDK support, along with an installation script (MinGW-w64).
* Updated `ContextManager` to ignore context reported by a state provider.
* The `SharedDataStream` object is now associated by playlist, rather than by URL.
* Added the `RegistrationManager` component. Now, when a user logs out all persistent user-specific data is cleared from the SDK. The log out functionality can be exercised in the sample app with the new command: `k`.

**Bug Fixes**
* [Issue 400](alexa/avs-device-sdk#400) Fixed a bug where the alert reminder did not iterate as intended after loss of network connection.
* [Issue 477](alexa/avs-device-sdk#477) Fixed a bug in which Alexa's weather response was being truncated.
* Fixed an issue in which there were reports of instability related to the Sensory engine. To correct this, the `portAudio` [`suggestedLatency`](https://github.com/alexa/avs-device-sdk/blob/master/Integration/AlexaClientSDKConfig.json#L62) value can now be configured.

**Known Issues**
* The `ACL` may encounter issues if audio attachments are received but not consumed.
* `SpeechSynthesizerState` currently uses `GAINING_FOCUS` and `LOSING_FOCUS` as a workaround for handling intermediate state. These states may be removed in a future release.
* Music playback doesn't immediately stop when a user barges-in on iHeartRadio.
* The Windows sample app sometimes hangs on exit.
* GDP receives a `SIGPIPE` when troubleshooting the Windows sample app.
padillag pushed a commit to padillag/avs-device-sdk-intel-speech-enabling-kit that referenced this issue May 11, 2018
Changes in this update:

**Enhancements**
* `rapidJson` is now included with "make install".
* Updated the `TemplateRuntimeObserverInterface` to support clearing of `displayCards`.
* Added Windows SDK support, along with an installation script (MinGW-w64).
* Updated `ContextManager` to ignore context reported by a state provider.
* The `SharedDataStream` object is now associated by playlist, rather than by URL.
* Added the `RegistrationManager` component. Now, when a user logs out all persistent user-specific data is cleared from the SDK. The log out functionality can be exercised in the sample app with the new command: `k`.

**Bug Fixes**
* [Issue 400](alexa/avs-device-sdk#400) Fixed a bug where the alert reminder did not iterate as intended after loss of network connection.
* [Issue 477](alexa/avs-device-sdk#477) Fixed a bug in which Alexa's weather response was being truncated.
* Fixed an issue in which there were reports of instability related to the Sensory engine. To correct this, the `portAudio` [`suggestedLatency`](https://github.com/alexa/avs-device-sdk/blob/master/Integration/AlexaClientSDKConfig.json#L62) value can now be configured.

**Known Issues**
* The `ACL` may encounter issues if audio attachments are received but not consumed.
* `SpeechSynthesizerState` currently uses `GAINING_FOCUS` and `LOSING_FOCUS` as a workaround for handling intermediate state. These states may be removed in a future release.
* Music playback doesn't immediately stop when a user barges-in on iHeartRadio.
* The Windows sample app sometimes hangs on exit.
* GDP receives a `SIGPIPE` when troubleshooting the Windows sample app.
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants