SDK V1.2 always stay in speaking mode

[aki29]

Hi AVS Team,

We found an issue in device SDK V1.2.
After we launched SampleApp.
In first time, we said "alexa, hi" and she can back to idle mode
In second time, we said "alexa, hi" but she always stay in speaking mode
following is the log in second asking.
we found DialogUXStateAggregator:setState:from=FINISHED,to=IDLE is always lost in second asking

########################### # Speaking... #
###########################

2017-11-09 08:51:32.530 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-11-09 08:51:32.530 [ 2] 0 MediaPlayer:callingOnTags
2017-11-09 08:51:32.551 [ 11] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-09 08:51:32.552 [ 11] 0 HTTP2StreamPool:getStream:streamId=17,numAcquiredStreams=2
2017-11-09 08:51:32.553 [ 11] 9 HTTP2Transport:insertActiveStream:handle=0x440788
2017-11-09 08:51:32.843 [ 11] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0ac244fffeaee3c4-00004faa-0004be3f-892751c276d1c262-efcbd172-13
2017-11-09 08:51:32.844 [ 11] 0 HTTP2Transport:cleanupFinishedStream:streamId=17,result=204
2017-11-09 08:51:32.844 [ 11] 0 HTTP2StreamPool:releaseStream:streamId=17,numAcquiredStreams=1
2017-11-09 08:51:32.844 [ 11] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2017-11-09 08:51:33.271 [ 2] 9 MediaPlayer:messageReceived:messageType=eos
2017-11-09 08:51:33.272 [ 2] 9 BaseStreamSource:~BaseStreamSource
2017-11-09 08:51:33.272 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=2
2017-11-09 08:51:33.272 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=2
2017-11-09 08:51:33.272 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2017-11-09 08:51:33.272 [ 9] 0 SpeechSynthesizer:executePlaybackFinished
2017-11-09 08:51:33.272 [ 9] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2017-11-09 08:51:33.273 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Knowledge#ACRI#3d87d055-4216-4fa2-a911-71ee5535b1ed#Alexa3P:1.0/2017/11/09/08/50b2de13ef85470e8d868f2927e82e8d/51:29::TNIH_2V. 7347a14a-2bd7-405e-8826-825148892875ZXV/1","offsetInMilliseconds":0,"playerActivity":"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2017-11-09 08:51:33.273 [ 9] 9 SpeechSynthesizer:releaseForegroundFocus
2017-11-09 08:51:33.273 [ 9] 1 FocusManager:releaseChannel:channelName=Dialog
2017-11-09 08:51:33.274 [ 13] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2017-11-09 08:51:33.275 [ 9] 0 EventBuilder:buildJsonEventString:messageId=55f24b18-fdf9-4703-b2f8-6b9e62784d15,namespace=SpeechSynthesizer,name=SpeechFinished
2017-11-09 08:51:33.275 [ 9] 9 HTTP2Transport:enqueueRequest
2017-11-09 08:51:33.275 [ 9] 9 SpeechSynthesizer:setHandlingCompleted
2017-11-09 08:51:33.276 [ 9] 0 DirectiveProcessor:onHandlingCompeted:messageId=f32f677f-71ff-4224-9bf2-07777c1986d7,directiveBeingPreHandled=(nullptr)
2017-11-09 08:51:33.276 [ 9] 0 CapabilityAgent:removingMessageIdFromMap:messageId=f32f677f-71ff-4224-9bf2-07777c1986d7
2017-11-09 08:51:33.347 [ 11] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-09 08:51:33.347 [ 11] 0 HTTP2StreamPool:getStream:streamId=19,numAcquiredStreams=2
2017-11-09 08:51:33.347 [ 11] 9 HTTP2Transport:insertActiveStream:handle=0x440788
2017-11-09 08:51:33.657 [ 11] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0ac244fffeaee3c4-00004faa-0004be3f-892751c276d1c262-efcbd172-15
2017-11-09 08:51:33.657 [ 11] 0 HTTP2Transport:cleanupFinishedStream:streamId=19,result=204
2017-11-09 08:51:33.657 [ 11] 0 HTTP2StreamPool:releaseStream:streamId=19,numAcquiredStreams=1
2017-11-09 08:51:33.657 [ 11] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

[ryan-esty]

@aki29, This appears to be the same issue as #189. I never got a resolution on it. Don't follow my hack as it isn't correct, as I mentioned in the thread, and you will never be able to shut off the timers or alarms. Since our project uses something external to go to listen mode this doesn't effect us any more.

Ryan

[jjamazon]

Hi aki29 ,
Are you running on Linux/Mac/Pi. I could not reproduce it on Mac.

can you enable DEBUG9 by running cmake with -DCMAKE_BUILD_TYPE=DEBUG and enabling DEBUG9 mode with

TZ=UTC ./SampleApp /root/BUILD/Integration/AlexaClientSDKConfig.json DEBUG9

This will help us get verbose logs and in diagnosing the issue.

[aki29]

Hi jjamazon

This is issue seem be sovled on 1.2.1.

aki