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

Alexa Listening / Thinking / Speaking. But no sound! #774

Closed
6 tasks
jairoMolina9 opened this issue Jun 10, 2018 · 2 comments
Closed
6 tasks

Alexa Listening / Thinking / Speaking. But no sound! #774

jairoMolina9 opened this issue Jun 10, 2018 · 2 comments

Comments

@jairoMolina9
Copy link

IMPORTANT: Before you create an issue, please take a look at our Issue Reporting Guide.

Briefly summarize your issue:

From what I can see in the terminal everything seems to work, I just cant hear Alexa's output
This is all that appears in my terminal:

pi@raspberrypi:~ $ sudo bash startsample.sh
2018-06-10 05:47:57.272 [ 1] I sdkVersion: 1.7.1
configFile /home/pi/build/Integration/AlexaClientSDKConfig.json
Running app with log level: DEBUG9
2018-06-10 05:47:57.288 [ 1] 0 ConfigurationNode:initializeSuccess
2018-06-10 05:47:57.299 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.385 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.387 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.388 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.390 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.390 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.392 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.392 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.394 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.395 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.396 [ 1] 9 MediaPlayer:createCalled
2018-06-10 05:47:57.397 [ 1] 9 MediaPlayer:noOutputConversion
2018-06-10 05:47:57.400 [ 1] 5 SQLiteBluetoothStorage:create
2018-06-10 05:47:57.402 [ 1] 5 CBLAuthDelegate:create
2018-06-10 05:47:57.403 [ 1] 5 CBLAuthDelegate:CBLAuthDelegate
2018-06-10 05:47:57.403 [ 1] 5 CBLAuthDelegate:init
2018-06-10 05:47:57.403 [ 1] 5 CBLAuthDelegateConfiguration:create
2018-06-10 05:47:57.403 [ 1] 5 CBLAuthDelegateConfiguration:init
2018-06-10 05:47:57.403 [ 1] 5 CBLAuthDelegateConfiguration:initScopeData
2018-06-10 05:47:57.404 [ 1] 9 CBLAuthDelegateConfiguration:initScopeDataSucceeded
2018-06-10 05:47:57.404 [ 1] 5 SQLiteCBLAuthDelegateStorage:open
2018-06-10 05:47:57.406 [ 2] 5 CBLAuthDelegate:handleAuthorizationFlow
2018-06-10 05:47:57.407 [ 2] 5 CBLAuthDelegate:handleStarting
2018-06-10 05:47:57.407 [ 2] 5 SQLiteCBLAuthDelegateStorage:getRefreshToken
2018-06-10 05:47:57.407 [ 2] 5 CBLAuthDelegate:handleRefreshingToken
2018-06-10 05:47:57.408 [ 2] 5 CBLAuthDelegate:requestRefresh
2018-06-10 05:47:57.409 [ 1] 5 CBLAuthDelegate:addAuthObserver:observer=0x1ef72f4
2018-06-10 05:47:57.410 [ 1] 5 CBLAuthDelegate:addAuthObserver:observer=0x1ee2168
2018-06-10 05:47:57.410 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x1ee216c
2018-06-10 05:47:57.413 [ 1] 5 InternetConnectionMonitor:startMonitoring
2018-06-10 05:47:57.417 [ 3] 5 InternetConnectionMonitor:testConnection
2018-06-10 05:47:57.444 [ 1] 9 MediaPlayer:setObserverCalled
2018-06-10 05:47:57.444 [ 4] 0 MediaPlayer:handleSetObserverCalled
2018-06-10 05:47:57.445 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x1efcc7c
2018-06-10 05:47:57.446 [ 1] 9 PlaybackRouter:create::called
2018-06-10 05:47:57.448 [ 1] 9 MediaPlayer:setObserverCalled
2018-06-10 05:47:57.448 [ 4] 0 MediaPlayer:handleSetObserverCalled
2018-06-10 05:47:57.448 [ 1] 9 MediaPlayer:setObserverCalled
2018-06-10 05:47:57.449 [ 4] 0 MediaPlayer:handleSetObserverCalled
2018-06-10 05:47:57.452 [ 1] 9 AlertScheduler:setTimerForNextAlertLocked
2018-06-10 05:47:57.452 [ 1] 9 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2018-06-10 05:47:57.454 [ 1] 5 NotificationsRenderer:create
2018-06-10 05:47:57.454 [ 1] 9 MediaPlayer:setObserverCalled
2018-06-10 05:47:57.455 [ 4] 0 MediaPlayer:handleSetObserverCalled
2018-06-10 05:47:57.455 [ 5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2018-06-10 05:47:57.457 [ 1] 5 NotificationsCapabilityAgent:init
2018-06-10 05:47:57.458 [ 1] 5 NotificationsRenderer:addObserver
2018-06-10 05:47:57.463 [ 6] 5 NotificationsCapabilityAgent:executeInit
2018-06-10 05:47:57.464 [ 6] 5 NotificationsCapabilityAgent:notifyObservers:indicatorState=0
2018-06-10 05:47:57.465 [ 6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0
2018-06-10 05:47:57.467 [ 6] 9 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-06-10 05:47:57.470 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.471 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.471 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.471 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.471 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.471 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2018-06-10 05:47:57.472 [ 1] 0 SpeakerManager:mapCreated:numAvsSynced=5,numLocal=1
2018-06-10 05:47:57.472 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-06-10 05:47:57.472 [ 4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-06-10 05:47:57.473 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-06-10 05:47:57.473 [ 4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-06-10 05:47:57.473 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-06-10 05:47:57.473 [ 4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-06-10 05:47:57.474 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-06-10 05:47:57.474 [ 4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-06-10 05:47:57.474 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-06-10 05:47:57.474 [ 4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-06-10 05:47:57.474 [ 1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-06-10 05:47:57.474 [ 1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2018-06-10 05:47:57.474 [ 1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-06-10 05:47:57.477 [ 1] 0 ExternalMediaPlayer:createAdapters
2018-06-10 05:47:57.481 [ 1] 1 AudioPlayer:addObserver
2018-06-10 05:47:57.483 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x1f03f48,policy=BLOCKING
2018-06-10 05:47:57.484 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x1f05088,policy=NON_BLOCKING
2018-06-10 05:47:57.484 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x1f05088,policy=NON_BLOCKING
2018-06-10 05:47:57.484 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x1f05088,policy=NON_BLOCKING
2018-06-10 05:47:57.484 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Play,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Logout,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Login,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=SetSeekPosition,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Play,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Unfavorite,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableShuffle,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Pause,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Next,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.485 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Rewind,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeat,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Previous,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=StartOver,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=FastForward,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Favorite,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.486 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableShuffle,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.487 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeatOne,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.487 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableRepeat,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.487 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=AdjustSeekPosition,handler=0x1f128a0,policy=NON_BLOCKING
2018-06-10 05:47:57.488 [ 7] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2018-06-10 05:47:57.488 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x1f03458,policy=NON_BLOCKING
2018-06-10 05:47:57.489 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x1f03458,policy=NON_BLOCKING
2018-06-10 05:47:57.489 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x1f06048,policy=NON_BLOCKING
2018-06-10 05:47:57.490 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x1f06048,policy=NON_BLOCKING
2018-06-10 05:47:57.491 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x1f154e8,policy=NON_BLOCKING
2018-06-10 05:47:57.492 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x1f031bc,policy=NON_BLOCKING
2018-06-10 05:47:57.493 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x1f12510,policy=NON_BLOCKING
2018-06-10 05:47:57.493 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x1f12510,policy=NON_BLOCKING
2018-06-10 05:47:57.494 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x1f12510,policy=NON_BLOCKING
2018-06-10 05:47:57.494 [ 1] 5 TemplateRuntime:getConfiguration
2018-06-10 05:47:57.494 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x1f14570,policy=HANDLE_IMMEDIATELY
2018-06-10 05:47:57.494 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x1f14570,policy=HANDLE_IMMEDIATELY
2018-06-10 05:47:57.494 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=ClearIndicator,handler=0x1f09cd4,policy=HANDLE_IMMEDIATELY
2018-06-10 05:47:57.494 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=SetIndicator,handler=0x1f09cd4,policy=HANDLE_IMMEDIATELY
2018-06-10 05:47:57.497 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2018-06-10 05:47:57.498 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1ee2178
2018-06-10 05:47:57.498 [ 1] 5 NotificationsCapabilityAgent:addObserver
2018-06-10 05:47:57.498 [ 1] 5 TemplateRuntime:addObserver
#################################################

NOTIFICATION INDICATOR STATE: OFF

#################################################

2018-06-10 05:47:57.501 [ 8] 5 TemplateRuntime:addObserverInExecutor
2018-06-10 05:47:57.574 [ 1] I SensoryKeywordDetector:License expires on 28 Sep 2018 00:00:00 GMT
2018-06-10 05:47:57.574 [ 1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
2018-06-10 05:47:57.587 [ 1] 5 CBLAuthDelegate:addAuthObserver:observer=0x1f55270
2018-06-10 05:47:57.587 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x1f55274
2018-06-10 05:47:57.587 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x1efcaf0
# # # ##### ##### ###### # #
# # # # # # # # # # # #
# # # # # # # # # #
# # # # ##### ##### # # ###
####### # # # # # # # #
# # # # # # # # # # # #
# # # ##### ##### ###### # #

   #####                                           #                      
  #     #   ##   #    # #####  #      ######      # #   #####  #####      
  #        #  #  ##  ## #    # #      #          #   #  #    # #    #     
   #####  #    # # ## # #    # #      #####     #     # #    # #    #     
        # ###### #    # #####  #      #         ####### #####  #####      
  #     # #    # #    # #      #      #         #     # #      #          
   #####  #    # #    # #      ###### ######    #     # #      #          

   SDK Version 1.7.1

+----------------------------------------------------------------------------+
| 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 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. |
| Reset device: |
| Press 'k' followed by Enter at any time to reset your device. This |
| will erase any data stored in the device and you will have to |
| re-register your device. |
| This option will also exit the application. |
| Quit: |
| Press 'q' followed by Enter at any time to quit the application. |
+----------------------------------------------------------------------------+

2018-06-10 05:47:57.595 [ 9] 5 CapabilitiesDelegate:setCapabilitiesState:newCapabilitiesState=SUCCESS
2018-06-10 05:47:57.596 [ 9] 9 CapabilitiesDelegate:callingOnCapabilitiesStateChange:state=SUCCESS,error=SUCCESS
2018-06-10 05:47:57.596 [ 9] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2018-06-10 05:47:57.598 [ 9] 5 CBLAuthDelegate:addAuthObserver:observer=0x1d54118
2018-06-10 05:47:57.599 [ a] 0 PostConnectSynchronizer:doPostConnect
2018-06-10 05:47:57.600 [ a] 5 PostConnectSynchronizer:setState:from=IDLE,to=RUNNING
2018-06-10 05:47:57.601 [ b] 5 PostConnectSynchronizer:mainLoop
2018-06-10 05:47:57.601 [ b] 5 PostConnectSynchronizer:setState:from=RUNNING,to=FETCHING
2018-06-10 05:47:57.608 [ 5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
#############################

Connecting...

#############################

2018-06-10 05:47:57.609 [ c] 5 VisualActivityTracker:provideState
2018-06-10 05:47:57.610 [ d] 5 VisualActivityTracker:executeProvideState
2018-06-10 05:47:57.611 [ d] 9 ContextManager:updateStateLocked:action=updatedState,namespace=VisualActivityTracker,name=ActivityState
2018-06-10 05:47:57.612 [ c] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=1
2018-06-10 05:47:57.612 [ e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-06-10 05:47:57.612 [ c] 5 AudioActivityTracker:provideState
2018-06-10 05:47:57.613 [ c] 9 SpeechSynthesizer:provideState:token=1
2018-06-10 05:47:57.613 [ e] 9 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-06-10 05:47:57.613 [ c] 0 AudioPlayer:provideState:stateRequestToken=1
2018-06-10 05:47:57.613 [ e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-06-10 05:47:57.614 [ e] 9 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-06-10 05:47:57.614 [ 6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=1
2018-06-10 05:47:57.615 [ 6] 9 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-06-10 05:47:57.616 [ f] 5 AudioActivityTracker:executeProvideState
2018-06-10 05:47:57.617 [ f] 9 ContextManager:updateStateLocked:action=updatedState,namespace=AudioActivityTracker,name=ActivityState
2018-06-10 05:47:57.618 [ 10] 9 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechRecognizer,name=RecognizerState
2018-06-10 05:47:57.619 [ 11] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1
2018-06-10 05:47:57.619 [ 11] 9 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-06-10 05:47:57.619 [ 12] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-06-10 05:47:57.620 [ 12] 9 ContextManager:updateStateLocked:action=updatedState,namespace=AudioPlayer,name=PlaybackState
2018-06-10 05:47:57.620 [ c] 9 ContextManager:buildContextIgnored:namespace=VisualActivityTracker,name=ActivityState
2018-06-10 05:47:57.621 [ c] 9 ContextManager:buildContextIgnored:namespace=AudioActivityTracker,name=ActivityState
2018-06-10 05:47:57.622 [ c] 5 ContextManager:buildContextSuccessful
2018-06-10 05:47:57.622 [ c] 5 PostConnectSynchronizer:onContectAvailable
2018-06-10 05:47:57.622 [ c] 5 PostConnectSynchronizer:setState:from=FETCHING,to=SENDING
2018-06-10 05:47:57.624 [ c] 0 EventBuilder:buildJsonEventString:messageId=d2c465a1-eb9c-42bb-8494-d4d7c6515b73,namespace=System,name=SynchronizeState
2018-06-10 05:47:57.627 [ c] 9 HTTP2Transport:enqueueRequest
2018-06-10 05:47:57.909 [ 3] 0 InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-06-10 05:47:57.910 [ 3] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
2018-06-10 05:47:57.910 [ 3] 5 InternetConnectionMonitor:notifyObserversLocked
2018-06-10 05:48:01.267 [ 2] 5 HttpPost:doPostSucceeded:code=200
2018-06-10 05:48:01.267 [ 2] 5 CBLAuthDelegate:receiveTokenResponse:code=200
2018-06-10 05:48:01.267 [ 2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS
2018-06-10 05:48:01.267 [ 2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
2018-06-10 05:48:01.267 [ 2] 5 CBLAuthDelegate:setRefreshToken
2018-06-10 05:48:01.268 [ 2] 5 SQLiteCBLAuthDelegateStorage:setRefreshToken
2018-06-10 05:48:01.293 [ 2] 5 CBLAuthDelegate:setAuthState:newAuthState=REFRESHED
2018-06-10 05:48:01.293 [ 2] 9 CBLAuthDelegate:callingOnAuthStateChange:state=REFRESHED,error=SUCCESS
###########################

Authorized!

###########################

2018-06-10 05:48:01.294 [ a] 3 HTTP2Transport:setupDownchannelStream:url=https://avs-alexa-na.amazon.com/v20160207/directives
2018-06-10 05:48:01.295 [ a] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1
2018-06-10 05:48:01.574 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 02230afffe17cedc-00007d7c-000e2132-8377f11cb517c7ba-8b588fb1-1
2018-06-10 05:48:01.574 [ a] 0 HTTP2Transport:processNextOutgoingMessage
2018-06-10 05:48:01.575 [ a] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2
2018-06-10 05:48:01.575 [ a] 9 HTTP2Transport:insertActiveStream:handle=0x714e0ec0
2018-06-10 05:48:01.898 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 02230afffe17cedc-00007d7c-000dfb6c-00ebcee6be5eb594-16c5e20c-25
2018-06-10 05:48:01.899 [ a] 5 PostConnectSynchronizer:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-06-10 05:48:01.899 [ a] 5 PostConnectSynchronizer:stop
2018-06-10 05:48:01.899 [ a] 5 PostConnectSynchronizer:setState:from=SENDING,to=STOPPING
2018-06-10 05:48:01.899 [ b] 5 PostConnectSynchronizer:mainLoopReturning
2018-06-10 05:48:01.900 [ a] 5 PostConnectSynchronizer:setState:from=STOPPING,to=STOPPED
2018-06-10 05:48:01.900 [ a] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2018-06-10 05:48:01.901 [ 5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2018-06-10 05:48:01.901 [ a] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=204
########################################

Alexa is currently idle!

########################################

2018-06-10 05:48:01.901 [ a] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
2018-06-10 05:48:01.901 [ a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-06-10 05:48:01.902 [ a] 0 PostConnectSynchronizer:~PostConnectSynchronizer
2018-06-10 05:48:01.902 [ a] 5 PostConnectSynchronizer:stop
2018-06-10 05:48:01.902 [ a] 5 PostConnectSynchronizer:stopIgnored:reason=alreadyStopped
t
2018-06-10 05:48:05.803 [ 10] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-06-10 05:48:05.804 [ 7] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-06-10 05:48:05.804 [ 7] 5 TemplateRuntime:onDialogUXStateChanged:state=LISTENING
2018-06-10 05:48:05.804 [ c] 5 VisualActivityTracker:provideState
############################

Listening...

############################

2018-06-10 05:48:05.804 [ d] 5 VisualActivityTracker:executeProvideState
2018-06-10 05:48:05.805 [ d] 9 ContextManager:updateStateLocked:action=updatedState,namespace=VisualActivityTracker,name=ActivityState
2018-06-10 05:48:05.805 [ e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-10 05:48:05.805 [ e] 9 ContextManager:updateStateLocked:action=updatedState,namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-06-10 05:48:05.805 [ e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-10 05:48:05.805 [ e] 9 ContextManager:updateStateLocked:action=updatedState,namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-06-10 05:48:05.805 [ c] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=2
2018-06-10 05:48:05.805 [ c] 5 AudioActivityTracker:provideState
2018-06-10 05:48:05.805 [ 6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-10 05:48:05.806 [ 6] 9 ContextManager:updateStateLocked:action=updatedState,namespace=Notifications,name=IndicatorState
2018-06-10 05:48:05.806 [ f] 5 AudioActivityTracker:executeProvideState
2018-06-10 05:48:05.806 [ f] 9 ContextManager:updateStateLocked:action=updatedState,namespace=AudioActivityTracker,name=ActivityState
2018-06-10 05:48:05.806 [ c] 9 ContextManager:buildContextIgnored:namespace=VisualActivityTracker,name=ActivityState
2018-06-10 05:48:05.806 [ c] 9 ContextManager:buildContextIgnored:namespace=AudioActivityTracker,name=ActivityState
2018-06-10 05:48:05.807 [ c] 5 ContextManager:buildContextSuccessful
2018-06-10 05:48:05.807 [ 10] 0 AudioInputProcessor:executeOnContextAvailable
2018-06-10 05:48:05.807 [ 10] 1 FocusManager:acquireChannel:channelName=Dialog,interface=SpeechRecognizer
2018-06-10 05:48:05.808 [ 10] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=a645d473-60ec-40ab-86f9-7c6d8c5d4b73
2018-06-10 05:48:05.808 [ 10] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2018-06-10 05:48:05.808 [ 10] 0 EventBuilder:buildJsonEventString:messageId=27d97d84-2829-4081-a6df-b51f6fe5159e,namespace=SpeechRecognizer,name=Recognize
2018-06-10 05:48:05.810 [ 13] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-06-10 05:48:05.811 [ 13] 5 AudioActivityTracker:notifyOfActivityUpdates
2018-06-10 05:48:05.811 [ f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
2018-06-10 05:48:05.812 [ 10] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-06-10 05:48:05.812 [ 10] 9 HTTP2Transport:enqueueRequest
2018-06-10 05:48:05.911 [ a] 0 HTTP2Transport:processNextOutgoingMessage
2018-06-10 05:48:05.911 [ a] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=2
2018-06-10 05:48:05.912 [ a] 9 HTTP2Transport:insertActiveStream:handle=0x714e0ec0
2018-06-10 05:48:08.552 [ 14] 0 MessageInterpreter:receive:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c:No dialogRequestId attached to message.
2018-06-10 05:48:08.553 [ 14] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"d3417cbf-ecf9-41d9-a6e3-4f23f87de58c",dialogRequestId:""}
2018-06-10 05:48:08.554 [ 15] I DirectiveRouter:preHandleDirective:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c,action=calling
2018-06-10 05:48:08.555 [ 15] 0 CapabilityAgent:addingMessageIdToMap:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c
2018-06-10 05:48:08.556 [ 16] I DirectiveRouter:handleDirective:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c,action=calling
2018-06-10 05:48:08.556 [ 10] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-06-10 05:48:08.556 [ 10] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-06-10 05:48:08.556 [ 7] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-06-10 05:48:08.556 [ 10] 0 DirectiveProcessor:onHandlingCompeted:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c,directiveBeingPreHandled=(nullptr)
2018-06-10 05:48:08.556 [ 7] 5 TemplateRuntime:onDialogUXStateChanged:state=THINKING
###########################

Thinking...

###########################

2018-06-10 05:48:08.557 [ 10] 0 CapabilityAgent:removingMessageIdFromMap:messageId=d3417cbf-ecf9-41d9-a6e3-4f23f87de58c
2018-06-10 05:48:08.562 [ a] 0 InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-06-10 05:48:08.994 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 02230afffe17cedc-00007d7c-000e2138-d57f983f3517dbb1-270e57a1-5
2018-06-10 05:48:09.047 [ 14] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"7f4c249a-d83f-4747-8b03-a2c14e63f895",dialogRequestId:"a645d473-60ec-40ab-86f9-7c6d8c5d4b73"}
2018-06-10 05:48:09.052 [ 14] I DirectiveSequencer:onDirective:directive={"namespace:"TemplateRuntime",name:"RenderTemplate",messageId:"b7916856-2895-4d1f-b233-df3dfe3f0824",dialogRequestId:"a645d473-60ec-40ab-86f9-7c6d8c5d4b73"}
2018-06-10 05:48:09.052 [ 15] I DirectiveRouter:preHandleDirective:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895,action=calling
2018-06-10 05:48:09.053 [ 15] 0 CapabilityAgent:addingMessageIdToMap:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:09.053 [ 15] 9 SpeechSynthesizer:preHandleDirective:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:09.053 [ 15] I DirectiveRouter:handleDirectiveWithPolicyHandleImmediately:messageId=b7916856-2895-4d1f-b233-df3dfe3f0824,action=calling
2018-06-10 05:48:09.059 [ 11] 0 SpeechSynthesizer:executePreHandle:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:09.061 [ 15] 5 TemplateRuntime:handleDirectiveImmediately
2018-06-10 05:48:09.061 [ 15] 5 TemplateRuntime:preHandleDirective
2018-06-10 05:48:09.061 [ 15] 5 TemplateRuntime:handleRenderTemplateDirective
2018-06-10 05:48:09.063 [ 8] 5 TemplateRuntime:handleRenderTemplateDirectiveInExecutor
2018-06-10 05:48:09.064 [ 8] 1 FocusManager:acquireChannel:channelName=Visual,interface=TemplateRuntime
2018-06-10 05:48:09.064 [ 8] 3 TemplateRuntime:executeDisplayCardEvent:prevState=IDLE,nextState=ACQUIRING
2018-06-10 05:48:09.064 [ 8] 5 TemplateRuntime:executeOnFocusChangedEvent:prevFocus=NONE,newFocus=FOREGROUND
2018-06-10 05:48:09.064 [ 8] 3 TemplateRuntime:executeStopTimer
2018-06-10 05:48:09.064 [ 8] 3 TemplateRuntime:executeRenderTemplateCallbacks:isClear=False
##############################################################################

RenderTemplateCard

#-----------------------------------------------------------------------------

Focus State : FOREGROUND

Template Type : WeatherTemplate

Main Title : Brooklyn, NY

##############################################################################

2018-06-10 05:48:09.064 [ 17] 5 VisualActivityTracker:notifyOfActivityUpdates
2018-06-10 05:48:09.070 [ 16] I DirectiveRouter:handleDirective:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895,action=calling
2018-06-10 05:48:09.072 [ 16] 9 SpeechSynthesizer:handleDirective:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:09.073 [ 11] 0 SpeechSynthesizer:executeHandle:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:09.073 [ 8] 3 TemplateRuntime:executeOnFocusChangedEvent:prevState=ACQUIRING,nextState=DISPLAYING
2018-06-10 05:48:09.075 [ 11] 1 FocusManager:acquireChannel:channelName=Dialog,interface=SpeechSynthesizer
2018-06-10 05:48:09.079 [ 13] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-06-10 05:48:09.080 [ 13] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-06-10 05:48:09.087 [ 13] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-06-10 05:48:09.087 [ 11] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-06-10 05:48:09.087 [ 11] 9 SpeechSynthesizer:startPlaying
2018-06-10 05:48:09.088 [ 11] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-06-10 05:48:09.088 [ 10] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-06-10 05:48:09.088 [ 10] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-06-10 05:48:09.088 [ 4] 0 MediaPlayer:handleSetSourceCalled
2018-06-10 05:48:09.088 [ 4] 9 MediaPlayer:tearDownTransientPipelineElements
2018-06-10 05:48:09.088 [ 10] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-06-10 05:48:09.089 [ 4] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-06-10 05:48:09.100 [ 4] 9 BaseStreamSource:initNoAudioFormat
2018-06-10 05:48:09.145 [ 11] 9 MediaPlayer:playCalled
2018-06-10 05:48:09.149 [ 4] 0 MediaPlayer:handlePlayCalled:idPassed=1,currentId=1
2018-06-10 05:48:09.152 [ 4] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-06-10 05:48:09.153 [ 4] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-06-10 05:48:09.153 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-06-10 05:48:09.153 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-06-10 05:48:09.153 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-06-10 05:48:09.153 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-06-10 05:48:09.154 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-06-10 05:48:09.155 [ 4] 9 MediaPlayer:messageReceived:type=stream-status,source=src
2018-06-10 05:48:09.156 [ 18] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-06-10 05:48:09.156 [ 4] 9 BaseStreamSource:handleNeedDataCalled
2018-06-10 05:48:09.156 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=19
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.157 [ 4] 9 AttachmentReaderSource:read:size=720,status=0
2018-06-10 05:48:09.158 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.158 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=19
2018-06-10 05:48:09.158 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=20,sourceRetryCount=1
2018-06-10 05:48:09.158 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.158 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=20
2018-06-10 05:48:09.158 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=21,sourceRetryCount=2
2018-06-10 05:48:09.168 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.169 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=21
2018-06-10 05:48:09.169 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=22,sourceRetryCount=3
2018-06-10 05:48:09.179 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.179 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=22
2018-06-10 05:48:09.179 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=23,sourceRetryCount=4
2018-06-10 05:48:09.190 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.190 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=23
2018-06-10 05:48:09.190 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=24,sourceRetryCount=5
2018-06-10 05:48:09.201 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-06-10 05:48:09.202 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-06-10 05:48:09.210 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.211 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=24
2018-06-10 05:48:09.211 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=25,sourceRetryCount=6
2018-06-10 05:48:09.212 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-06-10 05:48:09.214 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-06-10 05:48:09.231 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.232 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=removeSourceId,sourceId=25
2018-06-10 05:48:09.232 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=26
2018-06-10 05:48:09.232 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.232 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.232 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.232 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.232 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=26
2018-06-10 05:48:09.232 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=27,sourceRetryCount=1
2018-06-10 05:48:09.232 [ 4] 9 AttachmentReaderSource:read:size=0,status=1
2018-06-10 05:48:09.232 [ 4] 9 BaseStreamSource:updateOnReadDataHandler:action=removeSourceId,sourceId=27
2018-06-10 05:48:09.233 [ 4] 9 BaseStreamSource:updateOnReadDataHandlerNewSourceId:action=newSourceId,sourceId=28,sourceRetryCount=2
2018-06-10 05:48:09.248 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.248 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=removeSourceId,sourceId=28
2018-06-10 05:48:09.249 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=29
2018-06-10 05:48:09.250 [ a] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-06-10 05:48:09.250 [ a] 0 HTTP2Transport:cleanupFinishedStream:streamId=5,result=200
2018-06-10 05:48:09.251 [ a] 0 HTTP2StreamPool:releaseStream:streamId=5,numAcquiredStreams=1
2018-06-10 05:48:09.250 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.253 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=4096,status=0
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=749,status=0
2018-06-10 05:48:09.254 [ 4] 0 InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:read:size=0,status=3
2018-06-10 05:48:09.254 [ 4] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-06-10 05:48:09.255 [ 4] 9 BaseStreamSource:signalEndOfDataCalled
2018-06-10 05:48:09.255 [ 4] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-06-10 05:48:09.255 [ 4] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=29
2018-06-10 05:48:09.704 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-0
2018-06-10 05:48:09.704 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-0
2018-06-10 05:48:09.711 [ 18] 9 MediaPlayer:onPadAddedCalled
2018-06-10 05:48:09.712 [ 4] 9 MediaPlayer:handlePadAddedSignalCalled
2018-06-10 05:48:09.720 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-06-10 05:48:09.720 [ 4] 9 MediaPlayer:messageReceived:type=stream-start,source=audio-pipeline
2018-06-10 05:48:09.726 [ 4] 9 MediaPlayer:messageReceived:type=latency,source=audio_sink
2018-06-10 05:48:09.726 [ 4] 9 MediaPlayer:messageReceived:type=stream-status,source=audiosinkringbuffer0
2018-06-10 05:48:09.726 [ 4] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink
2018-06-10 05:48:09.726 [ 4] 0 MediaPlayer:callingOnTags
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink
2018-06-10 05:48:09.727 [ 4] 0 MediaPlayer:callingOnTags
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:messageReceived:type=async-done,source=audio-pipeline
2018-06-10 05:48:09.727 [ 4] 9 MediaPlayer:messageReceived:type=new-clock,source=audio-pipeline
2018-06-10 05:48:09.728 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio_sink
2018-06-10 05:48:09.729 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=volume
2018-06-10 05:48:09.729 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=converter
2018-06-10 05:48:09.729 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=avdec_mp3-0
2018-06-10 05:48:09.729 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=mpegaudioparse0
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=id3demux0
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=typefind
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=decoder
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=src
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:messageReceived:type=state-changed,source=audio-pipeline
2018-06-10 05:48:09.730 [ 4] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-06-10 05:48:09.730 [ 4] 0 MediaPlayer:callingOnPlaybackStarted:currentId=1
2018-06-10 05:48:09.730 [ 4] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=1
2018-06-10 05:48:09.731 [ 11] 0 SpeechSynthesizer:executePlaybackStarted
2018-06-10 05:48:09.731 [ 11] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-06-10 05:48:09.731 [ 11] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-06-10 05:48:09.731 [ 11] 9 MediaPlayer:getOffsetCalled
2018-06-10 05:48:09.739 [ 4] 9 MediaPlayer:messageReceived:type=tag,source=audio_sink
2018-06-10 05:48:09.739 [ 4] 0 MediaPlayer:callingOnTags
2018-06-10 05:48:09.739 [ 4] 0 MediaPlayer:handleGetOffsetCalled:idPassed=1,currentId=1
2018-06-10 05:48:09.740 [ 11] 9 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-06-10 05:48:09.740 [ 7] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-06-10 05:48:09.740 [ 13] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-06-10 05:48:09.740 [ 7] 5 TemplateRuntime:onDialogUXStateChanged:state=SPEAKING
###########################

Speaking...

###########################

2018-06-10 05:48:09.741 [ 13] 5 AudioActivityTracker:notifyOfActivityUpdates
2018-06-10 05:48:09.741 [ f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
2018-06-10 05:48:09.741 [ 11] 0 EventBuilder:buildJsonEventString:messageId=2b2247e9-8d5a-4b31-821e-ffa00ecca635,namespace=SpeechSynthesizer,name=SpeechStarted
2018-06-10 05:48:09.742 [ 11] 9 HTTP2Transport:enqueueRequest
2018-06-10 05:48:09.754 [ a] 0 HTTP2Transport:processNextOutgoingMessage
2018-06-10 05:48:09.754 [ a] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2018-06-10 05:48:09.755 [ a] 9 HTTP2Transport:insertActiveStream:handle=0x714e0ec0
2018-06-10 05:48:10.003 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 02230afffe17cedc-00007d7c-000df77b-97b12b715c5ab04b-a1329815-9
2018-06-10 05:48:10.003 [ a] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2018-06-10 05:48:10.003 [ a] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2018-06-10 05:48:10.003 [ a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-06-10 05:48:20.538 [ 18] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-06-10 05:48:20.538 [ 4] 9 BaseStreamSource:handleNeedDataCalled
2018-06-10 05:48:20.539 [ 4] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=33
2018-06-10 05:48:20.539 [ 4] 0 InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-06-10 05:48:20.539 [ 4] 9 AttachmentReaderSource:read:size=0,status=3
2018-06-10 05:48:20.539 [ 4] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-06-10 05:48:20.539 [ 4] 9 BaseStreamSource:signalEndOfDataCalled
2018-06-10 05:48:20.539 [ 4] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-06-10 05:48:20.539 [ 4] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=33
2018-06-10 05:48:20.899 [ 4] 9 MediaPlayer:messageReceived:type=eos,source=audio-pipeline
2018-06-10 05:48:20.933 [ 4] 9 BaseStreamSource:~BaseStreamSource
2018-06-10 05:48:20.934 [ 4] 0 MediaPlayer:callingOnPlaybackFinished:currentId=1
2018-06-10 05:48:20.934 [ 4] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=1
2018-06-10 05:48:20.934 [ 11] 0 SpeechSynthesizer:executePlaybackFinished
2018-06-10 05:48:20.934 [ 11] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-06-10 05:48:20.934 [ 11] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-06-10 05:48:20.934 [ 11] 9 ContextManager:updateStateLocked:action=updatedState,namespace=SpeechSynthesizer,name=SpeechState
2018-06-10 05:48:20.934 [ 4] 9 MediaPlayer:tearDownTransientPipelineElements
2018-06-10 05:48:20.934 [ 11] 0 EventBuilder:buildJsonEventString:messageId=ed01e585-7b2a-4b86-998f-0dde4813ce84,namespace=SpeechSynthesizer,name=SpeechFinished
2018-06-10 05:48:20.934 [ 4] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-06-10 05:48:20.935 [ 11] 9 HTTP2Transport:enqueueRequest
2018-06-10 05:48:20.935 [ 11] 9 SpeechSynthesizer:setHandlingCompleted
2018-06-10 05:48:20.935 [ 11] 0 DirectiveProcessor:onHandlingCompeted:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895,directiveBeingPreHandled=(nullptr)
2018-06-10 05:48:20.935 [ 11] 0 CapabilityAgent:removingMessageIdFromMap:messageId=7f4c249a-d83f-4747-8b03-a2c14e63f895
2018-06-10 05:48:21.030 [ a] 0 HTTP2Transport:processNextOutgoingMessage
2018-06-10 05:48:21.030 [ a] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2018-06-10 05:48:21.030 [ a] 9 HTTP2Transport:insertActiveStream:handle=0x714e0ec0
2018-06-10 05:48:21.135 [ 7] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
2018-06-10 05:48:21.135 [ 7] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
########################################

Alexa is currently idle!

########################################

2018-06-10 05:48:21.135 [ 11] 1 FocusManager:releaseChannel:channelName=Dialog
2018-06-10 05:48:21.136 [ 8] 3 TemplateRuntime:executeStartTimer:timeoutInMilliseconds=2000
2018-06-10 05:48:21.136 [ 13] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-06-10 05:48:21.136 [ 13] 5 AudioActivityTracker:notifyOfActivityUpdates
2018-06-10 05:48:21.137 [ f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
2018-06-10 05:48:21.367 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 02230afffe17cedc-00007d7c-000e2132-8377f11cb517c7ba-8b588fb1-9
2018-06-10 05:48:21.367 [ a] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204
2018-06-10 05:48:21.367 [ a] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
2018-06-10 05:48:21.367 [ a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-06-10 05:48:23.137 [ 8] 3 TemplateRuntime:executeRenderTemplateCallbacks:isClear=True
##############################################################################

RenderTemplateCard - Cleared

##############################################################################

2018-06-10 05:48:23.138 [ 8] 1 FocusManager:releaseChannel:channelName=Visual
2018-06-10 05:48:23.138 [ 8] 3 TemplateRuntime:executeTimerEvent:prevState=DISPLAYING,nextState=RELEASING
2018-06-10 05:48:23.138 [ 17] 5 VisualActivityTracker:notifyOfActivityUpdates
2018-06-10 05:48:23.139 [ 8] 5 TemplateRuntime:executeOnFocusChangedEvent:prevFocus=FOREGROUND,newFocus=NONE
2018-06-10 05:48:23.139 [ 8] 3 TemplateRuntime:executeOnFocusChangedEvent:prevState=RELEASING,nextState=IDLE

What is the expected behavior?

I should be hearing Alexa response to when I say "What is the weather?"

What behavior are you observing?

Everything seems to work perfectly fine except that I do not hear Alexa

Provide the steps to reproduce the issue, if applicable:

Once I type sudo bash startsample.sh
and then I type t to speak I dont hear a response

Tell us about your environment:

What version of the AVS Device SDK are you using?

  <1.7.1>

Tell us what hardware you're using:

  • Desktop / Laptop
  • [* ] Raspberry Pi
  • Other - tell us more:

Tell us about your OS (Type & version):

  • Linux
  • MacOS
  • [* ] Raspbian Stretch
  • Raspbian Jessy
  • Other - tell us more:
@scotthea-amazon
Copy link
Contributor

Hello jairoMolina9,

This can be caused by many things and there are several threads covering this kind of problem. I suggest looking in to the following:

@kclchan
Copy link
Contributor

kclchan commented Jul 9, 2018

Closing due to inactivity. Please reopen if you have further questions.

@kclchan kclchan closed this as completed Jul 9, 2018
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
@scotthea-amazon @kclchan @jairoMolina9