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

AlexaSDKv1.5/1.3 crash on openwrt linux platform #538

Closed
NathanMani opened this issue Feb 27, 2018 · 2 comments
Closed

AlexaSDKv1.5/1.3 crash on openwrt linux platform #538

NathanMani opened this issue Feb 27, 2018 · 2 comments

Comments

@NathanMani
Copy link

We are porting AlexaSDKv1.5/1.3 onto our platform running with openwrt linux. The basic functionality is fine. However the application is crashing randomly with
"terminate called after throwing an instance of 'std::future_error'
what(): Promise already satisfied
Aborted" Error.

Note: We have verified with gstreamer v1.10.4 and v1.10.5 and in both version the issues persist.

Please help us on how to resolve or trace the issues.
@NathanMani
Copy link
Author

018-02-21 04:31:41.553 [ 1] I sdkVersion: 1.5.0
Running app with log level: DEBUG9
2018-02-21 04:31:55.665 [ 1] 0 ConfigurationNode:initializeSuccess
2018-02-21 04:31:55.666 [ 1] 9 MediaPlayer:createCalled
2018-02-21 04:31:56.633 [ 1] 9 MediaPlayer:noOutputConversion
2018-02-21 04:31:56.639 [ 1] 9 MediaPlayer:createCalled
2018-02-21 04:31:56.642 [ 1] 9 MediaPlayer:noOutputConversion
2018-02-21 04:31:56.648 [ 1] 9 MediaPlayer:createCalled
2018-02-21 04:31:56.650 [ 1] 9 MediaPlayer:noOutputConversion
2018-02-21 04:31:56.656 [ 1] 9 MediaPlayer:createCalled
2018-02-21 04:31:56.659 [ 1] 9 MediaPlayer:noOutputConversion
2018-02-21 04:31:56.677 [ 1] 9 MediaPlayer:setObserverCalled
2018-02-21 04:31:56.678 [ 2] 0 MediaPlayer:handleSetObserverCalled
2018-02-21 04:31:56.679 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x345e5cc
2018-02-21 04:31:56.681 [ 1] 9 PlaybackRouter:create::called
2018-02-21 04:31:56.682 [ 1] 9 MediaPlayer:setObserverCalled
2018-02-21 04:31:56.684 [ 2] 0 MediaPlayer:handleSetObserverCalled
2018-02-21 04:31:56.685 [ 1] 9 MediaPlayer:setObserverCalled
2018-02-21 04:31:56.686 [ 2] 0 MediaPlayer:handleSetObserverCalled
2018-02-21 04:31:56.695 [ 1] 9 AlertScheduler:setTimerForNextAlertLocked
2018-02-21 04:31:56.695 [ 1] 9 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2018-02-21 04:31:56.696 [ 1] 5 NotificationsRenderer:create
2018-02-21 04:31:56.696 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2018-02-21 04:31:56.697 [ 1] 9 MediaPlayer:setObserverCalled
2018-02-21 04:31:56.698 [ 2] 0 MediaPlayer:handleSetObserverCalled
2018-02-21 04:31:56.698 [ 1] 5 NotificationsCapabilityAgent:init
2018-02-21 04:31:56.699 [ 1] 5 NotificationsRenderer:addObserver
2018-02-21 04:31:56.706 [ 4] 5 NotificationsCapabilityAgent:executeInit
2018-02-21 04:31:56.707 [ 4] 0 NotificationsCapabilityAgent:notifyObservers:indicatorState=0
2018-02-21 04:31:56.708 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0
2018-02-21 04:31:56.710 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:31:56.712 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-02-21 04:31:56.713 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-02-21 04:31:56.713 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-02-21 04:31:56.713 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-02-21 04:31:56.714 [ 1] 0 SpeakerManager:mapCreated:numAvsSynced=3,numLocal=1
2018-02-21 04:31:56.714 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:31:56.715 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:31:56.715 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:31:56.716 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:31:56.716 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:31:56.717 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:31:56.717 [ 1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-02-21 04:31:56.718 [ 1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2018-02-21 04:31:56.718 [ 1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-02-21 04:31:56.719 [ 1] 0 ExternalMediaPlayer:createAdapters
2018-02-21 04:31:56.720 [ 1] 1 AudioPlayer:addObserver
2018-02-21 04:31:56.721 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x3473c10,policy=BLOCKING
2018-02-21 04:31:56.721 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x3474948,policy=NON_BLOCKING
2018-02-21 04:31:56.722 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x3474948,policy=NON_BLOCKING
2018-02-21 04:31:56.722 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x3474948,policy=NON_BLOCKING
2018-02-21 04:31:56.723 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=AdjustSeekPosition,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.723 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableRepeat,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.723 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableShuffle,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.724 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=FastForward,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.724 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Unfavorite,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.725 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=StartOver,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.725 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Previous,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.725 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeat,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.726 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Rewind,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.726 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Next,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.727 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Pause,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.727 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableShuffle,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.727 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Play,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.728 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Favorite,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.728 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=SetSeekPosition,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.728 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Login,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.729 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Logout,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.729 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Play,handler=0x349dc70,policy=NON_BLOCKING
2018-02-21 04:31:56.729 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x3472ee0,policy=NON_BLOCKING
2018-02-21 04:31:56.730 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x3472ee0,policy=NON_BLOCKING
2018-02-21 04:31:56.730 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x34755c8,policy=NON_BLOCKING
2018-02-21 04:31:56.731 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x34755c8,policy=NON_BLOCKING
2018-02-21 04:31:56.731 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x34af930,policy=NON_BLOCKING
2018-02-21 04:31:56.731 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x3472cc4,policy=NON_BLOCKING
2018-02-21 04:31:56.732 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x349e140,policy=NON_BLOCKING
2018-02-21 04:31:56.732 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x349e140,policy=NON_BLOCKING
2018-02-21 04:31:56.733 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x349e140,policy=NON_BLOCKING
2018-02-21 04:31:56.733 [ 1] 9 TemplateRuntime:getConfiguration
2018-02-21 04:31:56.733 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x34af060,policy=HANDLE_IMMEDIATELY
2018-02-21 04:31:56.734 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x34af060,policy=HANDLE_IMMEDIATELY
2018-02-21 04:31:56.734 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=ClearIndicator,handler=0x3476fe4,policy=HANDLE_IMMEDIATELY
2018-02-21 04:31:56.735 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=SetIndicator,handler=0x3476fe4,policy=HANDLE_IMMEDIATELY
2018-02-21 04:31:58.415 [ 5] 0 HttpPost:doPostSucceeded:code=200
2018-02-21 04:31:58.416 [ 5] 0 AuthDelegate:handleLwaResponseSucceeded:expiresInSeconds=3600
2018-02-21 04:31:58.417 [ 5] 0 AuthDelegate:onAuthStateChangeCalled:state=1,error=0
2018-02-21 04:31:58.417 [ 1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2018-02-21 04:31:58.418 [ 1] W HTTP2Transport:Unable to parse OpenSSL version!:version=mbedTLS/2.0.0,required=1.0.2
2018-02-21 04:31:58.419 [ 1] 9 HTTP2Transport:setupDownchannelStream:url=https://avs-alexa-na.amazon.com/v20160207/directives
2018-02-21 04:31:58.419 [ 1] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1
2018-02-21 04:31:58.420 [ 6] 9 PostConnectSynchronize:Entering postConnectLoop thread
2018-02-21 04:31:58.421 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
#############################

Connecting...
#############################

2018-02-21 04:31:58.423 [ 7] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=1
2018-02-21 04:31:58.423 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-02-21 04:31:58.423 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=1
2018-02-21 04:31:58.424 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-02-21 04:31:58.424 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-02-21 04:31:58.424 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:31:58.425 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-02-21 04:31:58.423 [ 7] 0 AudioPlayer:provideState:stateRequestToken=1
2018-02-21 04:31:58.426 [ 7] 9 SpeechSynthesizer:provideState:token=1
2018-02-21 04:31:58.426 [ 9] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-02-21 04:31:58.427 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1
2018-02-21 04:31:58.427 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,namespace=AudioPlayer,name=PlaybackState
2018-02-21 04:31:58.428 [ b] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechRecognizer,name=RecognizerState
2018-02-21 04:31:58.428 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:31:58.430 [ 7] 0 ContextManager:buildContextSuccessful
2018-02-21 04:31:58.432 [ 7] 0 EventBuilder:buildJsonEventString:messageId=01531fa0-73f9-41c2-a8ff-605c4d9c2bd9,namespace=System,name=SynchronizeState
2018-02-21 04:31:58.433 [ 7] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport
2018-02-21 04:31:58.433 [ 7] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:00.246 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-1
2018-02-21 04:32:00.247 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:00.248 [ c] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2
2018-02-21 04:32:00.249 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:00.868 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-3
2018-02-21 04:32:00.869 [ c] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-02-21 04:32:00.869 [ 6] 9 PostConnectSynchronize:Exiting postConnectLoop thread
2018-02-21 04:32:00.869 [ c] 0 PostConnectSynchronize:onConnected()
2018-02-21 04:32:00.870 [ c] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2018-02-21 04:32:00.871 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=204
2018-02-21 04:32:00.871 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2018-02-21 04:32:00.872 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2018-02-21 04:32:00.872 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x3448f34
2018-02-21 04:32:00.873 [ 1] 5 NotificationsCapabilityAgent:addObserver
2018-02-21 04:32:00.873 [ 1] 9 TemplateRuntime:addObserver
#################################################

NOTIFICATION INDICATOR STATE: OFF
#################################################

2018-02-21 04:32:00.874 [ d] 0 TemplateRuntime:addObserverInExecutor
2018-02-21 04:32:00.871 [ c] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
2018-02-21 04:32:00.875 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:32:04.612 [ 1] I SensoryKeywordDetector:License expires on 01 Jun 2018 00:00:00 GMT
2018-02-21 04:32:04.614 [ 1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
+----------------------------------------------------------------------------+
| Options: |
| Wake word: |
| Simply say Alexa and begin your query. |
| Tap to talk: |
| Press 't' and Enter followed by your query (no need for the 'Alexa').|
| Hold to talk: |
| Press 'h' followed by Enter to simulate holding a button. |
| Then say your query (no need for the 'Alexa'). |
| Press 'h' followed by Enter to simulate releasing a button. |
| Stop an interaction: |
| Press 's' and Enter to stop an ongoing interaction. |
| Privacy mode (microphone off): |
| Press 'm' and Enter to turn on and off the microphone. |
| Echo Spatial Perception (ESP): This is only for testing purpose only! |
| Press 'e' followed by Enter at any time to adjust ESP settings. |
| Playback Controls: |
| Press '1' for a 'PLAY' button press. |
| Press '2' for a 'PAUSE' button press. |
| Press '3' for a 'NEXT' button press. |
| Press '4' for a 'PREVIOUS' button press. |
| Settings: |
| Press 'c' followed by Enter at any time to see the settings screen. |
| Speaker Control: |
| Press 'p' followed by Enter at any time to adjust speaker settings. |
| Firmware Version: |
| Press 'f' followed by Enter at any time to report a different |
| firmware version. |
| Info: |
| Press 'i' followed by Enter at any time to see the help screen. |
| Quit: |
| Press 'q' followed by Enter at any time to quit the application. |
+----------------------------------------------------------------------------+

2018-02-21 04:32:05.817 [ e] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut
2018-02-21 04:32:06.818 [ e] I AbstractKeywordDetector:readFromStreamFailed:reason=readerTimeOut
2018-02-21 04:32:19.315 [ b] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-02-21 04:32:19.316 [ b] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechRecognizer,name=RecognizerState
2018-02-21 04:32:19.316 [ 7] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=2
2018-02-21 04:32:19.316 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-02-21 04:32:19.317 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-02-21 04:32:19.317 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=2
2018-02-21 04:32:19.317 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-02-21 04:32:19.318 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-02-21 04:32:19.318 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:32:19.319 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-02-21 04:32:19.321 [ 7] 0 ContextManager:buildContextSuccessful
2018-02-21 04:32:19.322 [ b] 0 AudioInputProcessor:executeOnContextAvailable
2018-02-21 04:32:19.322 [ b] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-02-21 04:32:19.323 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:19.323 [ b] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=3f0d601a-842c-400f-b51d-3d918261a65e
2018-02-21 04:32:19.324 [ b] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2018-02-21 04:32:19.326 [ b] 0 EventBuilder:buildJsonEventString:messageId=fb2bbcbc-c677-4635-9ccc-ae11b27044a1,namespace=SpeechRecognizer,name=Recognize
2018-02-21 04:32:19.327 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:19.333 [ b] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:19.336 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:19.336 [ c] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=2
2018-02-21 04:32:19.337 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:19.340 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:19.340 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:19.340 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:24.896 [ 11] 0 MessageInterpreter:receive:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27:No dialogRequestId attached to message.
2018-02-21 04:32:24.896 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"39403054-bef9-41a0-aa4e-318cac6f8c27",dialogRequestId:""}
2018-02-21 04:32:24.897 [ 12] I DirectiveRouter:preHandleDirective:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27,action=calling
2018-02-21 04:32:24.897 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27
2018-02-21 04:32:24.898 [ 13] I DirectiveRouter:handleDirective:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27,action=calling
2018-02-21 04:32:24.898 [ b] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-02-21 04:32:24.899 [ b] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-02-21 04:32:24.899 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:24.899 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-02-21 04:32:24.900 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=39403054-bef9-41a0-aa4e-318cac6f8c27
2018-02-21 04:32:24.905 [ c] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:32:25.364 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002d4e0-5af0b36e558b4ac7-81afafbc-19
2018-02-21 04:32:25.631 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"1823720b-73fa-455c-9b0a-811827276951",dialogRequestId:"3f0d601a-842c-400f-b51d-3d918261a65e"}
2018-02-21 04:32:25.632 [ 12] I DirectiveRouter:preHandleDirective:messageId=1823720b-73fa-455c-9b0a-811827276951,action=calling
2018-02-21 04:32:25.632 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:25.633 [ 12] 9 SpeechSynthesizer:preHandleDirective:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:25.634 [ a] 0 SpeechSynthesizer:executePreHandle:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:25.635 [ 13] I DirectiveRouter:handleDirective:messageId=1823720b-73fa-455c-9b0a-811827276951,action=calling
2018-02-21 04:32:25.635 [ 13] 9 SpeechSynthesizer:handleDirective:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:25.637 [ a] 0 SpeechSynthesizer:executeHandle:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:25.637 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-02-21 04:32:25.638 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-02-21 04:32:25.639 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:25.639 [ 10] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-02-21 04:32:25.639 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-02-21 04:32:25.640 [ c] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-02-21 04:32:25.640 [ a] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-02-21 04:32:25.640 [ b] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-02-21 04:32:25.640 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=5,result=200
2018-02-21 04:32:25.641 [ a] 9 SpeechSynthesizer:startPlaying
2018-02-21 04:32:25.641 [ b] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-02-21 04:32:25.641 [ c] 0 HTTP2StreamPool:releaseStream:streamId=5,numAcquiredStreams=1
2018-02-21 04:32:25.642 [ a] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-02-21 04:32:25.643 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-02-21 04:32:25.644 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-02-21 04:32:25.644 [ 2] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-02-21 04:32:25.703 [ 2] 9 BaseStreamSource:initNoAudioFormat
2018-02-21 04:32:26.134 [ a] 9 MediaPlayer:playCalled
2018-02-21 04:32:26.135 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=1,currentId=1
2018-02-21 04:32:26.193 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-02-21 04:32:26.195 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-02-21 04:32:26.196 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:26.196 [ 14] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-02-21 04:32:26.197 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:26.198 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:26.200 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:26.201 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:26.202 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:26.203 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:26.204 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:26.205 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-02-21 04:32:26.205 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:26.206 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:26.207 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:26.208 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:26.209 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:26.210 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:26.211 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:26.212 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:26.213 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:26.213 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-02-21 04:32:26.214 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=15
2018-02-21 04:32:26.215 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:26.217 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:26.218 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:26.219 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:26.220 [ 2] 9 AttachmentReaderSource:read:size=1085,status=0
2018-02-21 04:32:26.221 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:32:26.222 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2018-02-21 04:32:26.222 [ 2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-02-21 04:32:26.223 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2018-02-21 04:32:26.223 [ 2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-02-21 04:32:26.225 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=15
2018-02-21 04:32:26.561 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-02-21 04:32:26.562 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-02-21 04:32:26.675 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-02-21 04:32:26.678 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-02-21 04:32:26.836 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad0
2018-02-21 04:32:26.838 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad0
2018-02-21 04:32:26.846 [ 14] 9 MediaPlayer:onPadAddedCalled
2018-02-21 04:32:26.847 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-02-21 04:32:26.866 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:26.872 [ 2] 9 MediaPlayer:messageReceived:type=stream-start,source=audio-pipeline
2018-02-21 04:32:26.979 [ 2] 9 MediaPlayer:messageReceived:type=latency,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:26.980 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=audiosinkringbuffer0
2018-02-21 04:32:26.983 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:26.984 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:26.986 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:26.987 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:26.989 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:26.991 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:26.992 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:26.993 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-02-21 04:32:26.994 [ 2] 9 MediaPlayer:messageReceived:type=async-done,source=audio-pipeline
2018-02-21 04:32:26.996 [ 2] 9 MediaPlayer:messageReceived:type=new-clock,source=audio-pipeline
2018-02-21 04:32:26.998 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:26.999 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:26.999 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:27.001 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:27.002 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:27.003 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad0
2018-02-21 04:32:27.005 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-02-21 04:32:27.006 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-02-21 04:32:27.007 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:27.009 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:27.010 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:27.012 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:27.012 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-02-21 04:32:27.013 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=1
2018-02-21 04:32:27.014 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=1
2018-02-21 04:32:27.016 [ a] 0 SpeechSynthesizer:executePlaybackStarted
2018-02-21 04:32:27.016 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:27.016 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-02-21 04:32:27.018 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:27.018 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-02-21 04:32:27.019 [ a] 9 MediaPlayer:getOffsetCalled
2018-02-21 04:32:27.020 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=1,currentId=1
2018-02-21 04:32:27.022 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:32:27.023 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-02-21 04:32:27.023 [ 10] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-02-21 04:32:27.024 [ a] 0 EventBuilder:buildJsonEventString:messageId=67996042-c11b-4722-9d80-5483b4623cfd,namespace=SpeechSynthesizer,name=SpeechStarted
2018-02-21 04:32:27.025 [ a] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:27.048 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:27.049 [ c] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2018-02-21 04:32:27.049 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:27.426 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-7
2018-02-21 04:32:27.427 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2018-02-21 04:32:27.427 [ c] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2018-02-21 04:32:27.427 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:32:28.650 [ 14] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-02-21 04:32:28.651 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-02-21 04:32:29.711 [ 2] 9 MediaPlayer:messageReceived:type=eos,source=audio-pipeline
2018-02-21 04:32:30.011 [ 2] 9 BaseStreamSource:~BaseStreamSource
2018-02-21 04:32:30.012 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=1
2018-02-21 04:32:30.012 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=1
2018-02-21 04:32:30.012 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-02-21 04:32:30.012 [ a] 0 SpeechSynthesizer:executePlaybackFinished
2018-02-21 04:32:30.013 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-02-21 04:32:30.013 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-02-21 04:32:30.013 [ 2] 0 MediaPlayer:saveOffsetBeforeTeardown:offset=2904
2018-02-21 04:32:30.014 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:32:30.015 [ a] 0 EventBuilder:buildJsonEventString:messageId=0a7b59a3-62c8-4236-a5ff-55488cb11008,namespace=SpeechSynthesizer,name=SpeechFinished
2018-02-21 04:32:30.016 [ a] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:30.016 [ a] 9 SpeechSynthesizer:setHandlingCompleted
2018-02-21 04:32:30.017 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=1823720b-73fa-455c-9b0a-811827276951,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:30.017 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=1823720b-73fa-455c-9b0a-811827276951
2018-02-21 04:32:30.035 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:30.035 [ c] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2018-02-21 04:32:30.036 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:30.215 [ f] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
2018-02-21 04:32:30.216 [ a] 1 FocusManager:releaseChannel:channelName=Dialog
2018-02-21 04:32:30.217 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-02-21 04:32:30.449 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-9
2018-02-21 04:32:30.449 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204
2018-02-21 04:32:30.450 [ c] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
2018-02-21 04:32:30.450 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:32:31.920 [ b] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-02-21 04:32:31.921 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-02-21 04:32:31.921 [ 7] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=3
2018-02-21 04:32:31.921 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
2018-02-21 04:32:31.922 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=3
2018-02-21 04:32:31.922 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-02-21 04:32:31.924 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
2018-02-21 04:32:31.924 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-02-21 04:32:31.924 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:32:31.927 [ 7] 0 ContextManager:buildContextSuccessful
2018-02-21 04:32:31.927 [ b] 0 AudioInputProcessor:executeOnContextAvailable
2018-02-21 04:32:31.928 [ b] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-02-21 04:32:31.928 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:31.928 [ b] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=3f0d601a-842c-400f-b51d-3d918261a65e,newValue=0fa895c4-6cc5-4e18-8613-71179db2da69
2018-02-21 04:32:31.929 [ b] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=3f0d601a-842c-400f-b51d-3d918261a65e
2018-02-21 04:32:31.930 [ b] 0 EventBuilder:buildJsonEventString:messageId=39931f62-ad82-4e5f-b47d-a176cb750803,namespace=SpeechRecognizer,name=Recognize
2018-02-21 04:32:31.932 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:31.932 [ b] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:34.808 [ 11] 0 MessageInterpreter:receive:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700:No dialogRequestId attached to message.
2018-02-21 04:32:34.809 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700",dialogRequestId:""}
2018-02-21 04:32:34.809 [ 12] I DirectiveRouter:preHandleDirective:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700,action=calling
2018-02-21 04:32:34.810 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700
2018-02-21 04:32:34.810 [ 13] I DirectiveRouter:handleDirective:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700,action=calling
2018-02-21 04:32:34.811 [ b] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-02-21 04:32:34.811 [ b] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-02-21 04:32:34.811 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:34.812 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=4ccd616f-1a6e-4ef1-a41f-fd8b34c7a700
2018-02-21 04:32:34.812 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-02-21 04:32:34.818 [ c] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:32:35.236 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc35-90a0c3c26d397e78-8ce6cc37-11
2018-02-21 04:32:35.238 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"Speaker",name:"SetVolume",messageId:"31b5d310-f630-496d-8ace-06f5e6b0a96a",dialogRequestId:"0fa895c4-6cc5-4e18-8613-71179db2da69"}
2018-02-21 04:32:35.239 [ 12] I DirectiveRouter:preHandleDirective:messageId=31b5d310-f630-496d-8ace-06f5e6b0a96a,action=calling
2018-02-21 04:32:35.239 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=31b5d310-f630-496d-8ace-06f5e6b0a96a
2018-02-21 04:32:35.239 [ c] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-02-21 04:32:35.240 [ 13] I DirectiveRouter:handleDirective:messageId=31b5d310-f630-496d-8ace-06f5e6b0a96a,action=calling
2018-02-21 04:32:35.240 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=11,result=200
2018-02-21 04:32:35.241 [ c] 0 HTTP2StreamPool:releaseStream:streamId=11,numAcquiredStreams=1
2018-02-21 04:32:35.241 [ 15] 9 SpeakerManager:executeSetMuteCalled:mute=false
2018-02-21 04:32:35.242 [ 15] 9 MediaPlayer:setMuteCalled
2018-02-21 04:32:35.242 [ 2] 9 MediaPlayer:handleSetMuteCalled
2018-02-21 04:32:35.243 [ 15] 9 MediaPlayer:setMuteCalled
2018-02-21 04:32:35.243 [ 2] 9 MediaPlayer:handleSetMuteCalled
2018-02-21 04:32:35.243 [ 15] 9 MediaPlayer:setMuteCalled
2018-02-21 04:32:35.244 [ 2] 9 MediaPlayer:handleSetMuteCalled
2018-02-21 04:32:35.244 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.245 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.245 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.246 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.246 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.247 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.247 [ 15] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-02-21 04:32:35.248 [ 15] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2018-02-21 04:32:35.248 [ 15] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-02-21 04:32:35.248 [ 15] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Speaker,name=VolumeState
2018-02-21 04:32:35.249 [ 15] I SpeakerManager:executeSetMute::Skipping sending notifications:reason=forceNoNotifications
2018-02-21 04:32:35.249 [ 15] 9 SpeakerManager:executeSetVolumeCalled:volume=10
2018-02-21 04:32:35.250 [ 15] 9 MediaPlayer:setVolumeCalled
2018-02-21 04:32:35.250 [ 2] 9 MediaPlayer:handleSetVolumeCalled
2018-02-21 04:32:35.250 [ 15] 9 MediaPlayer:setVolumeCalled
2018-02-21 04:32:35.251 [ 2] 9 MediaPlayer:handleSetVolumeCalled
2018-02-21 04:32:35.252 [ 15] 9 MediaPlayer:setVolumeCalled
2018-02-21 04:32:35.252 [ 2] 9 MediaPlayer:handleSetVolumeCalled
2018-02-21 04:32:35.253 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.253 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.254 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.254 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.255 [ 15] 9 MediaPlayer:getSpeakerSettingsCalled
2018-02-21 04:32:35.255 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-02-21 04:32:35.256 [ 15] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-02-21 04:32:35.257 [ 15] 9 SpeakerManager:validateSpeakerSettings:volume=10,mute=false
2018-02-21 04:32:35.257 [ 15] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-02-21 04:32:35.257 [ 15] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Speaker,name=VolumeState
2018-02-21 04:32:35.258 [ 15] 9 SpeakerManager:executeNotifyObserverCalled
2018-02-21 04:32:35.258 [ 15] 9 SpeakerManager:executeSendSpeakerSettingsChangedEvent
#################################################################

SOURCE:DIRECTIVE TYPE:AVS_SYNCED VOLUME:10 MUTE:0
#################################################################

2018-02-21 04:32:35.259 [ 15] 0 EventBuilder:buildJsonEventString:messageId=46d92591-6aba-4fec-bb51-eb02ba7f4806,namespace=Speaker,name=VolumeChanged
2018-02-21 04:32:35.260 [ 15] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:35.260 [ 15] 0 DirectiveProcessor:onHandlingCompeted:messageId=31b5d310-f630-496d-8ace-06f5e6b0a96a,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:35.260 [ 15] 0 CapabilityAgent:removingMessageIdFromMap:messageId=31b5d310-f630-496d-8ace-06f5e6b0a96a
2018-02-21 04:32:35.342 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:35.342 [ c] 0 HTTP2StreamPool:getStream:streamId=13,numAcquiredStreams=2
2018-02-21 04:32:35.343 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:35.440 [ f] 0 DialogUXStateAggregator:transitionFromThinkingTimedOut
2018-02-21 04:32:35.440 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE
2018-02-21 04:32:35.441 [ b] 1 FocusManager:releaseChannel:channelName=Dialog
2018-02-21 04:32:35.442 [ b] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-02-21 04:32:35.442 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-02-21 04:32:35.443 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-02-21 04:32:35.443 [ b] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-02-21 04:32:35.739 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-13
2018-02-21 04:32:35.739 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=13,result=204
2018-02-21 04:32:35.739 [ c] 0 HTTP2StreamPool:releaseStream:streamId=13,numAcquiredStreams=1
2018-02-21 04:32:35.740 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:32:45.155 [ b] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-02-21 04:32:45.156 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-02-21 04:32:45.156 [ 7] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=4
2018-02-21 04:32:45.157 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=4
2018-02-21 04:32:45.156 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=4
2018-02-21 04:32:45.158 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:32:45.158 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-02-21 04:32:45.159 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=4
2018-02-21 04:32:45.159 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-02-21 04:32:45.161 [ 7] 0 ContextManager:buildContextSuccessful
2018-02-21 04:32:45.162 [ b] 0 AudioInputProcessor:executeOnContextAvailable
2018-02-21 04:32:45.162 [ b] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-02-21 04:32:45.163 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:45.163 [ b] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=0fa895c4-6cc5-4e18-8613-71179db2da69,newValue=eb095ba2-a1c6-42c6-a775-9c1b1ebc9473
2018-02-21 04:32:45.164 [ b] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=0fa895c4-6cc5-4e18-8613-71179db2da69
2018-02-21 04:32:45.165 [ b] 0 EventBuilder:buildJsonEventString:messageId=2c95953a-2c99-4cc7-86aa-f1ddf2ec79a7,namespace=SpeechRecognizer,name=Recognize
2018-02-21 04:32:45.167 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:45.167 [ b] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:45.264 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:45.265 [ c] 0 HTTP2StreamPool:getStream:streamId=15,numAcquiredStreams=2
2018-02-21 04:32:45.265 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:45.267 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:45.268 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:45.268 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:32:50.698 [ 11] 0 MessageInterpreter:receive:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4:No dialogRequestId attached to message.
2018-02-21 04:32:50.699 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"d6674f4b-d584-4d85-abee-130086e3a6d4",dialogRequestId:""}
2018-02-21 04:32:50.699 [ 12] I DirectiveRouter:preHandleDirective:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4,action=calling
2018-02-21 04:32:50.700 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4
2018-02-21 04:32:50.701 [ 13] I DirectiveRouter:handleDirective:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4,action=calling
2018-02-21 04:32:50.701 [ b] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-02-21 04:32:50.702 [ b] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-02-21 04:32:50.702 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:50.702 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=d6674f4b-d584-4d85-abee-130086e3a6d4
2018-02-21 04:32:50.703 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-02-21 04:32:51.173 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002d748-db153a65320d2321-0e0d1ad6-5
2018-02-21 04:32:51.462 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"e0c8e878-1a5c-4ddf-92e1-dcde471a9d21",dialogRequestId:"eb095ba2-a1c6-42c6-a775-9c1b1ebc9473"}
2018-02-21 04:32:51.463 [ 12] I DirectiveRouter:preHandleDirective:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21,action=calling
2018-02-21 04:32:51.463 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:51.464 [ 12] 9 SpeechSynthesizer:preHandleDirective:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:51.464 [ a] 0 SpeechSynthesizer:executePreHandle:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:51.464 [ 13] I DirectiveRouter:handleDirective:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21,action=calling
2018-02-21 04:32:51.465 [ 13] 9 SpeechSynthesizer:handleDirective:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:51.469 [ c] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-02-21 04:32:51.470 [ a] 0 SpeechSynthesizer:executeHandle:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:51.475 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-02-21 04:32:51.475 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-02-21 04:32:51.476 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:32:51.476 [ 10] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-02-21 04:32:51.477 [ a] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-02-21 04:32:51.477 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-02-21 04:32:51.477 [ a] 9 SpeechSynthesizer:startPlaying
2018-02-21 04:32:51.477 [ b] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-02-21 04:32:51.478 [ a] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-02-21 04:32:51.478 [ b] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-02-21 04:32:51.479 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-02-21 04:32:51.479 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-02-21 04:32:51.480 [ 2] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-02-21 04:32:51.481 [ 2] 9 BaseStreamSource:initNoAudioFormat
2018-02-21 04:32:51.474 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=15,result=200
2018-02-21 04:32:51.482 [ c] 0 HTTP2StreamPool:releaseStream:streamId=15,numAcquiredStreams=1
2018-02-21 04:32:51.483 [ a] 9 MediaPlayer:playCalled
2018-02-21 04:32:51.485 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=2,currentId=2
2018-02-21 04:32:51.494 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-02-21 04:32:51.495 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-02-21 04:32:51.496 [ 16] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-02-21 04:32:51.496 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:51.498 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:51.498 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:51.499 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:51.500 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:51.501 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:51.502 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:51.503 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:51.504 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-02-21 04:32:51.504 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:51.506 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:51.507 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:51.507 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:51.508 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:51.508 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:51.509 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:51.509 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:51.510 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:32:51.510 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-02-21 04:32:51.511 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=34
2018-02-21 04:32:51.511 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:51.512 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:51.512 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:51.513 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:32:51.513 [ 2] 9 AttachmentReaderSource:read:size=1085,status=0
2018-02-21 04:32:51.514 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:32:51.515 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2018-02-21 04:32:51.515 [ 2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-02-21 04:32:51.516 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2018-02-21 04:32:51.516 [ 2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-02-21 04:32:51.517 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=34
2018-02-21 04:32:51.518 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux1
2018-02-21 04:32:51.518 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux1
2018-02-21 04:32:51.521 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse1
2018-02-21 04:32:51.522 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse1
2018-02-21 04:32:51.523 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad1
2018-02-21 04:32:51.524 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad1
2018-02-21 04:32:51.527 [ 16] 9 MediaPlayer:onPadAddedCalled
2018-02-21 04:32:51.528 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-02-21 04:32:51.552 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:51.555 [ 2] 9 MediaPlayer:messageReceived:type=stream-start,source=audio-pipeline
2018-02-21 04:32:51.647 [ 2] 9 MediaPlayer:messageReceived:type=latency,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.647 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=audiosinkringbuffer1
2018-02-21 04:32:51.650 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.650 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:51.651 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.651 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:51.652 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.653 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:51.654 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:51.654 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-02-21 04:32:51.655 [ 2] 9 MediaPlayer:messageReceived:type=async-done,source=audio-pipeline
2018-02-21 04:32:51.657 [ 2] 9 MediaPlayer:messageReceived:type=new-clock,source=audio-pipeline
2018-02-21 04:32:51.659 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.659 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:32:51.664 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:32:51.665 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:32:51.666 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:32:51.667 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad1
2018-02-21 04:32:51.668 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse1
2018-02-21 04:32:51.672 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:32:51.672 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:32:51.673 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux1
2018-02-21 04:32:51.674 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:32:51.674 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:32:51.675 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:32:51.675 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:32:51.676 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-02-21 04:32:51.676 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=2
2018-02-21 04:32:51.677 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=2
2018-02-21 04:32:51.678 [ a] 0 SpeechSynthesizer:executePlaybackStarted
2018-02-21 04:32:51.678 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-02-21 04:32:51.678 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-02-21 04:32:51.679 [ a] 9 MediaPlayer:getOffsetCalled
2018-02-21 04:32:51.679 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=2,currentId=2
2018-02-21 04:32:51.680 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:32:51.681 [ 10] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-02-21 04:32:51.681 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-02-21 04:32:51.681 [ a] 0 EventBuilder:buildJsonEventString:messageId=643016a4-943c-4a3d-aec5-9183ad42fa63,namespace=SpeechSynthesizer,name=SpeechStarted
2018-02-21 04:32:51.683 [ a] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:51.683 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:51.684 [ c] 0 HTTP2StreamPool:getStream:streamId=17,numAcquiredStreams=2
2018-02-21 04:32:51.685 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:52.063 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-17
2018-02-21 04:32:52.064 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=17,result=204
2018-02-21 04:32:52.064 [ c] 0 HTTP2StreamPool:releaseStream:streamId=17,numAcquiredStreams=1
2018-02-21 04:32:52.064 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:32:53.306 [ 16] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-02-21 04:32:53.307 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-02-21 04:32:54.367 [ 2] 9 MediaPlayer:messageReceived:type=eos,source=audio-pipeline
2018-02-21 04:32:54.668 [ 2] 9 BaseStreamSource:~BaseStreamSource
2018-02-21 04:32:54.669 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=2
2018-02-21 04:32:54.669 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=2
2018-02-21 04:32:54.669 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-02-21 04:32:54.669 [ a] 0 SpeechSynthesizer:executePlaybackFinished
2018-02-21 04:32:54.670 [ 2] 0 MediaPlayer:saveOffsetBeforeTeardown:offset=2904
2018-02-21 04:32:54.670 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-02-21 04:32:54.671 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-02-21 04:32:54.672 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:32:54.673 [ a] 0 EventBuilder:buildJsonEventString:messageId=1e4715fd-ada1-4413-8e96-ae4ebc7e3416,namespace=SpeechSynthesizer,name=SpeechFinished
2018-02-21 04:32:54.674 [ a] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:32:54.674 [ a] 9 SpeechSynthesizer:setHandlingCompleted
2018-02-21 04:32:54.675 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21,directiveBeingPreHandled=(nullptr)
2018-02-21 04:32:54.675 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=e0c8e878-1a5c-4ddf-92e1-dcde471a9d21
2018-02-21 04:32:54.772 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:32:54.772 [ c] 0 HTTP2StreamPool:getStream:streamId=19,numAcquiredStreams=2
2018-02-21 04:32:54.773 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:32:54.873 [ f] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
2018-02-21 04:32:54.874 [ a] 1 FocusManager:releaseChannel:channelName=Dialog
2018-02-21 04:32:54.875 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-02-21 04:32:55.182 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002dc01-3b008af47d3af673-bd6ece1a-19
2018-02-21 04:32:55.182 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=19,result=204
2018-02-21 04:32:55.183 [ c] 0 HTTP2StreamPool:releaseStream:streamId=19,numAcquiredStreams=1
2018-02-21 04:32:55.183 [ c] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-02-21 04:33:00.220 [ b] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-02-21 04:33:00.221 [ f] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-02-21 04:33:00.221 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=5
2018-02-21 04:33:00.222 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-02-21 04:33:00.221 [ 7] 0 NotificationsCapabilityAgent:provideState:stateRequestToken=5
2018-02-21 04:33:00.222 [ 8] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=5
2018-02-21 04:33:00.223 [ 4] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=5
2018-02-21 04:33:00.223 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-02-21 04:33:00.224 [ 4] 0 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-02-21 04:33:00.226 [ 7] 0 ContextManager:buildContextSuccessful
2018-02-21 04:33:00.227 [ b] 0 AudioInputProcessor:executeOnContextAvailable
2018-02-21 04:33:00.227 [ b] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-02-21 04:33:00.228 [ b] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=eb095ba2-a1c6-42c6-a775-9c1b1ebc9473,newValue=fc9e9fd8-d656-4574-8651-48724eee29c4
2018-02-21 04:33:00.228 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:33:00.228 [ b] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=eb095ba2-a1c6-42c6-a775-9c1b1ebc9473
2018-02-21 04:33:00.230 [ b] 0 EventBuilder:buildJsonEventString:messageId=19b8a751-a18c-4aa9-830d-ffcd093f8ed1,namespace=SpeechRecognizer,name=Recognize
2018-02-21 04:33:00.232 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-02-21 04:33:00.232 [ b] 9 HTTP2Transport:enqueueRequest
2018-02-21 04:33:00.325 [ c] 0 HTTP2Transport:processNextOutgoingMessage
2018-02-21 04:33:00.326 [ c] 0 HTTP2StreamPool:getStream:streamId=21,numAcquiredStreams=2
2018-02-21 04:33:00.326 [ c] 9 HTTP2Transport:insertActiveStream:handle=0x34dc2a8
2018-02-21 04:33:00.328 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:33:00.329 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:33:00.329 [ c] E InProcessAttachmentReader:readFailed:reason=bytes requested smaller than SDS word size
2018-02-21 04:33:05.029 [ 11] 0 MessageInterpreter:receive:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3:No dialogRequestId attached to message.
2018-02-21 04:33:05.029 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"082af715-f2e3-44e3-925a-6aa0fdd9a6c3",dialogRequestId:""}
2018-02-21 04:33:05.030 [ 12] I DirectiveRouter:preHandleDirective:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3,action=calling
2018-02-21 04:33:05.030 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3
2018-02-21 04:33:05.031 [ 13] I DirectiveRouter:handleDirective:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3,action=calling
2018-02-21 04:33:05.031 [ b] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-02-21 04:33:05.032 [ b] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-02-21 04:33:05.032 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3,directiveBeingPreHandled=(nullptr)
2018-02-21 04:33:05.033 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=082af715-f2e3-44e3-925a-6aa0fdd9a6c3
2018-02-21 04:33:05.033 [ f] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-02-21 04:33:05.038 [ c] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:33:05.557 [ c] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0e2cbdfffebad174-000056af-0002cecb-12e56c6a4d6a3464-511f9a83-235
2018-02-21 04:33:05.837 [ 11] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"20ea302c-00e8-4adc-befb-369c5ceb2e51",dialogRequestId:"fc9e9fd8-d656-4574-8651-48724eee29c4"}
2018-02-21 04:33:05.838 [ 12] I DirectiveRouter:preHandleDirective:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51,action=calling
2018-02-21 04:33:05.838 [ 12] 0 CapabilityAgent:addingMessageIdToMap:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51
2018-02-21 04:33:05.839 [ 12] 9 SpeechSynthesizer:preHandleDirective:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51
2018-02-21 04:33:05.839 [ 13] I DirectiveRouter:handleDirective:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51,action=calling
2018-02-21 04:33:05.840 [ 13] 9 SpeechSynthesizer:handleDirective:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51
2018-02-21 04:33:05.839 [ a] 0 SpeechSynthesizer:executePreHandle:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51
2018-02-21 04:33:05.842 [ a] 0 SpeechSynthesizer:executeHandle:messageId=20ea302c-00e8-4adc-befb-369c5ceb2e51
2018-02-21 04:33:05.843 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-02-21 04:33:05.843 [ c] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-02-21 04:33:05.843 [ 10] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-02-21 04:33:05.844 [ 10] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-02-21 04:33:05.844 [ b] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-02-21 04:33:05.844 [ 10] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-02-21 04:33:05.844 [ c] 0 HTTP2Transport:cleanupFinishedStream:streamId=21,result=200
2018-02-21 04:33:05.846 [ c] 0 HTTP2StreamPool:releaseStream:streamId=21,numAcquiredStreams=1
2018-02-21 04:33:05.845 [ a] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-02-21 04:33:05.844 [ b] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-02-21 04:33:05.847 [ a] 9 SpeechSynthesizer:startPlaying
2018-02-21 04:33:05.847 [ b] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-02-21 04:33:05.848 [ a] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-02-21 04:33:05.848 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-02-21 04:33:05.849 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-02-21 04:33:05.849 [ 2] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-02-21 04:33:05.850 [ 2] 9 BaseStreamSource:initNoAudioFormat
2018-02-21 04:33:05.852 [ a] 9 MediaPlayer:playCalled
2018-02-21 04:33:05.852 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=3,currentId=3
2018-02-21 04:33:05.860 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-02-21 04:33:05.861 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-02-21 04:33:05.862 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:33:05.862 [ 17] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-02-21 04:33:05.863 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:33:05.863 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:33:05.864 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:33:05.864 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:33:05.865 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:33:05.865 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:33:05.867 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:33:05.867 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-02-21 04:33:05.868 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:33:05.868 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:33:05.868 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:33:05.869 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:33:05.869 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:33:05.869 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:33:05.870 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:33:05.870 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:33:05.871 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-02-21 04:33:05.871 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-02-21 04:33:05.871 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=41
2018-02-21 04:33:05.872 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:33:05.872 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:33:05.873 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-02-21 04:33:05.873 [ 2] 9 AttachmentReaderSource:read:size=1005,status=0
2018-02-21 04:33:05.874 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-02-21 04:33:05.874 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2018-02-21 04:33:05.875 [ 2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-02-21 04:33:05.875 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2018-02-21 04:33:05.875 [ 2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-02-21 04:33:05.876 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=41
2018-02-21 04:33:05.877 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux2
2018-02-21 04:33:05.877 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux2
2018-02-21 04:33:05.880 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse2
2018-02-21 04:33:05.882 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse2
2018-02-21 04:33:05.886 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad2
2018-02-21 04:33:05.887 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad2
2018-02-21 04:33:05.890 [ 17] 9 MediaPlayer:onPadAddedCalled
2018-02-21 04:33:05.890 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-02-21 04:33:05.910 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:33:05.910 [ 2] 9 MediaPlayer:messageReceived:type=stream-start,source=audio-pipeline
2018-02-21 04:33:06.038 [ 2] 9 MediaPlayer:messageReceived:type=latency,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.038 [ 2] 9 MediaPlayer:messageReceived:type=stream-status,source=audiosinkringbuffer2
2018-02-21 04:33:06.041 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.041 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:33:06.042 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.043 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:33:06.044 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.045 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:33:06.046 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:33:06.046 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-02-21 04:33:06.047 [ 2] 9 MediaPlayer:messageReceived:type=async-done,source=audio-pipeline
2018-02-21 04:33:06.052 [ 2] 9 MediaPlayer:messageReceived:type=new-clock,source=audio-pipeline
2018-02-21 04:33:06.054 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.055 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-02-21 04:33:06.056 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-02-21 04:33:06.057 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-02-21 04:33:06.058 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decodedQueue
2018-02-21 04:33:06.059 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mad2
2018-02-21 04:33:06.060 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse2
2018-02-21 04:33:06.061 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux2
2018-02-21 04:33:06.061 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-02-21 04:33:06.063 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-02-21 04:33:06.063 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-02-21 04:33:06.064 [ 2] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-02-21 04:33:06.064 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-02-21 04:33:06.065 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=3
2018-02-21 04:33:06.065 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=3
2018-02-21 04:33:06.066 [ a] 0 SpeechSynthesizer:executePlaybackStarted
2018-02-21 04:33:06.066 [ a] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-02-21 04:33:06.067 [ a] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-02-21 04:33:06.067 [ a] 9 MediaPlayer:getOffsetCalled
2018-02-21 04:33:06.068 [ 2] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink-actual-sink-alsa
2018-02-21 04:33:06.068 [ 2] 0 MediaPlayer:callingOnTags
2018-02-21 04:33:06.069 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
2018-02-21 04:33:06.070 [ a] 0 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-02-21 04:33:06.070 [ 10] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-02-21 04:33:06.070 [ f] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-02-21 04:33:06.070 [ a] 0 EventBuilder:buildJsonEventString:messageId=91e79948-30bf-4687-bcf5-ed423a855b64,namespace=SpeechSynthesizer,name=SpeechStarted
2018-02-21 04:33:06.072 [ a] 9 HTTP2Transport:enqueueRequest
terminate called after throwing an instance of 'std::future_error'
what(): Promise already satisfied
Aborted
root@OpenWrt:/mnt/Alexa_SDK_APP#

@kencecka
Copy link
Contributor

Hi @NathanMani,

Per our previous discussions, it sounds like you are using GCC v4.8.2. The AVS Device SDK requires at least GCC v4.8.5. Earlier versions of GCC has incomplete or unstable implementations of some C++11 features.

The specific issue you are seeing has come up a few times in the past with older/unsupported GCC versions, but does not reproduce on supported GCC versions. Investigations into it have hinted at a toolchain issue, although we've never chased it to root cause.

From some cursory reading about OpenWRT, it appears to have some flexibility in building toolchains; can you reconfigure your OpenWRT environment to use a more recent GCC release?

Ken

@sanjayrd sanjayrd closed this as completed Mar 5, 2018
@BennyAvramson BennyAvramson mentioned this issue Apr 10, 2018
Closed
6 tasks
@kclchan kclchan mentioned this issue Dec 10, 2018
Closed
8 tasks
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@sanjayrd @kencecka @NathanMani