get no sound with plantronics speaker box

[comdata]

Hello,

I have a plantronics P420-M connected to a raspberry pi.

I could record and playback a file with sox and aplay.

Alexa seems to hear me but when speaking back nothing comes back.

The same setup was running ok with 1.0.0.

I found the following errors during initialization of version 1.1.0 .

What can be done?
What information do you need?

ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown 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 confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround40
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround41
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround50
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround51
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround71
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM iec958
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
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:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM default
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM default
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM dmix

[kclchan]

Hi @comdata, how did you tell Alexa was hearing you properly? Would you be able to attach more logs (with DEBUG9)? Also, what version of gstreamer are you using?

While It is normal to get messages like that from ALSA on raspberry pi, here's a great reference to asoundrc that may help you if the problem is due to audio being routed to a wrong device:
https://www.alsa-project.org/main/index.php/Asoundrc

[comdata]

This is the log from DEBUG9:
Running app with log level: DEBUG9
2017-10-05 07:28:20.261 [ 1] I ConfigurationNode:initializeSuccess
2017-10-05 07:28:20.363 [ 1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2017-10-05 07:28:20.366 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x8cbde8,policy=BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x8cc518,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x8cc518,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x8cc518,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x8cb150,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x8cb150,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x8ccee8,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x8ccee8,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x8d5e70,policy=NON_BLOCKING
2017-10-05 07:28:20.367 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x8caf0c,policy=NON_BLOCKING
#############################

Connecting...

#############################
2017-10-05 07:28:23.056 [ 2] I DirectiveSequencer:onDirective:directive={"namespace:"System",name:"SetEndpoint",messageId:"fb0dac93-2502-45e8-9011-2197f288204d",dialogRequestId:""}
########################################

Alexa is currently idle!

########################################
2017-10-05 07:28:23.057 [ 3] I DirectiveRouter:preHandleDirective:messageId=fb0dac93-2502-45e8-9011-2197f288204d,action=calling
2017-10-05 07:28:23.058 [ 4] I DirectiveRouter:handleDirective:messageId=fb0dac93-2502-45e8-9011-2197f288204d,action=calling
#####################################

Client not connected!

#####################################
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown 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 confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround40
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround41
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround50
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround51
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround71
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM iec958
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
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:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM default
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM default
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM dmix
2017-10-05 07:28:23.120 [ 1] I SensoryKeywordDetector:License expires on 28 Jan 2018 00:00:00 GMT
2017-10-05 07:28:23.120 [ 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. |
| 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. |
+----------------------------------------------------------------------------+

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

Alexa is currently idle!

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

I could see Alexa listening and speaking by seeing the Listening, Thinking and Speaking debug outputs and performing the smart home actions I asked.

I will see later if the asoundrc is having to do anything with it.

[scotthea-amazon]

Hi @comdata,

I can see that you specified DEBUG9, but it looks like you are running a RELEASE build, where DEBUG level logging is compiled out. Would you please rebuild the SDK, passing -DCMAKE_BUILD_TYPE=DEBUG on the cmake command line and update the logs?

Thank you,
-SWH

[comdata]

Ok, I've compiled it with the DEBUG build statement.
But that changed nothing:

2017-10-06 08:32:39.209 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=SERVER_ENDPOINT_CHANGED
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM sysdefault
ALSA lib confmisc.c:767:(parse_card) cannot find card '0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
ALSA lib confmisc.c:1246:(snd_func_refer) error evaluating name
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown 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 confmisc.c:767:(parse_card) cannot find card '0'

It seems somehow that the ALSA is not finding the card.

[comdata]

It seems like it is trying just to use card 0 and not the default card.

[kclchan]

Hi @comdata, Please take a look at the URL below on how to modify the $HOME/.asoundrc file to change the card or device:
https://www.alsa-project.org/main/index.php/Asoundrc

If that doesn't help, please attach more DEBUG9 logs. Maybe attach the logs when you ask Alexa something, and when Alexa responses (or should have response). We would like see if there's maybe something else that's causing the issue.

Thanks!

[comdata]

Hi @kclchan,

I tried various configurations in the .asoundrc file and can control where aplay will output my test.wav file.

So far no change.

Please find attached the log:

Running app with log level: DEBUG9
2017-10-19 15:36:45.374 [ 1] I ConfigurationNode:initializeSuccess
2017-10-19 15:36:45.391 [ 1] 9 MediaPlayer:createCalled
2017-10-19 15:36:45.465 [ 1] 9 MediaPlayer:createCalled
2017-10-19 15:36:45.467 [ 1] 9 MediaPlayer:createCalled
2017-10-19 15:36:45.489 [ 1] 9 MediaPlayer:setObserverCalled
2017-10-19 15:36:45.490 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-10-19 15:36:45.491 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x16002bc
2017-10-19 15:36:45.493 [ 1] 9 MediaPlayer:setObserverCalled
2017-10-19 15:36:45.494 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-10-19 15:36:45.495 [ 1] 9 MediaPlayer:setObserverCalled
2017-10-19 15:36:45.496 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-10-19 15:36:45.502 [ 1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2017-10-19 15:36:45.504 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2017-10-19 15:36:45.512 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x1606a30,policy=BLOCKING
2017-10-19 15:36:45.513 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x1607228,policy=NON_BLOCKING
2017-10-19 15:36:45.514 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x1607228,policy=NON_BLOCKING
2017-10-19 15:36:45.515 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x1607228,policy=NON_BLOCKING
2017-10-19 15:36:45.516 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x16060f0,policy=NON_BLOCKING
2017-10-19 15:36:45.516 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x16060f0,policy=NON_BLOCKING
2017-10-19 15:36:45.517 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x1607e88,policy=NON_BLOCKING
2017-10-19 15:36:45.518 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x1607e88,policy=NON_BLOCKING
2017-10-19 15:36:45.519 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x1610e50,policy=NON_BLOCKING
2017-10-19 15:36:45.520 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x1605eac,policy=NON_BLOCKING
2017-10-19 15:36:46.323 [ 4] 0 HttpPost:doPostSucceeded:code=200
2017-10-19 15:36:46.324 [ 4] 0 AuthDelegate:handleLwaResponseSucceeded:expiresInSeconds=3600
2017-10-19 15:36:46.325 [ 4] 0 AuthDelegate:onAuthStateChangeCalled:state=1,error=0
2017-10-19 15:36:46.325 [ 1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2017-10-19 15:36:46.331 [ 1] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1
2017-10-19 15:36:46.333 [ 5] 9 PostConnectSynchronize:Entering postConnectLoop thread
#############################

Connecting...

#############################
2017-10-19 15:36:46.335 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
2017-10-19 15:36:46.336 [ 6] 9 SpeechSynthesizer:provideState:token=1
2017-10-19 15:36:46.336 [ 7] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2017-10-19 15:36:46.337 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1
2017-10-19 15:36:46.338 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"},namespace=AudioPlayer,name=PlaybackState
2017-10-19 15:36:46.339 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" : ""},namespace=SpeechRecognizer,name=RecognizerState
2017-10-19 15:36:46.340 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2017-10-19 15:36:46.342 [ 6] 0 ContextManager:buildContextSuccessful:context={"context":[{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":""}}]}
2017-10-19 15:36:46.345 [ 6] 0 EventBuilder:buildJsonEventString:messageId=5487a507-1b7a-4e27-969e-0572aaea008a,namespace=System,name=SynchronizeState
2017-10-19 15:36:46.348 [ 6] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport
2017-10-19 15:36:46.933 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 124c16fffeeb83ce-00007517-00031ca7-a20de14697d9889c-63323cc2-1
2017-10-19 15:36:46.934 [ a] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2
2017-10-19 15:36:46.934 [ a] 9 HTTP2Transport:insertActiveStream:handle=0x67aa06a0
2017-10-19 15:36:48.173 [ a] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 124c16fffeeb83ce-00007517-00031ca7-a20de14697d9889c-63323cc2-3
2017-10-19 15:36:48.176 [ b] 0 MessageInterpreter:receive:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440:No dialogRequestId attached to message.
2017-10-19 15:36:48.177 [ b] I DirectiveSequencer:onDirective:directive={"namespace:"System",name:"SetEndpoint",messageId:"1bb9aaed-9e81-4932-b5ac-b8be49510440",dialogRequestId:""}
2017-10-19 15:36:48.178 [ c] I DirectiveRouter:preHandleDirective:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440,action=calling
2017-10-19 15:36:48.179 [ c] 0 CapabilityAgent:addingMessageIdToMap:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440
2017-10-19 15:36:48.179 [ a] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS
2017-10-19 15:36:48.180 [ 5] 9 PostConnectSynchronize:Exiting postConnectLoop thread
2017-10-19 15:36:48.180 [ a] 0 PostConnectSynchronize:onConnected()
2017-10-19 15:36:48.180 [ a] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2017-10-19 15:36:48.181 [ a] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=200
########################################

Alexa is currently idle!

########################################
2017-10-19 15:36:48.182 [ a] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
2017-10-19 15:36:48.184 [ d] I DirectiveRouter:handleDirective:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440,action=calling
2017-10-19 15:36:48.185 [ d] 0 MessageRouter:connectionStatusChanged:reason=SERVER_ENDPOINT_CHANGED,newStatus=DISCONNECTED
2017-10-19 15:36:48.185 [ d] 0 PostConnectSynchronize:PostConnectSynchronizer::doShutdown().
2017-10-19 15:36:48.186 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
#####################################

Client not connected!

#####################################
2017-10-19 15:36:48.187 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=SERVER_ENDPOINT_CHANGED
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.front.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown 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 confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround40.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround40
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround41
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround50
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround51
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.surround71.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround71
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM iec958
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
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
2017-10-19 15:36:48.292 [ 1] I SensoryKeywordDetector:License expires on 28 Jan 2018 00:00:00 GMT
2017-10-19 15:36:48.292 [ 1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
2017-10-19 15:36:48.293 [ a] 0 HTTP2StreamPool:releaseStream:streamId=1,numAcquiredStreams=0
2017-10-19 15:36:48.297 [ a] 0 PostConnectSynchronize:onDisconnected()
2017-10-19 15:36:48.297 [ a] 0 PostConnectSynchronize:PostConnectSynchronizer::doShutdown().
2017-10-19 15:36:48.300 [ d] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=1
2017-10-19 15:36:48.300 [ e] 9 PostConnectSynchronize:Entering postConnectLoop thread
2017-10-19 15:36:48.301 [ d] 0 DirectiveProcessor:onHandlingCompeted:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440,directiveBeingPreHandled=(nullptr)
2017-10-19 15:36:48.301 [ d] 0 CapabilityAgent:removingMessageIdFromMap:messageId=1bb9aaed-9e81-4932-b5ac-b8be49510440
2017-10-19 15:36:48.303 [ 6] 0 ContextManager:buildContextSuccessful:context={"context":[{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":""}}]}
2017-10-19 15:36:48.304 [ 6] 0 EventBuilder:buildJsonEventString:messageId=11d30585-3041-46c5-9053-2eabdae6fe71,namespace=System,name=SynchronizeState
2017-10-19 15:36:48.305 [ 6] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport
# # # ##### ##### ###### # #
# # # # # # # # # # # #
# # # # # # # # # #
# # # # ##### ##### # # ###
####### # # # # # # # #
# # # # # # # # # # # #
# # # ##### ##### ###### # #

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

+----------------------------------------------------------------------------+
| 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. |
| 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. |
+----------------------------------------------------------------------------+

2017-10-19 15:36:48.596 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-1
2017-10-19 15:36:48.597 [ f] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2017-10-19 15:36:48.597 [ f] 9 HTTP2Transport:insertActiveStream:handle=0x72fd4ef8
2017-10-19 15:36:50.365 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-3
2017-10-19 15:36:50.366 [ f] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS
2017-10-19 15:36:50.366 [ e] 9 PostConnectSynchronize:Exiting postConnectLoop thread
2017-10-19 15:36:50.366 [ f] 0 PostConnectSynchronize:onConnected()
2017-10-19 15:36:50.367 [ f] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2017-10-19 15:36:50.367 [ f] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2017-10-19 15:36:50.367 [ f] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2017-10-19 15:36:50.367 [ f] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2017-10-19 15:36:50.368 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
########################################

Alexa is currently idle!

########################################
2017-10-19 15:38:42.610 [ 9] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2017-10-19 15:38:42.612 [ 10] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
2017-10-19 15:38:42.612 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" : "alexa"},namespace=SpeechRecognizer,name=RecognizerState
############################

Listening...

############################
2017-10-19 15:38:42.616 [ 6] 0 ContextManager:buildContextSuccessful:context={"context":[{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}}]}
2017-10-19 15:38:42.619 [ 9] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context":[{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}}]}
2017-10-19 15:38:42.620 [ 9] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2017-10-19 15:38:42.621 [ 9] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=017fdc97-1fc1-4909-a42f-87d150cd0950
2017-10-19 15:38:42.622 [ 9] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2017-10-19 15:38:42.624 [ 9] 0 EventBuilder:buildJsonEventString:messageId=7105c66f-3153-4540-a22d-e19d50ef220b,namespace=SpeechRecognizer,name=Recognize
2017-10-19 15:38:42.632 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2017-10-19 15:38:42.719 [ f] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2017-10-19 15:38:42.719 [ f] 9 HTTP2Transport:insertActiveStream:handle=0x72fd4ef8
2017-10-19 15:38:45.753 [ b] 0 MessageInterpreter:receive:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb:No dialogRequestId attached to message.
2017-10-19 15:38:45.753 [ b] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"21516326-c0c1-41d3-89c6-27cd6c0913cb",dialogRequestId:""}
2017-10-19 15:38:45.754 [ c] I DirectiveRouter:preHandleDirective:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb,action=calling
2017-10-19 15:38:45.755 [ c] 0 CapabilityAgent:addingMessageIdToMap:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb
2017-10-19 15:38:45.756 [ d] I DirectiveRouter:handleDirective:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb,action=calling
2017-10-19 15:38:45.757 [ 9] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2017-10-19 15:38:45.758 [ 9] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2017-10-19 15:38:45.758 [ 9] 0 DirectiveProcessor:onHandlingCompeted:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb,directiveBeingPreHandled=(nullptr)
2017-10-19 15:38:45.758 [ 10] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
2017-10-19 15:38:45.759 [ 9] 0 CapabilityAgent:removingMessageIdFromMap:messageId=21516326-c0c1-41d3-89c6-27cd6c0913cb
###########################

Thinking...

###########################
2017-10-19 15:38:45.763 [ f] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2017-10-19 15:38:46.256 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-5
2017-10-19 15:38:46.290 [ b] I DirectiveSequencer:onDirective:directive={"namespace:"Speaker",name:"SetMute",messageId:"29c0a89f-ee12-46c5-b482-791671a41e72",dialogRequestId:"017fdc97-1fc1-4909-a42f-87d150cd0950"}
2017-10-19 15:38:46.292 [ b] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"0ed1a321-ee45-47b5-80bf-c93ce1031b5a",dialogRequestId:"017fdc97-1fc1-4909-a42f-87d150cd0950"}
2017-10-19 15:38:46.293 [ c] W DirectiveRouter:preHandleDirectiveFailed:messageId=29c0a89f-ee12-46c5-b482-791671a41e72,reason=noHandlerRegistered
2017-10-19 15:38:46.293 [ c] I DirectiveSequencer:sendingExceptionEncountered:messageId=29c0a89f-ee12-46c5-b482-791671a41e72
2017-10-19 15:38:46.295 [ c] 0 EventBuilder:buildJsonEventString:messageId=fabe9d1d-656a-4e0c-9d9a-a0ecddbffeab,namespace=System,name=ExceptionEncountered
2017-10-19 15:38:46.296 [ c] I DirectiveRouter:preHandleDirective:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a,action=calling
2017-10-19 15:38:46.297 [ c] 0 CapabilityAgent:addingMessageIdToMap:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:46.298 [ c] 9 SpeechSynthesizer:preHandleDirective:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:46.299 [ 8] 0 SpeechSynthesizer:executePreHandle:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:46.299 [ d] I DirectiveRouter:handleDirective:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a,action=calling
2017-10-19 15:38:46.300 [ d] 9 SpeechSynthesizer:handleDirective:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:46.301 [ f] 0 HTTP2StreamPool:getStream:streamId=11,numAcquiredStreams=3
2017-10-19 15:38:46.303 [ 8] 0 SpeechSynthesizer:executeHandle:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:46.303 [ f] 9 HTTP2Transport:insertActiveStream:handle=0x72fde760
2017-10-19 15:38:46.303 [ 8] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2017-10-19 15:38:46.305 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2017-10-19 15:38:46.305 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2017-10-19 15:38:46.305 [ 9] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2017-10-19 15:38:46.305 [ 8] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2017-10-19 15:38:46.305 [ 8] 9 SpeechSynthesizer:startPlaying
2017-10-19 15:38:46.305 [ 9] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2017-10-19 15:38:46.306 [ 8] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2017-10-19 15:38:46.307 [ 2] 0 MediaPlayer:handleSetSourceCalled
2017-10-19 15:38:46.307 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2017-10-19 15:38:46.307 [ 2] 0 MediaPlayer:doStopSuccess:reason=alreadyStopped
2017-10-19 15:38:46.307 [ 2] 9 MediaPlayer:doStopSuccess
2017-10-19 15:38:46.334 [ 8] 9 MediaPlayer:playCalled
2017-10-19 15:38:46.334 [ 2] 0 MediaPlayer:handlePlayCalled
2017-10-19 15:38:46.334 [ 2] 0 MediaPlayer:doStopSuccess:reason=alreadyStopped
2017-10-19 15:38:46.334 [ 2] 9 MediaPlayer:doStopSuccess
2017-10-19 15:38:46.334 [ 2] 0 MediaPlayer:handlePlay:attemptBuffering=0
2017-10-19 15:38:46.359 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2017-10-19 15:38:46.360 [ 2] 9 MediaPlayer:messageReceived:messageType=warning
2017-10-19 15:38:46.360 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.360 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.360 [ 12] 9 BaseStreamSource:onNeedDataCalled:size=4096
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.361 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.362 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2017-10-19 15:38:46.362 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.362 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2017-10-19 15:38:46.362 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2017-10-19 15:38:46.362 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=10
2017-10-19 15:38:46.363 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.363 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.363 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.364 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.364 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.364 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.365 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.365 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.365 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.366 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.366 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.366 [ 2] 9 AttachmentReaderSource:read:size=2854,status=0
2017-10-19 15:38:46.379 [ f] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=200
2017-10-19 15:38:46.380 [ f] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=2
2017-10-19 15:38:46.381 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-7
2017-10-19 15:38:46.381 [ f] 0 HTTP2Transport:cleanupFinishedStream:streamId=11,result=204
2017-10-19 15:38:46.381 [ f] 0 HTTP2StreamPool:releaseStream:streamId=11,numAcquiredStreams=1
2017-10-19 15:38:46.381 [ f] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2017-10-19 15:38:46.382 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.383 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.383 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.383 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-10-19 15:38:46.383 [ 2] 9 AttachmentReaderSource:read:size=4007,status=0
2017-10-19 15:38:46.384 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2017-10-19 15:38:46.384 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2017-10-19 15:38:46.384 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2017-10-19 15:38:46.385 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=10
2017-10-19 15:38:46.391 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.392 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.401 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.401 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.409 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.409 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.410 [ 12] 9 MediaPlayer:onPadAddedCalled
2017-10-19 15:38:46.411 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2017-10-19 15:38:46.413 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.413 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start
2017-10-19 15:38:46.415 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-10-19 15:38:46.416 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-10-19 15:38:46.416 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.416 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.416 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.417 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2017-10-19 15:38:46.417 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done
2017-10-19 15:38:46.417 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock
2017-10-19 15:38:46.418 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.422 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.423 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.423 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.423 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.423 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-10-19 15:38:46.423 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2017-10-19 15:38:46.424 [ 2] 0 MediaPlayer:callingOnPlaybackStarted
2017-10-19 15:38:46.424 [ 2] 9 SpeechSynthesizer:onPlaybackStarted
2017-10-19 15:38:46.424 [ 8] 0 SpeechSynthesizer:executePlaybackStarted
2017-10-19 15:38:46.424 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2017-10-19 15:38:46.424 [ 8] 9 MediaPlayer:getOffsetCalled
2017-10-19 15:38:46.425 [ 2] 0 MediaPlayer:handleGetOffsetCalled
2017-10-19 15:38:46.426 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#d0e3006b-0bdd-4e1f-baf8-6a1ee215af5d#Alexa3P:1.0/2017/10/19/15/93babf7500464c0b8a9493c3d312499a/38:43::TNIH_2V.8b32b200-32ec-492c-a25b-20865c237becLPM/1","offsetInMilliseconds":8,"playerActivity":"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState
2017-10-19 15:38:46.426 [ 11] 9 SpeechSynthesizer:onFocusChangedSuccess
2017-10-19 15:38:46.427 [ 8] 0 EventBuilder:buildJsonEventString:messageId=decde87e-2b1e-42a1-92bd-e959cb38bf52,namespace=SpeechSynthesizer,name=SpeechStarted
2017-10-19 15:38:46.428 [ 10] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
###########################

Speaking...

###########################
2017-10-19 15:38:46.482 [ f] 0 HTTP2StreamPool:getStream:streamId=13,numAcquiredStreams=2
2017-10-19 15:38:46.483 [ f] 9 HTTP2Transport:insertActiveStream:handle=0x72fde760
2017-10-19 15:38:46.585 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-10-19 15:38:46.671 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-9
2017-10-19 15:38:46.671 [ f] 0 HTTP2Transport:cleanupFinishedStream:streamId=13,result=204
2017-10-19 15:38:46.671 [ f] 0 HTTP2StreamPool:releaseStream:streamId=13,numAcquiredStreams=1
2017-10-19 15:38:46.672 [ f] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2017-10-19 15:38:57.745 [ 12] 9 BaseStreamSource:onNeedDataCalled:size=4096
2017-10-19 15:38:57.747 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2017-10-19 15:38:57.793 [ 2] 9 MediaPlayer:messageReceived:messageType=eos
2017-10-19 15:38:57.793 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2017-10-19 15:38:57.800 [ 2] 9 BaseStreamSource:~BaseStreamSource
2017-10-19 15:38:57.801 [ 2] 0 MediaPlayer:callingOnPlaybackFinished
2017-10-19 15:38:57.801 [ 2] 9 SpeechSynthesizer:onPlaybackFinished
2017-10-19 15:38:57.801 [ 2] 9 MediaPlayer:doStopSuccess
2017-10-19 15:38:57.801 [ 8] 0 SpeechSynthesizer:executePlaybackFinished
2017-10-19 15:38:57.802 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2017-10-19 15:38:57.802 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#d0e3006b-0bdd-4e1f-baf8-6a1ee215af5d#Alexa3P:1.0/2017/10/19/15/93babf7500464c0b8a9493c3d312499a/38:43::TNIH_2V.8b32b200-32ec-492c-a25b-20865c237becLPM/1","offsetInMilliseconds":0,"playerActivity":"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2017-10-19 15:38:57.803 [ 8] 9 SpeechSynthesizer:releaseForegroundFocus
2017-10-19 15:38:57.803 [ 8] 1 FocusManager:releaseChannel:channelName=Dialog
2017-10-19 15:38:57.804 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2017-10-19 15:38:57.806 [ 8] 0 EventBuilder:buildJsonEventString:messageId=e6cbfc0a-bd0e-4a38-9b76-6c1bb840f5fd,namespace=SpeechSynthesizer,name=SpeechFinished
2017-10-19 15:38:57.807 [ 8] 9 SpeechSynthesizer:setHandlingCompleted
2017-10-19 15:38:57.809 [ 8] 0 DirectiveProcessor:onHandlingCompeted:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a,directiveBeingPreHandled=(nullptr)
2017-10-19 15:38:57.810 [ 8] 0 CapabilityAgent:removingMessageIdFromMap:messageId=0ed1a321-ee45-47b5-80bf-c93ce1031b5a
2017-10-19 15:38:57.823 [ f] 0 HTTP2StreamPool:getStream:streamId=15,numAcquiredStreams=2
2017-10-19 15:38:57.824 [ f] 9 HTTP2Transport:insertActiveStream:handle=0x72fde760
2017-10-19 15:38:58.014 [ 10] 0 DialogUXStateAggregator:setState:from=FINISHED,to=IDLE
########################################

Alexa is currently idle!

########################################
2017-10-19 15:38:58.167 [ f] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 0255fffffef673c4-000077eb-0001851e-4e5006df1289b00c-de853329-11
2017-10-19 15:38:58.167 [ f] 0 HTTP2Transport:cleanupFinishedStream:streamId=15,result=204
2017-10-19 15:38:58.168 [ f] 0 HTTP2StreamPool:releaseStream:streamId=15,numAcquiredStreams=1
2017-10-19 15:38:58.168 [ f] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

What more can I provide?

[BennyAvramson]

Hi @comdata,

Can you try connect a 3.5mm headphones and configure to use it
Also, did you test your speakerPhone with other applications such as aplay?

What OS and version do you use?
Did you install the SDK with the automated_install script?

Thanks,
Benny

[comdata]

Hi @BennyAvramson,

I have a 3.5mm headphone connected. This way I could figure out that both output channels are working. I could direct the output of play through the headphones and the usb audio device.

I am using raspbian stretch updated just yesterday.

I did not use the automated_install script.

[comdata]

Hi,

I have run it with strace maybe this helps to see what is wrong.

alexadebug.zip

Kind Regards,
Christoph

[bandkenamazoncom]

Can you confirm that the headphones work in other applications?

[comdata]

Yes. Both the headphones and also the speaker box work with other applications e.g. aplay Kind Regards, Christoph Am 28.10.2017 23:03 schrieb "bandkenamazoncom" <notifications@github.com>:

…

Can you confirm that the headphones work in other applications? — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub <#212 (comment)>, or mute the thread </~https://github.com/notifications/unsubscribe-auth/AEe6z5fjYUNc0kZt2iGWobm-yO6IgkyBks5sw5aQgaJpZM4PuIg8> .

[comdata]

I tried this with version 1.2.0 today but it seems not to change anything.

Kind Regards,
Christoph

[merdahl]

Hi, I have a similar issue. Alexa hears me, but I do not hear her responses.

I am bringing up the C++ SampleApp on a BeagleBone, using a USB audio card for sound input/ output. The Sensory voice triggering is working (Alexa goes into Listening mode), and my requests are handled properly (e.g. asking weather brings up the proper city card), but playback is always silent.

Things I've tried that work:

• Port Audio test programs
• ALSA play/ record utilities

ALSA says there are the cards installed on my system:
debian@beaglebone:~$ aplay -l
**** List of PLAYBACK Hardware Devices ****
card 0: Black [TI BeagleBone Black], device 0: davinci-mcasp.0-i2s-hifi i2s-hifi-0 []
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: Juke [ROCCAT Juke], device 0: USB Audio [USB Audio]
Subdevices: 1/1
Subdevice #0: subdevice #0

debian@beaglebone:~$ arecord -l
**** List of CAPTURE Hardware Devices ****
card 1: Juke [ROCCAT Juke], device 0: USB Audio [USB Audio]
Subdevices: 1/1
Subdevice #0: subdevice #0

My ~/.asoundrc looks like this:
pcm.!default {
type asym
playback.pcm {
type plug
slave.pcm "hw:Juke"
}
capture.pcm {
type plug
slave.pcm "hw:Juke"
}
}

Here are my logs asking Alexa for the weather:

Running app with log level: DEBUG9
2017-11-04 21:38:10.222 [ 1] I ConfigurationNode:initializeSuccess
2017-11-04 21:38:10.243 [ 1] 9 MediaPlayer:createCalled
2017-11-04 21:38:10.366 [ 1] 9 MediaPlayer:createCalled
2017-11-04 21:38:10.371 [ 1] 9 MediaPlayer:createCalled
2017-11-04 21:38:10.428 [ 1] 9 MediaPlayer:setObserverCalled
2017-11-04 21:38:10.429 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-11-04 21:38:10.430 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x18540bc
2017-11-04 21:38:10.434 [ 1] 9 MediaPlayer:setObserverCalled
2017-11-04 21:38:10.435 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-11-04 21:38:10.436 [ 1] 9 MediaPlayer:setObserverCalled
2017-11-04 21:38:10.437 [ 2] 0 MediaPlayer:handleSetObserverCalled
2017-11-04 21:38:10.446 [ 1] 9 AlertScheduler:setTimerForNextAlertLocked
2017-11-04 21:38:10.446 [ 1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2017-11-04 21:38:10.450 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2017-11-04 21:38:10.462 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2017-11-04 21:38:10.463 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2017-11-04 21:38:10.463 [ 1] 9 MediaPlayer:getSpeakerTypeCalled
2017-11-04 21:38:10.464 [ 1] 0 SpeakerManager:mapCreated:numAvsSynced=2,numLocal=1
2017-11-04 21:38:10.467 [ 1] 1 AudioPlayer:addObserver
2017-11-04 21:38:10.472 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x1863c88,policy=BLOCKING
2017-11-04 21:38:10.473 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x1864758,policy=NON_BLOCKING
2017-11-04 21:38:10.473 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x1864758,policy=NON_BLOCKING
2017-11-04 21:38:10.473 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x1864758,policy=NON_BLOCKING
2017-11-04 21:38:10.474 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x1863538,policy=NON_BLOCKING
2017-11-04 21:38:10.474 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x1863538,policy=NON_BLOCKING
2017-11-04 21:38:10.474 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x1865210,policy=NON_BLOCKING
2017-11-04 21:38:10.475 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x1865210,policy=NON_BLOCKING
2017-11-04 21:38:10.476 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0x186efb8,policy=NON_BLOCKING
2017-11-04 21:38:10.476 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x1863324,policy=NON_BLOCKING
2017-11-04 21:38:10.476 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x186d9d0,policy=NON_BLOCKING
2017-11-04 21:38:10.476 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x186d9d0,policy=NON_BLOCKING
2017-11-04 21:38:10.477 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x186d9d0,policy=NON_BLOCKING
2017-11-04 21:38:10.477 [ 1] 9 TemplateRuntime:getConfiguration
2017-11-04 21:38:10.477 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x186e9a0,policy=NON_BLOCKING
2017-11-04 21:38:10.478 [ 1] I DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x186e9a0,policy=NON_BLOCKING
2017-11-04 21:38:10.883 [ 4] 0 HttpPost:doPostSucceeded:code=200
2017-11-04 21:38:10.884 [ 4] 0 AuthDelegate:handleLwaResponseSucceeded:expiresInSeconds=3600
2017-11-04 21:38:10.885 [ 4] 0 AuthDelegate:onAuthStateChangeCalled:state=1,error=0
2017-11-04 21:38:10.885 [ 1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2017-11-04 21:38:10.891 [ 1] 0 HTTP2StreamPool:getStream:streamId=1,numAcquiredStreams=1
2017-11-04 21:38:10.896 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
#############################

Connecting...

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

2017-11-04 21:38:10.901 [ 5] 9 PostConnectSynchronize:Entering postConnectLoop thread
2017-11-04 21:38:10.902 [ 6] 9 SpeakerManager:provideStateCalled
2017-11-04 21:38:10.903 [ 7] 9 SpeakerManager:executeProvideStateCalled
2017-11-04 21:38:10.903 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:10.904 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:10.905 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:10.905 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:10.906 [ 6] 9 SpeechSynthesizer:provideState:token=1
2017-11-04 21:38:10.906 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1
2017-11-04 21:38:10.908 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2017-11-04 21:38:10.909 [ 7] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2017-11-04 21:38:10.909 [ 7] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2017-11-04 21:38:10.909 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume":100,"muted":false},namespace=Speaker,name=VolumeState
2017-11-04 21:38:10.910 [ 6] 0 AudioPlayer:provideState:stateRequestToken=1
2017-11-04 21:38:10.910 [ 9] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2017-11-04 21:38:10.911 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"},namespace=AudioPlayer,name=PlaybackState
2017-11-04 21:38:10.912 [ a] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" : ""},namespace=SpeechRecognizer,name=RecognizerState
2017-11-04 21:38:10.915 [ 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":[]}}]}
2017-11-04 21:38:10.922 [ 6] 0 EventBuilder:buildJsonEventString:messageId=09a3a224-45e1-49cf-8d03-e7d48612dd42,namespace=System,name=SynchronizeState
2017-11-04 21:38:10.929 [ 6] 0 PostConnectSynchronize:onContextAvailable : Send PostConnectMessage to transport
2017-11-04 21:38:10.929 [ 6] 9 HTTP2Transport:enqueueRequest
2017-11-04 21:38:11.335 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-1
2017-11-04 21:38:11.336 [ b] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-04 21:38:11.337 [ b] 0 HTTP2StreamPool:getStream:streamId=3,numAcquiredStreams=2
2017-11-04 21:38:11.338 [ b] 9 HTTP2Transport:insertActiveStream:handle=0xb46d69a0
2017-11-04 21:38:12.609 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-3
2017-11-04 21:38:12.610 [ b] 0 PostConnectSynchronize:onSendCompleted:status=SUCCESS_NO_CONTENT
2017-11-04 21:38:12.611 [ 5] 9 PostConnectSynchronize:Exiting postConnectLoop thread
2017-11-04 21:38:12.612 [ b] 0 PostConnectSynchronize:onConnected()
2017-11-04 21:38:12.612 [ b] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2017-11-04 21:38:12.614 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2017-11-04 21:38:12.614 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1853080
2017-11-04 21:38:12.616 [ 3] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
########################################

Alexa is currently idle!

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

2017-11-04 21:38:12.621 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=204
2017-11-04 21:38:12.622 [ b] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
2017-11-04 21:38:12.622 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
ALSA lib pcm_plug.c:909:(snd_pcm_plug_hw_refine_cchange) Unable to find an usable client format
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S8
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U8
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S16_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S16_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U16_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U16_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S24_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S24_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U24_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U24_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S32_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S32_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U32_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U32_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: FLOAT_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: FLOAT_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: FLOAT64_LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: FLOAT64_BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: MU_LAW
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: A_LAW
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: IMA_ADPCM
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S24_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S24_3BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U24_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U24_3BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S20_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S20_3BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U20_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U20_3BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S18_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: S18_3BE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U18_3LE
ALSA lib pcm_plug.c:913:(snd_pcm_plug_hw_refine_cchange) Format: U18_3BE
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.front.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown 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 confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround21
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround40.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround40
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround41
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround50
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround51.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround51
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.surround71.0:CARD=0'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM surround71
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM iec958
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.TI_BeagleBone_B.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:5007:(snd_config_expand) Evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM spdif
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 pc[82418.917575] hdmi-audio-codec hdmi-audio-codec.2.auto: ASoC: can't set i2s-hifi hw params: -19
m.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 pcm_direct.c:1055:(snd1_pcm_direct_initialize_slave) unable to install hw params
ALSA lib pcm_dmix.c:1064:(snd_pcm_dmix_open) unable to initialize slave
2017-11-04 21:38:13.394 [ 1] I SensoryKeywordDetector:License expires on 28 Feb 2018 00:00:00 GMT
2017-11-04 21:38:13.395 [ 1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
2017-11-04 21:38:13.414 [ 1] 9 TemplateRuntime:addObserver
2017-11-04 21:38:13.415 [ c] 0 TemplateRuntime:addObserverInExecutor
# # # ##### ##### ###### # #
# # # # # # # # # # # #
# # # # # # # # # #
# # # # ##### ##### # # ###
####### # # # # # # # #
# # # # # # # # # # # #
# # # ##### ##### ###### # #

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

+----------------------------------------------------------------------------+
| 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. |
+----------------------------------------------------------------------------+

2017-11-04 21:38:18.413 [ a] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
2017-11-04 21:38:18.414 [ d] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
############################

Listening...

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

2017-11-04 21:38:18.416 [ a] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" : "alexa"},namespace=SpeechRecognizer,name=RecognizerState
2017-11-04 21:38:18.421 [ 6] 9 SpeakerManager:provideStateCalled
2017-11-04 21:38:18.422 [ 7] 9 SpeakerManager:executeProvideStateCalled
2017-11-04 21:38:18.422 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:18.422 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:18.423 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:18.423 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:18.423 [ 7] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2017-11-04 21:38:18.423 [ 7] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2017-11-04 21:38:18.424 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume":100,"muted":false},namespace=Speaker,name=VolumeState
2017-11-04 21:38:18.425 [ 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":[]}}]}
2017-11-04 21:38:18.427 [ a] 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":[]}}]}
2017-11-04 21:38:18.427 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2017-11-04 21:38:18.430 [ a] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=4346e786-99e2-4453-9196-36d98eea46da
2017-11-04 21:38:18.430 [ a] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2017-11-04 21:38:18.431 [ a] 0 EventBuilder:buildJsonEventString:messageId=ebdbb4b0-e25a-4411-9100-6b5c8235582c,namespace=SpeechRecognizer,name=Recognize
2017-11-04 21:38:18.433 [ a] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
2017-11-04 21:38:18.433 [ a] 9 HTTP2Transport:enqueueRequest
2017-11-04 21:38:18.457 [ b] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-04 21:38:18.457 [ b] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=2
2017-11-04 21:38:18.457 [ b] 9 HTTP2Transport:insertActiveStream:handle=0xb46d69a0
2017-11-04 21:38:21.569 [ e] 0 MessageInterpreter:receive:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9:No dialogRequestId attached to message.
2017-11-04 21:38:21.571 [ e] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechRecognizer",name:"StopCapture",messageId:"f2bb414f-d555-4dd8-94de-f690352d90f9",dialogRequestId:""}
2017-11-04 21:38:21.572 [ f] I DirectiveRouter:preHandleDirective:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9,action=calling
2017-11-04 21:38:21.573 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9
2017-11-04 21:38:21.589 [ 10] I DirectiveRouter:handleDirective:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9,action=calling
2017-11-04 21:38:21.590 [ a] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2017-11-04 21:38:21.591 [ a] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
2017-11-04 21:38:21.591 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9,directiveBeingPreHandled=(nullptr)
2017-11-04 21:38:21.591 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=f2bb414f-d555-4dd8-94de-f690352d90f9
2017-11-04 21:38:21.592 [ d] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
###########################

Thinking...

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

2017-11-04 21:38:21.613 [ b] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2017-11-04 21:38:21.894 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-5
2017-11-04 21:38:21.954 [ e] I DirectiveSequencer:onDirective:directive={"namespace:"SpeechSynthesizer",name:"Speak",messageId:"0b2322e5-cd70-4ea0-b68f-20b24edf3f25",dialogRequestId:"4346e786-99e2-4453-9196-36d98eea46da"}
2017-11-04 21:38:21.954 [ f] I DirectiveRouter:preHandleDirective:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25,action=calling
2017-11-04 21:38:21.954 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:21.955 [ f] 9 SpeechSynthesizer:preHandleDirective:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:21.955 [ 8] 0 SpeechSynthesizer:executePreHandle:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:21.961 [ 10] I DirectiveRouter:handleDirective:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25,action=calling
2017-11-04 21:38:21.961 [ 10] 9 SpeechSynthesizer:handleDirective:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:21.979 [ e] I DirectiveSequencer:onDirective:directive={"namespace:"TemplateRuntime",name:"RenderTemplate",messageId:"4168cb09-4ef2-4f8f-b97e-96789ae25c82",dialogRequestId:"4346e786-99e2-4453-9196-36d98eea46da"}
2017-11-04 21:38:21.993 [ 8] 0 SpeechSynthesizer:executeHandle:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:21.994 [ 8] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2017-11-04 21:38:21.995 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2017-11-04 21:38:21.995 [ a] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2017-11-04 21:38:21.996 [ a] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2017-11-04 21:38:21.996 [ a] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2017-11-04 21:38:21.997 [ d] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE
########################################

Alexa is currently idle!

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

2017-11-04 21:38:21.998 [ f] I DirectiveRouter:preHandleDirective:messageId=4168cb09-4ef2-4f8f-b97e-96789ae25c82,action=calling
2017-11-04 21:38:21.998 [ f] 0 CapabilityAgent:addingMessageIdToMap:messageId=4168cb09-4ef2-4f8f-b97e-96789ae25c82
2017-11-04 21:38:21.998 [ f] 9 TemplateRuntime:preHandleDirective
2017-11-04 21:38:21.999 [ f] 9 TemplateRuntime:handleRenderTemplateDirective
2017-11-04 21:38:21.999 [ c] 0 TemplateRuntime:handleRenderTemplateDirectiveInExecutor
##############################################################################

RenderTemplateCard

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

Template Type : WeatherTemplate

Main Title : Dallas, TX

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

2017-11-04 21:38:22.004 [ c] 0 DirectiveProcessor:onHandlingCompeted:messageId=4168cb09-4ef2-4f8f-b97e-96789ae25c82,directiveBeingPreHandled=4168cb09-4ef2-4f8f-b97e-96789ae25c82
2017-11-04 21:38:22.006 [ 8] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2017-11-04 21:38:22.006 [ 8] 9 SpeechSynthesizer:startPlaying
2017-11-04 21:38:22.011 [ 8] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2017-11-04 21:38:22.012 [ c] 0 CapabilityAgent:removingMessageIdFromMap:messageId=4168cb09-4ef2-4f8f-b97e-96789ae25c82
2017-11-04 21:38:22.013 [ 2] 0 MediaPlayer:handleSetSourceCalled
2017-11-04 21:38:22.013 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2017-11-04 21:38:22.089 [ 8] 9 MediaPlayer:playCalled
2017-11-04 21:38:22.090 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=1,currentId=1
2017-11-04 21:38:22.096 [ 2] 0 MediaPlayer:handlePlay:attemptBuffering=0
2017-11-04 21:38:22.126 [ b] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2017-11-04 21:38:22.127 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=5,result=200
2017-11-04 21:38:22.127 [ b] 0 HTTP2StreamPool:releaseStream:streamId=5,numAcquiredStreams=1
2017-11-04 21:38:22.184 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2017-11-04 21:38:22.184 [ 2] 9 MediaPlayer:messageReceived:messageType=warning
2017-11-04 21:38:22.184 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.184 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.184 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.189 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.189 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.189 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.189 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2017-11-04 21:38:22.190 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.193 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2017-11-04 21:38:22.193 [ 12] 9 BaseStreamSource:onNeedDataCalled:size=4096
2017-11-04 21:38:22.194 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2017-11-04 21:38:22.194 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=14
2017-11-04 21:38:22.195 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.195 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.195 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.195 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.205 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.205 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.206 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.206 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2017-11-04 21:38:22.206 [ 2] 9 AttachmentReaderSource:read:size=3821,status=0
2017-11-04 21:38:22.206 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2017-11-04 21:38:22.206 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2017-11-04 21:38:22.207 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2017-11-04 21:38:22.545 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=14
2017-11-04 21:38:22.545 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.545 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.546 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.546 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.546 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.546 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.338 [ 12] 9 MediaPlayer:onPadAddedCalled
2017-11-04 21:38:22.546 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2017-11-04 21:38:22.556 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.561 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start
2017-11-04 21:38:22.561 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-11-04 21:38:22.562 [ 2] 0 MediaPlayer:callingOnTags
2017-11-04 21:38:22.562 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-11-04 21:38:22.563 [ 2] 0 MediaPlayer:callingOnTags
2017-11-04 21:38:22.563 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.563 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.563 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.563 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2017-11-04 21:38:22.563 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done
2017-11-04 21:38:22.568 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock
2017-11-04 21:38:22.571 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.572 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.573 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.573 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2017-11-04 21:38:22.573 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2017-11-04 21:38:22.573 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=1
2017-11-04 21:38:22.573 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=1
2017-11-04 21:38:22.579 [ 8] 0 SpeechSynthesizer:executePlaybackStarted
2017-11-04 21:38:22.580 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2017-11-04 21:38:22.580 [ 8] 9 MediaPlayer:getOffsetCalled
2017-11-04 21:38:22.581 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=1,currentId=1
2017-11-04 21:38:22.582 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#86ec0e8a-36a9-44f0-828f-027d8b42bcc5#Alexa3P:1.0/2017/11/04/21/22de94f9e0744edfb9523559fa1d2138/38:18::TNIH_2V.d7f40674-7f61-4757-ac04-e8223d6bcca5ZXV/1","offsetInMilliseconds":16,"playerActivity":"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState
2017-11-04 21:38:22.583 [ 11] 9 SpeechSynthesizer:onFocusChangedSuccess
2017-11-04 21:38:22.584 [ 8] 0 EventBuilder:buildJsonEventString:messageId=0901e552-cd8e-49b1-9685-ac968bef33f8,namespace=SpeechSynthesizer,name=SpeechStarted
2017-11-04 21:38:22.585 [ 8] 9 HTTP2Transport:enqueueRequest
2017-11-04 21:38:22.585 [ d] 0 DialogUXStateAggregator:setState:from=IDLE,to=SPEAKING
###########################

Speaking...

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

2017-11-04 21:38:22.631 [ b] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-04 21:38:22.631 [ b] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2017-11-04 21:38:22.632 [ b] 9 HTTP2Transport:insertActiveStream:handle=0xb46d69a0
2017-11-04 21:38:22.734 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2017-11-04 21:38:22.735 [ 2] 0 MediaPlayer:callingOnTags
2017-11-04 21:38:22.772 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-7
2017-11-04 21:38:22.772 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2017-11-04 21:38:22.773 [ b] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2017-11-04 21:38:22.773 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2
2017-11-04 21:38:22.871 [ 13] 9 PlaybackController:buttonPressed:Button=PAUSE
2017-11-04 21:38:22.872 [ 14] 9 PlaybackController:buttonPressedExecutor:Button=PAUSE
2017-11-04 21:38:22.873 [ 14] 9 PlaybackController:buttonPressedExecutor::Queue is empty, call getContext().
2017-11-04 21:38:22.873 [ 6] 9 SpeakerManager:provideStateCalled
2017-11-04 21:38:22.874 [ 6] 9 SpeechSynthesizer:provideState:token=3
2017-11-04 21:38:22.874 [ 7] 9 SpeakerManager:executeProvideStateCalled
2017-11-04 21:38:22.874 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:22.875 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=3
2017-11-04 21:38:22.875 [ 8] 9 MediaPlayer:getOffsetCalled
2017-11-04 21:38:22.875 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:22.876 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=1,currentId=1
2017-11-04 21:38:22.878 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#86ec0e8a-36a9-44f0-828f-027d8b42bcc5#Alexa3P:1.0/2017/11/04/21/22de94f9e0744edfb9523559fa1d2138/38:18::TNIH_2V.d7f40674-7f61-4757-ac04-e8223d6bcca5ZXV/1","offsetInMilliseconds":311,"playerActivity":"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState
2017-11-04 21:38:22.879 [ 7] 9 MediaPlayer:getSpeakerSettingsCalled
2017-11-04 21:38:22.879 [ 2] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2017-11-04 21:38:22.880 [ 7] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2017-11-04 21:38:22.880 [ 7] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2017-11-04 21:38:22.880 [ 7] 0 ContextManager:updateStateLocked:action=updatedState,state={"volume":100,"muted":false},namespace=Speaker,name=VolumeState
2017-11-04 21:38:22.882 [ 6] 0 ContextManager:buildContextSuccessful:context={"context":[{"header":{"namespace":"Speaker","name":"VolumeState"},"payload":{"volume":100,"muted":false}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#86ec0e8a-36a9-44f0-828f-027d8b42bcc5#Alexa3P:1.0/2017/11/04/21/22de94f9e0744edfb9523559fa1d2138/38:18::TNIH_2V.d7f40674-7f61-4757-ac04-e8223d6bcca5ZXV/1","offsetInMilliseconds":311,"playerActivity":"PLAYING"}},{"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":[]}}]}
2017-11-04 21:38:23.221 [ 6] 9 PlaybackController:onContextAvailable
2017-11-04 21:38:23.222 [ 14] 9 PlaybackController:onContextAvailableExecutor
2017-11-04 21:38:23.223 [ 14] 0 EventBuilder:buildJsonEventString:messageId=376ec233-f0ed-4e15-80ff-ae6dc17a542e,namespace=PlaybackController,name=PauseCommandIssued
2017-11-04 21:38:23.224 [ 14] 9 HTTP2Transport:enqueueRequest
2017-11-04 21:38:23.277 [ b] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-04 21:38:23.277 [ b] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2017-11-04 21:38:23.278 [ b] 9 HTTP2Transport:insertActiveStream:handle=0xb46d69a0
2017-11-04 21:38:23.546 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-9
2017-11-04 21:38:23.546 [ b] E PlaybackController:messageSentFailed:ButtonPressed=PAUSE,error=SUCCESS_NO_CONTENT
2017-11-04 21:38:23.547 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204
2017-11-04 21:38:23.547 [ b] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
2017-11-04 21:38:23.547 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2017-11-04 21:38:32.021 [ 12] 9 BaseStreamSource:onNeedDataCalled:size=4096
2017-11-04 21:38:32.022 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2017-11-04 21:38:32.069 [ 2] 9 MediaPlayer:messageReceived:messageType=eos
2017-11-04 21:38:32.070 [ 2] 9 BaseStreamSource:~BaseStreamSource
2017-11-04 21:38:32.070 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=1
2017-11-04 21:38:32.070 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=1
2017-11-04 21:38:32.071 [ 8] 0 SpeechSynthesizer:executePlaybackFinished
2017-11-04 21:38:32.071 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2017-11-04 21:38:32.071 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token":"amzn1.as-ct.v1.Domain:Application:Weather#ACRI#86ec0e8a-36a9-44f0-828f-027d8b42bcc5#Alexa3P:1.0/2017/11/04/21/22de94f9e0744edfb9523559fa1d2138/38:18::TNIH_2V.d7f40674-7f61-4757-ac04-e8223d6bcca5ZXV/1","offsetInMilliseconds":0,"playerActivity":"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
2017-11-04 21:38:32.072 [ 8] 9 SpeechSynthesizer:releaseForegroundFocus
2017-11-04 21:38:32.072 [ 8] 1 FocusManager:releaseChannel:channelName=Dialog
2017-11-04 21:38:32.073 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2017-11-04 21:38:32.073 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2017-11-04 21:38:32.076 [ 8] 0 EventBuilder:buildJsonEventString:messageId=4f4ff0db-24e1-4e7d-a667-3c7715814615,namespace=SpeechSynthesizer,name=SpeechFinished
2017-11-04 21:38:32.081 [ 8] 9 HTTP2Transport:enqueueRequest
2017-11-04 21:38:32.081 [ 8] 9 SpeechSynthesizer:setHandlingCompleted
2017-11-04 21:38:32.081 [ 8] 0 DirectiveProcessor:onHandlingCompeted:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25,directiveBeingPreHandled=(nullptr)
2017-11-04 21:38:32.082 [ 8] 0 CapabilityAgent:removingMessageIdFromMap:messageId=0b2322e5-cd70-4ea0-b68f-20b24edf3f25
2017-11-04 21:38:32.098 [ b] 0 HTTP2Transport:processNextOutgoingMessage
2017-11-04 21:38:32.099 [ b] 0 HTTP2StreamPool:getStream:streamId=11,numAcquiredStreams=2
2017-11-04 21:38:32.099 [ b] 9 HTTP2Transport:insertActiveStream:handle=0xb46d69a0
2017-11-04 21:38:32.288 [ d] 0 DialogUXStateAggregator:setState:from=FINISHED,to=IDLE
########################################

Alexa is currently idle!

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

2017-11-04 21:38:32.317 [ b] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid: 060111fffed571dc-00004a1e-00030bb7-f47f0873a91f662b-e6a934ba-11
2017-11-04 21:38:32.317 [ b] 0 HTTP2Transport:cleanupFinishedStream:streamId=11,result=204
2017-11-04 21:38:32.318 [ b] 0 HTTP2StreamPool:releaseStream:streamId=11,numAcquiredStreams=1
2017-11-04 21:38:32.318 [ b] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

[merdahl]

Hi,

I was able to fix the issue by installing the gstreamer1.0-alsa package. I'm guessing RPI must already have this installed.

For others that have audio issues, it is best to back up and test things in stages:

1. verify you have basic functionality (you should hear a constant tone):
   $ gst-launch-0.10 audiotestsrc ! autoaudiosink

2. verify you can play mp3 (using mad decoder from gstreamer1.0-plugins-ugly):
   $ gst-launch-1.0 filesrc location=${HOME}/sdk-folder/application-necessities/sound-files/med_system_alerts_melodic_02.TTH.mp3 ! mpegaudioparse ! mad! autoaudiosink

Once I had these things working, I had audio working in the AVS SDK Demo.

[comdata]

Ok,

I did the following two things:

1. disable the onboard audio:
   Disables/blacklists the onboard audio.
   sudo echo "blacklist snd_bcm2835" > /etc/modprobe.d/alsa-blacklist.conf
   Sets you usb/dac card as the default device.
   sudo echo "options snd-usb-audio index=0" > /etc/modporbe.d/alsa-base.conf

reboot

2. install gstreamer1.0-alsa as mention be @merdahl
   sudo apt install gstreamer1.0-alsa

Issue is solved for me, but seems like something needs to be added to the documentation about the package and maybe something internally fails without being visible to the outside.

[sanjayrd]

Thank you for the valuable information @comdata. We will review what you did and see if we can update the docs as needed.