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

Playback issue with Raspberry Pi Stretch 2017-11-29 #427

Closed
2 of 8 tasks
sdumorti opened this issue Jan 4, 2018 · 15 comments
Closed
2 of 8 tasks

Playback issue with Raspberry Pi Stretch 2017-11-29 #427

sdumorti opened this issue Jan 4, 2018 · 15 comments

Comments

@sdumorti
Copy link

sdumorti commented Jan 4, 2018 •
edited
Loading

Briefly summarize your issue:

Raspberry Pi reference 2017-11-29:
I have no audio playback, the Alexa keyword is detected, I ask my question but when it says "Speaking" nothing comes out to the speaker.
Note: I can successfully play a sound using "aplay"

Raspberry Pi reference 2017-09-07:
Same SDK, same hardware, same ALSA config as above but it works fine.

It seems that something related to the MediaPlayer is broken with the latest Raspbian image

What is the expected behavior?

I would expect the same behavior in "Raspberry Pi reference 2017-11-29" as in "Raspberry Pi reference 2017-09-07". Both are Raspbian Stretch

What behavior are you observing?

No audio playback within the Alexa Sample App with "Raspberry Pi reference 2017-11-29"

Provide the steps to reproduce the issue, if applicable:

Follow the instructions provided in this wiki to install the Alexa sample app on a Raspberry Pi

Tell us about your environment:

cat /etc/os-release
PRETTY_NAME="Raspbian GNU/Linux 9 (stretch)"
NAME="Raspbian GNU/Linux"
VERSION_ID="9"
VERSION="9 (stretch)"
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

uname -a
Linux Microsemi-AVS 4.9.59-v7+ #1047 SMP Sun Oct 29 12:19:23 GMT 2017 armv7l GNU/Linux

cat /etc/rpi-issue
Raspberry Pi reference 2017-11-29
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, b71bf3179310aadf9aaf2ffcdf094a88e488dce8, stage5

What version of the AVS Device SDK are you using?

  <1.3.0> and <1.2.1> both have the issue

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:
@sdumorti
Copy link
Author

sdumorti commented Jan 4, 2018

I recompiled with the debugs enabled on both Stretch version (2017-09-07 "working" and 2017-11-29 "not working") and compared the logs, but nothing obvious.

Here's below the log for "Raspberry Pi reference 2017-11-29" where I said "Alexa, what time is it" and it didn't playback anything

Thank you

Running app with log level: DEBUG9
2018-01-04 18:49:37.072 [  1] I ConfigurationNode:initializeSuccess
2018-01-04 18:49:37.135 [  1] 9 MediaPlayer:createCalled
2018-01-04 18:49:37.441 [  1] 9 MediaPlayer:createCalled
2018-01-04 18:49:37.447 [  1] 9 MediaPlayer:createCalled
2018-01-04 18:49:37.501 [  1] 9 MediaPlayer:setObserverCalled
2018-01-04 18:49:37.501 [  2] 0 MediaPlayer:handleSetObserverCalled
2018-01-04 18:49:37.502 [  1] 9 SpeechSynthesizer:addObserver:observer=0x1d1057c
2018-01-04 18:49:37.507 [  1] 9 MediaPlayer:setObserverCalled
2018-01-04 18:49:37.507 [  2] 0 MediaPlayer:handleSetObserverCalled
2018-01-04 18:49:37.508 [  1] 9 MediaPlayer:setObserverCalled
2018-01-04 18:49:37.509 [  2] 0 MediaPlayer:handleSetObserverCalled
2018-01-04 18:49:37.529 [  1] 9 AlertScheduler:setTimerForNextAlertLocked
2018-01-04 18:49:37.529 [  1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2018-01-04 18:49:37.530 [  3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2018-01-04 18:49:37.537 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-01-04 18:49:37.537 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-01-04 18:49:37.537 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-01-04 18:49:37.538 [  1] 0 SpeakerManager:mapCreated:numAvsSynced=2,numLocal=1
2018-01-04 18:49:37.538 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-01-04 18:49:37.538 [  2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-01-04 18:49:37.538 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-01-04 18:49:37.538 [  2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-01-04 18:49:37.539 [  1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-01-04 18:49:37.539 [  1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2018-01-04 18:49:37.539 [  1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-01-04 18:49:37.543 [  1] 1 AudioPlayer:addObserver
2018-01-04 18:49:37.545 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x1d16cf8,policy=BLOCKING
2018-01-04 18:49:37.545 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x1d174d8,policy=NON_BLOCKING
2018-01-04 18:49:37.545 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x1d174d8,policy=NON_BLOCKING
2018-01-04 18:49:37.545 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x1d174d8,policy=NON_BLOCKING
2018-01-04 18:49:37.546 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x1d163b8,policy=NON_BLOCKING
2018-01-04 18:49:37.546 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x1d163b8,policy=NON_BLOCKING
2018-01-04 18:49:37.546 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x1d181d8,policy=NON_BLOCKING
2018-01-04 18:49:37.546 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x1d181d8,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x1d21fe8,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x1d16174,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x1d20970,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x1d20970,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x1d20970,policy=NON_BLOCKING
2018-01-04 18:49:37.547 [  1] 9 TemplateRuntime:getConfiguration
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x1d20d20,policy=HANDLE_IMMEDIATELY
2018-01-04 18:49:37.547 [  1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x1d20d20,policy=HANDLE_IMMEDIATELY
2018-01-04 18:49:38.014 [  4] 0 HttpPost:doPostSucceeded:code=200
2018-01-04 18:49:38.016 [  4] 0 AuthDelegate:handleLwaResponseSucceeded:expiresInSeconds=3600
2018-01-04 18:49:38.016 [  4] 0 AuthDelegate:onAuthStateChangeCalled:state=1,error=0
2018-01-04 18:49:38.016 [  1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2018-01-04 18:49:38.020 [  1] I HTTP2Transport:setupDownchannelStream:url=https\://avs-alexa-na.amazon.com/v20160207/directives
2018-01-04 18:49:38.021 [  1] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1
2018-01-04 18:49:38.023 [  5] 9 PostConnectSynchronize:Entering postConnectLoop thread
2018-01-04 18:49:38.025 [  3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
2018-01-04 18:49:38.025 [  6] 9 SpeechSynthesizer:provideState:token=1
2018-01-04 18:49:38.026 [  6] 0 AudioPlayer:provideState:stateRequestToken=1
2018-01-04 18:49:38.026 [  7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1
2018-01-04 18:49:38.027 [  8] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" \: ""},namespace=SpeechRecognizer,name=RecognizerState
#############################
#       Connecting...       #
#############################

2018-01-04 18:49:38.029 [  9] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2018-01-04 18:49:38.029 [  9] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"},namespace=AudioPlayer,name=PlaybackState
2018-01-04 18:49:38.030 [  7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2018-01-04 18:49:38.032 [  6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]}
2018-01-04 18:49:38.034 [  6] 0 EventBuilder:buildJsonEventString:messageId=4f35d3f5-7889-41bf-af9b-910766609168,namespace=System,name=SynchronizeState
2018-01-04 18:49:38.036 [  6] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport
2018-01-04 18:49:38.036 [  6] 9 HTTP2Transport:enqueueRequest
2018-01-04 18:49:38.520 [  a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 06898dfffe3bf104-00004d4e-000cba3f-c35d32d25c078ab4-a6a47d41-1
2018-01-04 18:49:38.522 [  a] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-04 18:49:38.523 [  a] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2
2018-01-04 18:49:38.524 [  a] 9 HTTP2Transport:insertActiveStream:handle=0x72cd4fe8
2018-01-04 18:49:39.688 [  a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 06898dfffe3bf104-00004d4e-000cba3f-c35d32d25c078ab4-a6a47d41-3
2018-01-04 18:49:39.690 [  a] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-01-04 18:49:39.690 [  5] 9 PostConnectSynchronize:Exiting postConnectLoop thread
2018-01-04 18:49:39.691 [  a] 0 PostConnectSynchronize:onConnected()
2018-01-04 18:49:39.692 [  a] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
########################################
#       Alexa is currently idle!       #
########################################

2018-01-04 18:49:39.693 [  3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2018-01-04 18:49:39.693 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2018-01-04 18:49:39.694 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1d07320
2018-01-04 18:49:39.693 [  a] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=204
2018-01-04 18:49:39.694 [  1] 9 TemplateRuntime:addObserver
2018-01-04 18:49:39.695 [  a] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
2018-01-04 18:49:39.695 [  b] 0 TemplateRuntime:addObserverInExecutor
2018-01-04 18:49:39.697 [  a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.front
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.rear
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.center_lfe
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.side
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround40
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround41
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround50
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround51
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround71
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
ALSA lib pcm_dmix.c:990:(snd_pcm_dmix_open) The dmix plugin supports only playback stream
ALSA lib pcm_dsnoop.c:556:(snd_pcm_dsnoop_open) The dsnoop plugin supports only capture stream
2018-01-04 18:49:39.822 [  1] I SensoryKeywordDetector:License expires on 28 Mar 2018 00:00:00 GMT
2018-01-04 18:49:39.823 [  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.               |
| 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.  |
| 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-01-04 18:49:52.330 [  8] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2018-01-04 18:49:52.330 [  8] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" \: "alexa"},namespace=SpeechRecognizer,name=RecognizerState
2018-01-04 18:49:52.330 [  c] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2018-01-04 18:49:52.332 [  6] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]}
############################
#       Listening...       #
############################

2018-01-04 18:49:52.333 [  8] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}]}
2018-01-04 18:49:52.334 [  8] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-01-04 18:49:52.335 [  d] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-01-04 18:49:52.336 [  8] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=6f2f91c2-e34f-4b1b-a708-133e9270da83
2018-01-04 18:49:52.336 [  8] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2018-01-04 18:49:52.337 [  8] 0 EventBuilder:buildJsonEventString:messageId=8b91823e-ad93-4e00-9a2e-51a38e325d39,namespace=SpeechRecognizer,name=Recognize
2018-01-04 18:49:52.339 [  8] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2018-01-04 18:49:52.339 [  8] 9 HTTP2Transport:enqueueRequest
2018-01-04 18:49:52.435 [  a] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-04 18:49:52.435 [  a] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=2
2018-01-04 18:49:52.436 [  a] 9 HTTP2Transport:insertActiveStream:handle=0x72cd4fe8
2018-01-04 18:49:56.073 [  e] 0 MessageInterpreter:receive:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c:No dialogRequestId attached to message.
2018-01-04 18:49:56.075 [  e] I DirectiveSequencer:onDirective:directive={"namespace\:"SpeechRecognizer"\,name\:"StopCapture"\,messageId\:"a64bdf1c-857d-4f86-b790-9dc1b5c2045c"\,dialogRequestId\:""}
2018-01-04 18:49:56.076 [  f] I DirectiveRouter:preHandleDirective:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c,action=calling
2018-01-04 18:49:56.077 [  f] 0 CapabilityAgent:addingMessageIdToMap:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c
2018-01-04 18:49:56.079 [ 10] I DirectiveRouter:handleDirective:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c,action=calling
2018-01-04 18:49:56.080 [  8] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-01-04 18:49:56.080 [  8] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2018-01-04 18:49:56.080 [  c] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2018-01-04 18:49:56.080 [  8] 0 DirectiveProcessor:onHandlingCompeted:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c,directiveBeingPreHandled=(nullptr)
###########################
#       Thinking...       #
###########################

2018-01-04 18:49:56.081 [  8] 0 CapabilityAgent:removingMessageIdFromMap:messageId=a64bdf1c-857d-4f86-b790-9dc1b5c2045c
2018-01-04 18:49:56.085 [  a] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-04 18:49:56.447 [  a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 06898dfffe3bf104-00004d4e-000cba3f-c35d32d25c078ab4-a6a47d41-5
2018-01-04 18:49:56.507 [  e] I DirectiveSequencer:onDirective:directive={"namespace\:"SpeechSynthesizer"\,name\:"Speak"\,messageId\:"e6fda604-4cdb-455e-bf7b-7a82f4b2d856"\,dialogRequestId\:"6f2f91c2-e34f-4b1b-a708-133e9270da83"}
2018-01-04 18:49:56.508 [  f] I DirectiveRouter:preHandleDirective:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856,action=calling
2018-01-04 18:49:56.509 [  f] 0 CapabilityAgent:addingMessageIdToMap:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:49:56.509 [  f] 9 SpeechSynthesizer:preHandleDirective:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:49:56.509 [  7] 0 SpeechSynthesizer:executePreHandle:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:49:56.511 [ 10] I DirectiveRouter:handleDirective:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856,action=calling
2018-01-04 18:49:56.511 [ 10] 9 SpeechSynthesizer:handleDirective:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:49:56.513 [  a] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-01-04 18:49:56.514 [  a] 0 HTTP2Transport:cleanupFinishedStream:streamId=5,result=200
2018-01-04 18:49:56.514 [  a] 0 HTTP2StreamPool:releaseStream:streamId=5,numAcquiredStreams=1
2018-01-04 18:49:56.515 [  7] 0 SpeechSynthesizer:executeHandle:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:49:56.515 [  7] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-01-04 18:49:56.515 [  d] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-01-04 18:49:56.516 [  d] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-01-04 18:49:56.516 [  8] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-01-04 18:49:56.516 [  d] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-01-04 18:49:56.516 [  8] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-01-04 18:49:56.516 [  8] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-01-04 18:49:56.516 [  7] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-01-04 18:49:56.517 [  7] 9 SpeechSynthesizer:startPlaying
2018-01-04 18:49:56.517 [  7] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-01-04 18:49:56.517 [  2] 0 MediaPlayer:handleSetSourceCalled
2018-01-04 18:49:56.517 [  2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-04 18:49:56.518 [  2] E MediaPlayer:saveOffsetBeforeTeardown - gst_element_query_position failed
2018-01-04 18:49:56.598 [  7] 9 MediaPlayer:playCalled
2018-01-04 18:49:56.599 [  2] 0 MediaPlayer:handlePlayCalled:idPassed=1,currentId=1
2018-01-04 18:49:56.599 [  2] 0 MediaPlayer:handlePlay:attemptBuffering=0
2018-01-04 18:49:56.763 [  2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-01-04 18:49:56.763 [  2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [ 11] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.764 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-04 18:49:56.765 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.766 [  2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-04 18:49:56.766 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.766 [  2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-04 18:49:56.766 [  2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-04 18:49:56.766 [  2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=12
2018-01-04 18:49:56.766 [  2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-04 18:49:56.767 [  2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-04 18:49:56.767 [  2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-04 18:49:56.767 [  2] 9 AttachmentReaderSource:read:size=285,status=0
2018-01-04 18:49:56.767 [  2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-04 18:49:56.767 [  2] 9 AttachmentReaderSource:read:size=0,status=3
2018-01-04 18:49:56.767 [  2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-01-04 18:49:56.768 [  2] 9 BaseStreamSource:signalEndOfDataCalled
2018-01-04 18:49:56.768 [  2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-01-04 18:49:56.768 [  2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=12
2018-01-04 18:49:56.796 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.797 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.816 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:56.817 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:58.947 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:58.948 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.025 [ 11] 9 MediaPlayer:onPadAddedCalled
2018-01-04 18:49:59.025 [  2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-01-04 18:49:59.028 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.032 [  2] 9 MediaPlayer:messageReceived:messageType=stream-start
2018-01-04 18:49:59.047 [  2] 9 MediaPlayer:messageReceived:messageType=latency
2018-01-04 18:49:59.047 [  2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-04 18:49:59.061 [  2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-04 18:49:59.062 [  2] 0 MediaPlayer:callingOnTags
2018-01-04 18:49:59.063 [  2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-04 18:49:59.063 [  2] 0 MediaPlayer:callingOnTags
2018-01-04 18:49:59.063 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.063 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=async-done
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=new-clock
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.064 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.065 [  2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-04 18:49:59.066 [  2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-01-04 18:49:59.066 [  2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=1
2018-01-04 18:49:59.066 [  2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=1
2018-01-04 18:49:59.066 [  7] 0 SpeechSynthesizer:executePlaybackStarted
2018-01-04 18:49:59.066 [  7] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-01-04 18:49:59.066 [  7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-04 18:49:59.066 [  7] 9 MediaPlayer:getOffsetCalled
2018-01-04 18:49:59.067 [  2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-04 18:49:59.067 [  2] 0 MediaPlayer:callingOnTags
2018-01-04 18:49:59.067 [  2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=1,currentId=1
2018-01-04 18:49:59.069 [  7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_979b36f2-8a91-49fb-af74-eb87cbdc8664"\,"offsetInMilliseconds"\:408\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState
2018-01-04 18:49:59.069 [  c] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-01-04 18:49:59.069 [  7] 0 EventBuilder:buildJsonEventString:messageId=6c03d345-ca8c-4066-96ea-0a510af7078e,namespace=SpeechSynthesizer,name=SpeechStarted
2018-01-04 18:49:59.070 [  7] 9 HTTP2Transport:enqueueRequest
2018-01-04 18:49:59.070 [  d] 9 SpeechSynthesizer:onFocusChangedSuccess
###########################
#       Speaking...       #
###########################

2018-01-04 18:49:59.122 [  a] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-04 18:49:59.123 [  a] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2018-01-04 18:49:59.123 [  a] 9 HTTP2Transport:insertActiveStream:handle=0x72cd4fe8
2018-01-04 18:49:59.284 [  a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 06898dfffe3bf104-00004d4e-000cba3f-c35d32d25c078ab4-a6a47d41-7
2018-01-04 18:49:59.284 [  a] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2018-01-04 18:49:59.284 [  a] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2018-01-04 18:49:59.284 [  a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-04 18:49:59.376 [ 11] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-04 18:49:59.377 [  2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-04 18:50:00.407 [  2] 9 MediaPlayer:messageReceived:messageType=eos
2018-01-04 18:50:00.707 [  2] 9 BaseStreamSource:~BaseStreamSource
2018-01-04 18:50:00.708 [  2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=1
2018-01-04 18:50:00.708 [  2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=1
2018-01-04 18:50:00.708 [  7] 0 SpeechSynthesizer:executePlaybackFinished
2018-01-04 18:50:00.708 [  7] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-01-04 18:50:00.708 [  7] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-04 18:50:00.709 [  7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:NotificationsV4#ACRI#DeviceTTSRendererV4_979b36f2-8a91-49fb-af74-eb87cbdc8664"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2018-01-04 18:50:00.709 [  7] 0 EventBuilder:buildJsonEventString:messageId=de063b03-ecf2-4701-9c9a-c8357c815313,namespace=SpeechSynthesizer,name=SpeechFinished
2018-01-04 18:50:00.710 [  7] 9 HTTP2Transport:enqueueRequest
2018-01-04 18:50:00.710 [  7] 9 SpeechSynthesizer:setHandlingCompleted
2018-01-04 18:50:00.710 [  7] 0 DirectiveProcessor:onHandlingCompeted:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856,directiveBeingPreHandled=(nullptr)
2018-01-04 18:50:00.710 [  7] 0 CapabilityAgent:removingMessageIdFromMap:messageId=e6fda604-4cdb-455e-bf7b-7a82f4b2d856
2018-01-04 18:50:00.711 [  2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-04 18:50:00.711 [  2] 0 MediaPlayer:saveOffsetBeforeTeardown:offset=2088
2018-01-04 18:50:00.789 [  a] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-04 18:50:00.789 [  a] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2018-01-04 18:50:00.790 [  a] 9 HTTP2Transport:insertActiveStream:handle=0x72cd4fe8
2018-01-04 18:50:00.910 [  c] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
2018-01-04 18:50:00.910 [  7] 1 FocusManager:releaseChannel:channelName=Dialog
########################################
#       Alexa is currently idle!       #
########################################

2018-01-04 18:50:00.911 [  d] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-01-04 18:50:00.996 [  a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 06898dfffe3bf104-00004d4e-000cba3f-c35d32d25c078ab4-a6a47d41-9
2018-01-04 18:50:00.996 [  a] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204
2018-01-04 18:50:00.996 [  a] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
2018-01-04 18:50:00.997 [  a] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

@kclchan
Copy link
Contributor

kclchan commented Jan 4, 2018

Hi @sdumorti, the MediaPlayer uses a autoaudiosink element in the pipeline for audio playback. Would you be able to run some basic functionality test with the below command and see if you could hear a constant tone?

gst-launch-1.0 audiotestsrc ! autoaudiosink

Also, I found that #212 contains some very useful suggestions. For example, some users were able to get audio working after installing the gstreamer1.0-alsa package:
sudo apt install gstreamer1.0-alsa

@sdumorti
Copy link
Author

sdumorti commented Jan 5, 2018

Hi @kclchan,

Thanks for your help.
"gst-launch-1.0 audiotestsrc ! autoaudiosink" doesn't generate a tone with "Raspbian 2017-11-29" but it does with "Raspbian 2017-09-07"

For the fix mentioned at #212, I already had gstreamer1.0-alsa installed, so it doesn't fix my issue.

As a debug test, I did the following:

  • Disconnected my audio card from the Pi
  • Burned a fresh new "Raspbian 2017-11-29"
  • Used "raspi-config" to set the 3.5mm Pi jack as default
  • Installed GStreamer
    sudo apt-get install libgstreamer1.0-0 gstreamer1.0-plugins-base gstreamer1.0-plugins-good gstreamer1.0-plugins-bad gstreamer1.0-plugins-ugly gstreamer1.0-libav gstreamer1.0-doc gstreamer1.0-tools
  • Tried the debug command with headphones connected to the Raspberry Pi 3.5mm jack
    gst-launch-1.0 audiotestsrc ! autoaudiosink
  • I have no tone, it doesn't work

I then tried "aplay":

  • Downloaded a wav file
    wget https://images-na.ssl-images-amazon.com/images/G/01/mobile-apps/dex/alexa/alexa-voice-service/docs/audio/states/med_system_alerts_melodic_01_short._TTH_.wav
  • Played the wav file
    aplay med_system_alerts_melodic_01_short._TTH_.wav
  • I can hear the sound, it works

It seems that there's an issue with GStreamer and the latest "Raspbian 2017-11-29" then.

@kclchan
Copy link
Contributor

kclchan commented Jan 5, 2018

Hi @sdumorti, maybe look into the .asoundrc configuration as well and check if sound is going through the HDMI instead.

@sdumorti
Copy link
Author

sdumorti commented Jan 8, 2018

Hi @kclchan,

"Raspbian 2017-11-29":
I could connect a sound enabled HDMI device and realized that "gst-launch-1.0 audiotestsrc ! autoaudiosink" was going through the HDMI even if it was forced to go to the 3.5mm jack. aplay goes through the 3.5mm jack though.

"Raspbian 2017-09-07":
"gst-launch-1.0 audiotestsrc ! autoaudiosink" and aplay are going through the 3.5mm jack when configured as the default.

There's still something wrong with "Raspbian 2017-11-29" and Gstreamer.

@BennyAvramson
Copy link
Contributor

Hi @sdumorti, Could you confirm that SDK SampleApp works through HDMI? Also, can you compare the .asoundrc files of the two Stretch versions?

@sdumorti
Copy link
Author

sdumorti commented Jan 8, 2018

Hi @BennyAvramson,

With "Raspbian 2017-11-29":
I confirm that the SampleApp outputs the audio to the HDMI. Audio in from my sound card, audio out to HDMI (instead of my defaulted sound card).

I diffed "/usr/share/alsa/alsa.conf" for both the "Raspbian 2017-09-07" and "Raspbian 2017-11-29" and they are identical.

Even with identical ".asoundrc" to override the default sound card, "Raspbian 2017-11-29" always routes the audio out to HDMI with GStreamer. "aplay" behaves as expected though and follows the default sound card.

@BennyAvramson
Copy link
Contributor

Hi @sdumorti, Unfortunately, debugging your ALSA .asoundrc file isn't something that can easily be done through Github issues. We simply can't help debugging .asoundrc issues beyond pointing out common problems that we've seen posted elsewhere. Sorry!

You may take a look on Gstreamer docs to understand how to configure the autoaudiosink or how to install and use other sinks.

@sdumorti
Copy link
Author

Hi @BennyAvramson,

The GStreamer issue appears even with a very generic scenario on a standalone Raspberry Pi (no third party sound card attached, no custom .asoundrc). I use "raspi-config" to set the Pi 3.5mm jack as default on two freshly burned official images.

"Raspbian 2017-11-29":
"gst-launch-1.0 audiotestsrc ! autoaudiosink" plays through HDMI

"Raspbian 2017-09-07":
"gst-launch-1.0 audiotestsrc ! autoaudiosink" plays through the 3.5mm jack

I'll look into the GStreamer docs

@XPlay1990
Copy link

XPlay1990 commented Jan 10, 2018 •
edited
Loading

Hey @sdumorti ,
i had the same issue at work today.

Starting the audio-test with -m option prints some additional information.
gst-launch-1.0 -m audiotestsrc ! autoaudiosink
In my case there was written something with "...omxhdmi", which i grep'ed for, and uncommented it in a config file.
After that gst-launch and alexa were working with 3.5mm jack.

@BennyAvramson
Copy link
Contributor

BennyAvramson commented Jan 10, 2018 •
edited
Loading

Hi @sdumorti, Could you check @XPlay1990 suggestion and see if it's working for you?

@sdumorti
Copy link
Author

Thanks @XPlay1990 for the hint on the "-m" switch.
I can see now that my issue has to do with the default audio sink, on the latest Raspbian "autoaudiosink" doesn't default on "alsasink". Hence the difference in behavior between "gst-launch-1.0 audiotestsrc ! autoaudiosink" and "aplay".
I now need to figure out how to change the defaults so "autoaudiosink" points to "alsasink"

@dhpp
Copy link
Contributor

dhpp commented Jan 16, 2018

Hi @sdumorti,

This is great to hear - let us know if you need any further assistance with the SDK.

@dhpp dhpp closed this as completed Jan 16, 2018
@mikejrh
Copy link

mikejrh commented Mar 3, 2018

I believe I am having the same issue with no audio out from Alexa using the 3.5mm jack.
@XPlay1990 what config file did you modify to get alexa working ? Thanks,

@sdumorti
Copy link
Author

sdumorti commented Mar 5, 2018

Hi @mikejrh
I haven't fixed it yet, I'm still using "Raspbian 2017-09-07"

@bandkenamazoncom bandkenamazoncom mentioned this issue Apr 26, 2018
Closed
@scotthea-amazon scotthea-amazon mentioned this issue Jun 12, 2018
Closed
6 tasks
@scotthea-amazon scotthea-amazon mentioned this issue Oct 27, 2018
Closed
8 tasks
@mvelegon-amzn mvelegon-amzn mentioned this issue Mar 30, 2019
Closed
8 tasks
@aklinkert aklinkert mentioned this issue Apr 25, 2019
Open
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
6 participants
@mikejrh @dhpp @sdumorti @kclchan @BennyAvramson @XPlay1990