[Solved] MATRIX Creator Running Alexa authentication errors

Hello

I’d like to ask a question about MATRIX Creator Running Alexa.

I’ve downloaded Alexa image with everything pre-installed to a new SD card,and I configued config.txt file.Then I installed Alexa SDK .All went well.

But after I run bash ./startsample.sh and use the code provided. Clicked “Allow” , it show the errors message. And the led in matrix creator light in blue color.

How to correct this issure?

--------------------------------------------------------------------------------------------------------

2018-06-11 10:54:41.941 [  2] 5 CBLAuthDelegate:receiveTokenResponse:code=200,b                                                ody={"access_token"\:"Atza|IwEBIL4URWcTQySY5PHo9GXABxC0lb0dU7LBQaoNeZZ9BrnB_HMb                                                K3_AIcQxUVOsfCo0jNIrFLjM-7xMoKfQ8VsUolpO-1h-4c460nDRQYXDyew7ZLbBdkZazb4VBgLj1V6                                                K9L355e2P9_yvR5OB74dE9y-ovsx5XsqsQDQGsrQFXW5RRgmQYgzChwhGVdX8JlNhTDuUnZcdQPjSB-                                                cBtTmfcu3XS-pbqaxgO0KGMn6nUOB3R6Qk4Ss4y32qKUi4eEvUO6hANZIjBzdAw90ej06HvlmzaLMbT                                                0iYrOehB19heiWttqtzPomflgzEMXlIjRVHKaTlqmgKFGk9ZVqAGQzK4R4lxlvEpMGhMZfUPllUi72_                                                A6UK-U-yOUDZwj7oUzH1E5g0ddOVKvGJdqIy-ktaIGOIywPV3_z6RbBb5sqghBEyyz5muVk1Pu1EioZ                                                7dSZBBHX17ckl8W_tdRXccW3S4luiULvLooucaXxzLXtagWEBs07E3x-3wkzkFAPdBjvO8wsGLrk1yR                                                rpvkb8JV0i6vGs"\,"refresh_token"\:"Atzr|IwEBIOqddVG3071feJWQuYhk1qbyXoyFEpv_WPK                                                Ce8MZyYmKmU_NmjnVFPeDBb35zS0qy6EtxKizAXbdf-ASsEfCirK9Q9DuuLXu0R5NthsAm2roht_x1u                                                quXwGTXyhzQHdyLnjLii2MRFY-aAX3lOfNKD5MwjVfIN1V2l6yl65b3aL4j_xelI7aiePbGf5ulMRMF                                                dhUxQZ8n1OmPidyNZrGkNX58DUbNDmJitGcHf3K4r1ZSOzG2ZOHsuvOi_Wuc6z9LfQUOSBzA0cvJ8e_                                                f246I3X6tg0QnRlaS7RzgUxUvgWGVEcTyK2W3Wl3hPc9TV98Z64a7mVQVNwbJZ-3HCLpHvG3b1m54LG                                                MAbjPRhAsP0kgBFij-UvigRrOSvF09jRAuTtc8IwTvvJ4teH2qPloZX-BXmWnq2zCgRdi6x8TEGGoyP                                                4XMtWRQR_wNyDLJjAF1YUrtYnZFg8S5TZGsfqs4f__h_680oi9f08vwd--etg7zUESKS1zBWMVMpTAP                                                D_dvyRrcJiuKBhPcaEFZVLi-WCF"\,"token_type"\:"bearer"\,"expires_in"\:3600}
2018-06-11 10:54:41.941 [  2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,e                                                rror=SUCCESS
2018-06-11 10:54:41.942 [  2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
2018-06-11 10:54:41.942 [  2] 5 CBLAuthDelegate:setRefreshToken:refreshToken=At                                                zr|IwEBIOqddVG3071feJWQuYhk1qbyXoyFEpv_WPKCe8MZyYmKmU_NmjnVFPeDBb35zS0qy6EtxKiz                                                AXbdf-ASsEfCirK9Q9DuuLXu0R5NthsAm2roht_x1uquXwGTXyhzQHdyLnjLii2MRFY-aAX3lOfNKD5                                                MwjVfIN1V2l6yl65b3aL4j_xelI7aiePbGf5ulMRMFdhUxQZ8n1OmPidyNZrGkNX58DUbNDmJitGcHf                                                3K4r1ZSOzG2ZOHsuvOi_Wuc6z9LfQUOSBzA0cvJ8e_f246I3X6tg0QnRlaS7RzgUxUvgWGVEcTyK2W3                                                Wl3hPc9TV98Z64a7mVQVNwbJZ-3HCLpHvG3b1m54LGMAbjPRhAsP0kgBFij-UvigRrOSvF09jRAuTtc                                                8IwTvvJ4teH2qPloZX-BXmWnq2zCgRdi6x8TEGGoyP4XMtWRQR_wNyDLJjAF1YUrtYnZFg8S5TZGsfq                                                s4f__h_680oi9f08vwd--etg7zUESKS1zBWMVMpTAPD_dvyRrcJiuKBhPcaEFZVLi-WCF
2018-06-11 10:54:41.943 [  2] 5 SQLiteCBLAuthDelegateStorage:setRefreshToken
2018-06-11 10:54:41.964 [  2] 5 CBLAuthDelegate:handleRefreshingToken
2018-06-11 10:54:41.965 [  2] 5 CBLAuthDelegate:requestRefresh
2018-06-11 10:54:42.273 [  2] 5 HttpPost:doPostSucceeded:code=400
2018-06-11 10:54:42.273 [  2] 5 CBLAuthDelegate:receiveTokenResponse:code=400,b                                                ody={"error_description"\:"The request is missing a required parameter \: clien                                                t_secret"\,"error"\:"invalid_request"}
2018-06-11 10:54:42.273 [  2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=400,e                                                rror=INVALID_REQUEST
2018-06-11 10:54:42.274 [  2] 5 CBLAuthDelegate:errorInLwaResponseBody:error=in                                                valid_request,errorCode=INVALID_REQUEST
2018-06-11 10:54:42.274 [  2] 5 CBLAuthDelegate:setAuthError:authError=INVALID_                                                REQUEST
2018-06-11 10:54:42.274 [  2] 0 CBLAuthDelegate:receiveTokenResponseFailed:resu                                                lt=INVALID_REQUEST
2018-06-11 10:54:42.274 [  2] 5 CBLAuthDelegate:setAuthState:newAuthState=UNREC                                                OVERABLE_ERROR
2018-06-11 10:54:42.274 [  2] 9 CBLAuthDelegate:callingOnAuthStateChange:state=                                                UNRECOVERABLE_ERROR,error=INVALID_REQUEST
2018-06-11 10:54:42.275 [  2] 5 CBLAuthDelegate:handleStopping
2018-06-11 10:54:42.275 [  8] E DCFDelegate:getAuthTokenFailed:reason=Unrecover                                                able error by auth delegate

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

#       UNRECOVERABLE AUTHORIZATION ERROR: INVALID_REQUEST       #

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

pure virtual method called
terminate called without an active exception
./startsample.sh: line 2:   759 Aborted                 ./SampleApp "/home/pi/b                                                uild/Integration/AlexaClientSDKConfig.json" "/home/pi/third-party/alexa-rpi/mod                                                els" DEBUG9

------------------------------------------------------------------------------

Part of getting Alexa working is to create a device on developer.amazon.com. This gets you the clientid and product I’d to place in your json file referenced in the error message.

When you create your device on developer.amazon.com, the client ID you paste in the json is not the one you see plainly on the product I’d tab. You generate it from the security profile tab when you view My products.

Looking at this line of the output log:

2018-06-11 10:54:42.273 [ 2] 5 CBLAuthDelegate:receiveTokenResponse:code=400,body={"error_description"\:"The request is missing a required parameter \: client_secret"\,"error"\:"invalid_request"}

YOu have to be carefull following the registration instructions here. The client id requiere is the one under Other Devices and Platforms tab.
See below:

I’ve followed the registration instructions and generate client ID at “Other devices and platforms”.

After I run “bash ./startsample.sh” this time it showed some message bellow.

ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.front
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.rear
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.center_lfe
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.side
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround40
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround41
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround50
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround51
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround71
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
2018-06-13 07:12:54.225 [  1] I SensoryKeywordDetector:License expires on 28 Sep 2018 00:00:00 GMT
2018-06-13 07:12:54.225 [  1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
                  #    #     #  #####      #####  ######  #    #
                 # #   #     # #     #    #     # #     # #   #
                #   #  #     # #          #       #     # #  #
               #     # #     #  #####      #####  #     # ###
               #######  #   #        #          # #     # #  #
               #     #   # #   #     #    #     # #     # #   #
               #     #    #     #####      #####  ######  #    #

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

       SDK Version 1.7.0



 Wake word:                                                                 |
|       Simply say Alexa and begin your query.                               |
| Tap to talk:                                                               |
|       Press 't' and Enter followed by your query (no need for the 'Alexa').|
| Hold to talk:                                                              |
|       Press 'h' followed by Enter to simulate holding a button.            |
|       Then say your query (no need for the 'Alexa').                       |
|       Press 'h' followed by Enter to simulate releasing a button.          |
| Stop an interaction:                                                       |
|       Press 's' and Enter to stop an ongoing interaction.                  |
| Privacy mode (microphone off):                                             |
|       Press 'm' and Enter to turn on and off the microphone.               |
| Echo Spatial Perception (ESP): This is for testing purpose only!           |
|       Press 'e' followed by Enter at any time to adjust ESP settings.      |
| Playback Controls:                                                         |
|       Press '1' for a 'PLAY' button press.                                 |
|       Press '2' for a 'PAUSE' button press.                                |
|       Press '3' for a 'NEXT' button press.                                 |
|       Press '4' for a 'PREVIOUS' button press.                             |
| Settings:                                                                  |
|       Press 'c' followed by Enter at any time to see the settings screen.  |
| Speaker Control:                                                           |
|       Press 'p' followed by Enter at any time to adjust speaker settings.  |
| Firmware Version:                                                          |
|       Press 'f' followed by Enter at any time to report a different        |
|       firmware version.                                                    |
| Info:                                                                      |
|       Press 'i' followed by Enter at any time to see the help screen.      |
| Reset device:                                                              |
|       Press 'k' followed by Enter at any time to reset your device. This   |
|       will erase any data stored in the device and you will have to        |
|       register your device with another account.                           |
|       This will kill the application since we don't support login yet.     |
| Quit:                                                                      |
|       Press 'q' followed by Enter at any time to quit the application. 

But after I said “Alexa” or tape “t” ,the Matrix light turn green then red then white. I ask “What time is it now?” There is no response. it showed message.

########################################
#       Alexa is currently idle!       #
########################################

2018-06-13 07:57:26.980 [  7] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2018-06-13 07:57:26.980 [ 10] 1 FocusManager:releaseChannel:channelName=Dialog
2018-06-13 07:57:26.980 [ 10] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
2018-06-13 07:57:26.980 [ 18] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-06-13 07:57:26.981 [ 18] 5 AudioActivityTracker:notifyOfActivityUpdates
2018-06-13 07:57:26.981 [  f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
2018-06-13 07:57:26.981 [ 10] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-06-13 07:57:26.981 [ 10] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
2018-06-13 07:57:27.533 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 020fd2fffe9146a0-000008e3-0005fe70-a495bf6becb78a30-be73dde3-5
2018-06-13 07:57:27.533 [ 16] 9 HTTP2Stream:libcurl:streamId=39,text=Curl_http_done\: called premature \=\= 0
2018-06-13 07:57:27.534 [ 16] 0 HTTP2Transport:cleanupFinishedStream:streamId=39,result=204
2018-06-13 07:57:27.534 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=39,numAcquiredStreams=1
2018-06-13 07:57:27.534 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT ,that’s mean there is no content in Alexa?

Do I need configure Alexa to listen the response? How to do that?

I think the error could be here.

Can you share the output of :

sudo dpkg -l | grep matrix
lsmod | grep matrix
cat /etc/asound.conf

Hello ,
the output are
sudo dpkg -l | grep matrix

ii  libmatrixio-creator-hal:armhf         0.3.5                                armhf        Hardware Abstraction Layer for MATRIX.
ii  libmatrixio-malos:armhf               0.1.3                                armhf        Libraries of MATRIX abstraction layer for MOS
ii  libmatrixio-malos-dev                 0.1.3                                armhf        Libraries of MATRIX abstraction layer for MOS
ii  libmatrixio-protos:armhf              0.1.5                                armhf        Protocolbuffers for MATRIX abstraction layer
ii  libmatrixio-protos-dev                0.1.5                                armhf        Protocolbuffers headers for MATRIX abstraction layer
ii  libswresample2:armhf                  7:3.2.10-1~deb9u1+rpt1               armhf        FFmpeg library for audio resampling, rematrixing etc. - runtime files
ii  matrixio-creator-init                 0.4.12                               armhf        Install scripts that can program the MATRIX Creator FPGA and SAM3 IMU.
ii  matrixio-kernel-modules               0.1.4                                armhf        Install kernel modules for MATRIX devices.
ii  matrixio-malos                        0.3.5                                armhf        Allows queries to MATRIX Creator hardware via ZMQ.
ii  matrixio-malos-wakeword               0.1.7                                armhf        Voice commands for MATRIX Creator hardware via ZMQ callback.
ii  matrixio-malos-zigbee                 0.1.6                                armhf        Allows queries to MATRIX Creator hardware via ZMQ.
ii  matrixio-openocd                      1.0.1                                armhf        Open on-chip JTAG debug solution for ARM and MIPS systems
ii  matrixio-pocketsphinx                 0.1.0                                armhf        This is PocketSphinx, one of Carnegie Mellon University's open source large
ii  matrixio-protobuf                     3.5.0                                armhf        Protocol Buffers - Google's data interchange format. (From GRPC 3.5.0)
ii  matrixio-sphinxbase                   0.1.0                                armhf        This is PocketSphinx, one of Carnegie Mellon University's open source large
ii  matrixio-xc3sprog                     1.1.1                                armhf        Spartan3, XCF and CPLD JTAG programmer and other utilities.

lsmod | grep matrix

matrixio_pcm          278528  1
matrixio_codec         16384  1
matrixio_uart          16384  0
snd_soc_core          188416  2 matrixio_pcm,matrixio_codec
matrixio_env           16384  0
matrixio_imu           16384  0
industrialio           73728  2 matrixio_imu,matrixio_env
matrixio_gpio          16384  0
matrixio_everloop      16384  0
matrixio_regmap        61440  1
matrixio_core          16384  6 matrixio_everloop,matrixio_regmap,matrixio_pcm,matrixio_imu,matrixio_uart,matrixio_env
snd_pcm                98304  5 matrixio_pcm,snd_pcm_dmaengine,snd_bcm2835,snd_soc_core,snd_dummy

cat /etc/asound.conf
cat: /etc/asound.conf: No such file or directory

Weird, you should’ve this instead:

$ cat /etc/asound.conf 
pcm.!default {
  type asym
  capture.pcm "mic"
  playback.pcm "speaker"
}

pcm.mic {
   type softvol
   slave {
     pcm "array"
   }
   control {
     name "MicArray Master"
        card 0
   }
}

pcm.speaker {
  type plug
  slave {
    pcm "hw:0,0"
  }
}

pcm.array {
  type plug
  slave {
    pcm "hw:2,0"
  }
}

You can get it here using

wget https://github.com/matrix-io/matrixio-kernel-modules/blob/master/misc/asound.conf
sudo cp ./asound.conf /etc/
cat /etc/asound.conf

Thanks for your answer.

I’ve got asound.conf . And I’ve run command:
gst-launch-1.0 -m audiotestsrc ! autoaudiosink

then
gst-launch-1.0 -m audiotestsrc ! alsasink
I’ve heard “zizi” sound from speaker.
Then I changed

m_pipeline.audioSink = gst_element_factory_make("alsasink", "audio_sink")

Then I run bash ./startsample.sh , the output is

    #####################################
    #       Client not connected!       #
    #####################################

    2018-06-15 08:30:16.272 [ 15] 5 CBLAuthDelegate:removeAuthObserver:observer=0x71514ef8
    2018-06-15 08:30:16.272 [  b] 5 PostConnectSynchronizer:mainLoopReturning
    2018-06-15 08:30:16.273 [  a] 5 PostConnectSynchronizer:setState:from=STOPPING,to=STOPPED
    2018-06-15 08:30:16.274 [  a] 0 HTTP2Transport:cleanupFinishedStream:streamId=3,result=200
    2018-06-15 08:30:16.274 [  a] 0 HTTP2StreamPool:releaseStream:streamId=3,numAcquiredStreams=1
    2018-06-15 08:30:16.275 [  a] 0 PostConnectSynchronizer:~PostConnectSynchronizer
    2018-06-15 08:30:16.275 [  a] 5 PostConnectSynchronizer:stop
    2018-06-15 08:30:16.275 [  a] 5 PostConnectSynchronizer:stopIgnored:reason=alreadyStopped
    2018-06-15 08:30:16.276 [  a] 9 HTTP2Stream:libcurl:streamId=1,text=Curl_http_done\: called premature \=\= 1
    2018-06-15 08:30:16.276 [  a] 9 HTTP2Stream:libcurl:streamId=1,text=Closing connection 0
  PostConnectSynchronizer:setState:from=FETCHING,to=SENDING
    2018-06-15 08:30:16.312 [  c] 0 EventBuilder:buildJsonEventString:messageId=65377358-dbbe-4f9b-8fda-3edb09fba314,namespace=System,name=SynchronizeState
    2018-06-15 08:30:16.314 [  c] 9 HTTP2Transport:enqueueRequest:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"SynchronizeState"\,"messageId"\:"65377358-dbbe-4f9b-8fda-3edb09fba314"}\,"payload"\:{}}}
    2018-06-15 08:30:16.480 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=  Trying 54.239.38.218...
    2018-06-15 08:30:16.480 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TCP_NODELAY set
    2018-06-15 08:30:16.529 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connected to avs-alexa-eu.amazon.com (54.239.38.218) port 443 (#0)
    2018-06-15 08:30:16.529 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, offering h2
    2018-06-15 08:30:16.529 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, offering http/1.1
    2018-06-15 08:30:16.530 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Cipher selection\: ALL\:!EXPORT\:!EXPORT40\:!EXPORT56\:!aNULL\:!LOW\:!RC4\:@STRENGTH
    2018-06-15 08:30:16.584 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=successfully set certificate verify locations\:
    2018-06-15 08:30:16.584 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=  CAfile\: /etc/ssl/certs/ca-certificates.crt
      CApath\: /etc/ssl/certs
    2018-06-15 08:30:16.584 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS header\, Certificate Status (22)\:
    2018-06-15 08:30:16.584 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Client hello (1)\:
    2018-06-15 08:30:16.643 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server hello (2)\:
    2018-06-15 08:30:16.645 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Certificate (11)\:
    2018-06-15 08:30:16.650 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server key exchange (12)\:
    2018-06-15 08:30:16.651 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server finished (14)\:
    2018-06-15 08:30:16.659 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Client key exchange (16)\:
    2018-06-15 08:30:16.659 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS change cipher\, Client hello (1)\:
    2018-06-15 08:30:16.659 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Finished (20)\:
    2018-06-15 08:30:16.714 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS change cipher\, Client hello (1)\:
    2018-06-15 08:30:16.714 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Finished (20)\:
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, server accepted to use h2
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Server certificate\:
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= subject\: C\=US; ST\=Washington; L\=Seattle; O\=Amazon.com\, Inc.; CN\=latinum-eu.amazon.com
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= start date\: Feb 20 00\:00\:00 2018 GMT
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= expire date\: Apr  9 12\:00\:00 2019 GMT
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= subjectAltName\: host "avs-alexa-eu.amazon.com" matched cert's "avs-alexa-eu.amazon.com"
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= issuer\: C\=US; O\=DigiCert Inc; CN\=DigiCert Global CA G2
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= SSL certificate verify ok.
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Using HTTP2\, server supports multi-use
    2018-06-15 08:30:16.715 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connection state changed (HTTP/2 confirmed)
    2018-06-15 08:30:16.716 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Copying HTTP/2 data in stream buffer to connection buffer after upgrade\: len\=0
    2018-06-15 08:30:16.716 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Using Stream ID\: 1 (easy handle 0x72701c90)
    2018-06-15 08:30:16.716 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connection state changed (MAX_CONCURRENT_STREAMS updated)!
    2018-06-15 08:30:16.814 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a9229fffe1ca9d4-000026a9-00034573-aa66ce225d9961e8-10dc2a90-1
    2018-06-15 08:30:16.814 [ 16] 0 HTTP2Transport:processNextOutgoingMessage:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"SynchronizeState"\,"messageId"\:"65377358-dbbe-4f9b-8fda-3edb09fba314"}\,"payload"\:{}}},uriPathExtension=
    2018-06-15 08:30:16.815 [ 16] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Transport:insertActiveStream:handle=0x727e09c0
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found bundle for host avs-alexa-eu.amazon.com\: 0x7270b338 [can multiplex]
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Conn\: 0 (0x7270a9d0) Receive pipe weight\: (-1/0)\, penalized\: (nil)
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Multiplexed connection found!
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found connection 0\, with requests in the pipe (1)
    2018-06-15 08:30:16.815 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Re-using existing connection! (#0) with host avs-alexa-eu.amazon.com
    2018-06-15 08:30:16.816 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Using Stream ID\: 3 (easy handle 0x727e09c0)
    2018-06-15 08:30:17.793 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a9229fffe1ca9d4-000026a9-000301e7-749f6957a57cf49c-9160f1d6-151
    2018-06-15 08:30:17.793 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Curl_http_done\: called premature \=\= 0
    2018-06-15 08:30:17.793 [ 16] 5 PostConnectSynchronizer:onSendCompleted:status=SUCCESS_NO_CONTENT
    2018-06-15 08:30:17.793 [ 16] 5 PostConnectSynchronizer:stop
    2018-06-15 08:30:17.793 [ 16] 5 PostConnectSynchronizer:setState:from=SENDING,to=STOPPING
    2018-06-15 08:30:17.794 [ 17] 5 PostConnectSynchronizer:mainLoopReturning
    2018-06-15 08:30:17.794 [ 16] 5 PostConnectSynchronizer:setState:from=STOPPING,to=STOPPED
    2018-06-15 08:30:17.794 [ 16] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
    2018-06-15 08:30:17.794 [ 16] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
    2018-06-15 08:30:17.795 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
    2018-06-15 08:30:17.795 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
    2018-06-15 08:30:17.795 [ 16] 0 PostConnectSynchronizer:~PostConnectSynchronizer
    2018-06-15 08:30:17.795 [ 16] 5 PostConnectSynchronizer:stop
    2018-06-15 08:30:17.795 [ 16] 5 PostConnectSynchronizer:stopIgnored:reason=alreadyStopped
    2018-06-15 08:30:17.795 [  5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
    ########################################
    #       Alexa is currently idle!       #
    ########################################

    2018-06-15 08:30:22.298 [ 11] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
    2018-06-15 08:30:22.299 [ 11] 9 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" \: "alexa"},namespace=SpeechRecognizer,name=RecognizerState
    2018-06-15 08:30:22.299 [  7] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
    2018-06-15 08:30:22.300 [  7] 5 TemplateRuntime:onDialogUXStateChanged:state=LISTENING
    ############################
    #       Listening...       #
    ############################

    2018-06-15 08:30:22.302 [  c] 5 VisualActivityTracker:provideState
    2018-06-15 08:30:22.303 [  d] 5 VisualActivityTracker:executeProvideState
    2018-06-15 08:30:22.303 [  d] 9 ContextManager:updateStateLocked:action=updatedState,state=,namespace=VisualActivityTracker,name=ActivityState
    2018-06-15 08:30:22.303 [  e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
    2018-06-15 08:30:22.304 [  c] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=3
    2018-06-15 08:30:22.304 [  e] 9 ContextManager:updateStateLocked:action=updatedState,state={"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]},namespace=Alexa.PlaybackStateReporter,name=playbackState
    2018-06-15 08:30:22.304 [  e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
    2018-06-15 08:30:22.304 [  e] 9 ContextManager:updateStateLocked:action=updatedState,state={"playerInFocus"\:""\,"players"\:[]},namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
    2018-06-15 08:30:22.305 [  c] 5 AudioActivityTracker:provideState
    2018-06-15 08:30:22.305 [  6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=3
    2018-06-15 08:30:22.305 [  f] 5 AudioActivityTracker:executeProvideState
    2018-06-15 08:30:22.305 [  f] 9 ContextManager:updateStateLocked:action=updatedState,state=,namespace=AudioActivityTracker,name=ActivityState
    2018-06-15 08:30:22.305 [  6] 9 ContextManager:updateStateLocked:action=updatedState,state={"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false},namespace=Notifications,name=IndicatorState
    2018-06-15 08:30:22.306 [  c] 9 ContextManager:buildContextIgnored:namespace=VisualActivityTracker,name=ActivityState
    2018-06-15 08:30:22.307 [  c] 9 ContextManager:buildContextIgnored:namespace=AudioActivityTracker,name=ActivityState
    2018-06-15 08:30:22.309 [  c] 5 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]}
    2018-06-15 08:30:22.310 [ 11] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]}
    2018-06-15 08:30:22.311 [ 11] 1 FocusManager:acquireChannel:channelName=Dialog,interface=SpeechRecognizer
    2018-06-15 08:30:22.313 [ 18] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
    2018-06-15 08:30:22.315 [ 18] 5 AudioActivityTracker:notifyOfActivityUpdates
    2018-06-15 08:30:22.317 [  f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
    2018-06-15 08:30:22.318 [ 11] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=2badbfc7-61b0-494a-8912-050cbb74d0fc
    2018-06-15 08:30:22.319 [ 11] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
    2018-06-15 08:30:22.320 [ 11] 0 EventBuilder:buildJsonEventString:messageId=c388e6a5-ca3d-420e-a39d-a91c59696836,namespace=SpeechRecognizer,name=Recognize
    2018-06-15 08:30:22.323 [ 11] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
    2018-06-15 08:30:22.324 [ 11] 9 HTTP2Transport:enqueueRequest:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"Recognize"\,"messageId"\:"c388e6a5-ca3d-420e-a39d-a91c59696836"\,"dialogRequestId"\:"2badbfc7-61b0-494a-8912-050cbb74d0fc"}\,"payload"\:{"profile"\:"NEAR_FIELD"\,"format"\:"AUDIO_L16_RATE_16000_CHANNELS_1"\,"initiator"\:{"type"\:"WAKEWORD"\,"payload"\:{"wakeWordIndices"\:{"startIndexInSamples"\:8000\,"endIndexInSamples"\:16880}}}}}}
    2018-06-15 08:30:22.407 [ 16] 0 HTTP2Transport:processNextOutgoingMessage:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"Recognize"\,"messageId"\:"c388e6a5-ca3d-420e-a39d-a91c59696836"\,"dialogRequestId"\:"2badbfc7-61b0-494a-8912-050cbb74d0fc"}\,"payload"\:{"profile"\:"NEAR_FIELD"\,"format"\:"AUDIO_L16_RATE_16000_CHANNELS_1"\,"initiator"\:{"type"\:"WAKEWORD"\,"payload"\:{"wakeWordIndices"\:{"startIndexInSamples"\:8000\,"endIndexInSamples"\:16880}}}}}},uriPathExtension=
    2018-06-15 08:30:22.408 [ 16] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
    2018-06-15 08:30:22.409 [ 16] 9 HTTP2Transport:insertActiveStream:handle=0x727e09c0
    2018-06-15 08:30:22.409 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Found bundle for host avs-alexa-eu.amazon.com\: 0x7270b338 [can multiplex]
    2018-06-15 08:30:22.409 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Conn\: 0 (0x7270a9d0) Receive pipe weight\: (-1/0)\, penalized\: (nil)
    2018-06-15 08:30:22.409 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Multiplexed connection found!
    2018-06-15 08:30:22.409 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Found connection 0\, with requests in the pipe (1)
    2018-06-15 08:30:22.410 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Re-using existing connection! (#0) with host avs-alexa-eu.amazon.com
    2018-06-15 08:30:22.410 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Using Stream ID\: 5 (easy handle 0x727e09c0)
    2018-06-15 08:30:29.150 [ 13] I DirectiveSequencer:onDirective:directive={"namespace\:"SpeechRecognizer"\,name\:"StopCapture"\,messageId\:"a8be276d-e5f4-4c67-880b-b6db7ef9b0cb"\,dialogRequestId\:"2badbfc7-61b0-494a-8912-050cbb74d0fc"}
    2018-06-15 08:30:29.151 [ 14] I DirectiveRouter:preHandleDirective:messageId=a8be276d-e5f4-4c67-880b-b6db7ef9b0cb,action=calling
    2018-06-15 08:30:29.151 [ 14] 0 CapabilityAgent:addingMessageIdToMap:messageId=a8be276d-e5f4-4c67-880b-b6db7ef9b0cb
    2018-06-15 08:30:29.152 [ 15] I DirectiveRouter:handleDirective:messageId=a8be276d-e5f4-4c67-880b-b6db7ef9b0cb,action=calling
    2018-06-15 08:30:29.153 [ 11] 0 AudioInputProcessor:stopCapture:stopImmediately=true
    2018-06-15 08:30:29.153 [ 11] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
    2018-06-15 08:30:29.153 [ 11] 0 DirectiveProcessor:onHandlingCompeted:messageId=a8be276d-e5f4-4c67-880b-b6db7ef9b0cb,directiveBeingPreHandled=(nullptr)
    2018-06-15 08:30:29.154 [ 11] 0 CapabilityAgent:removingMessageIdFromMap:messageId=a8be276d-e5f4-4c67-880b-b6db7ef9b0cb
    2018-06-15 08:30:29.154 [  7] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
    2018-06-15 08:30:29.156 [  7] 5 TemplateRuntime:onDialogUXStateChanged:state=THINKING
    ###########################
    #       Thinking...       #
    ###########################

    2018-06-15 08:30:29.160 [ 16] I InProcessAttachmentReader:readFailed:reason=SDS is closed
    2018-06-15 08:30:29.292 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a9229fffe1ca9d4-000026a9-000304e8-94e1f1534affe1ad-d3c422a1-27
    2018-06-15 08:30:29.292 [ 16] 9 HTTP2Stream:libcurl:streamId=9,text=Curl_http_done\: called premature \=\= 0
    2018-06-15 08:30:29.293 [ 16] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS_NO_CONTENT
    2018-06-15 08:30:29.293 [ 16] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=204
    2018-06-15 08:30:29.293 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
    2018-06-15 08:30:29.293 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
    2018-06-15 08:30:34.163 [  7] 0 DialogUXStateAggregator:transitionFromThinkingTimedOut
    2018-06-15 08:30:34.163 [  7] 0 DialogUXStateAggregator:setState:from=THINKING,to=IDLE
    2018-06-15 08:30:34.164 [  7] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
    ########################################
    #       Alexa is currently idle!       #
    ########################################

    2018-06-15 08:30:34.165 [ 11] 1 FocusManager:releaseChannel:channelName=Dialog
    2018-06-15 08:30:34.166 [ 18] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
    2018-06-15 08:30:34.166 [ 18] 5 AudioActivityTracker:notifyOfActivityUpdates
    2018-06-15 08:30:34.166 [  f] 5 AudioActivityTracker:executeNotifyOfActivityUpdates
    2018-06-15 08:30:34.167 [ 11] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
    2018-06-15 08:30:34.167 [ 11] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
    2018-06-15 08:30:34.167 [ 11] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
    2018-06-15 08:35:14.264 [  3] 5 InternetConnectionMonitor:testConnection
    2018-06-15 08:35:15.011 [  3] I InProcessAttachmentReader:readFailed:reason=SDS is closed
    2018-06-15 08:35:15.011 [  3] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
    2018-06-15 08:35:29.330 [ 16] 0 HTTP2Transport:sendPing:pingStream=0
    2018-06-15 08:35:29.330 [ 16] 0 HTTP2StreamPool:getStream:streamId=11,numAcquiredStreams=2
    2018-06-15 08:35:29.330 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Found bundle for host avs-alexa-eu.amazon.com\: 0x7270b338 [can multiplex]
    2018-06-15 08:35:29.331 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Conn\: 0 (0x7270a9d0) Receive pipe weight\: (-1/0)\, penalized\: (nil)
    2018-06-15 08:35:29.331 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Multiplexed connection found!
    2018-06-15 08:35:29.331 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Found connection 0\, with requests in the pipe (1)
    2018-06-15 08:35:29.331 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Re-using existing connection! (#0) with host avs-alexa-eu.amazon.com
    2018-06-15 08:35:29.331 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Using Stream ID\: 7 (easy handle 0x727e09c0)
    2018-06-15 08:35:29.407 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a9229fffe1ca9d4-000026a9-00034573-aa66ce225d9961e8-10dc2a90-7
    2018-06-15 08:35:29.408 [ 16] 9 HTTP2Stream:libcurl:streamId=11,text=Curl_http_done\: called premature \=\= 0
    2018-06-15 08:35:29.408 [ 16] 0 HTTP2Transport:handlePingResponse
    2018-06-15 08:35:29.408 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=11,numAcquiredStreams=1
    2018-06-15 08:35:29.408 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT

After “listing”,“thinking”,There is no “speaking” title output.I said ALEXA, the led turned green color but hear nothing.

Then I retyped bash ./startsample.sh

2018-06-15 08:52:02.455 [ 16] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Transport:insertActiveStream:handle=0x727dfd98
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found bundle for host avs-alexa-eu.amazon.com\: 0x7270b238 [can multiplex]
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Conn\: 0 (0x7270a758) Receive pipe weight\: (-1/0)\, penalized\: (nil)
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Multiplexed connection found!
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found connection 0\, with requests in the pipe (1)
2018-06-15 08:52:02.455 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Re-using existing connection! (#0) with host avs-alexa-eu.amazon.com
2018-06-15 08:52:02.456 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Using Stream ID\: 3 (easy handle 0x727dfd98)
2018-06-15 08:52:03.559 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0a72b2fffe01f802-000067d2-0002e1f7-8718e1ddf3d06d59-43159036-19
2018-06-15 08:52:03.559 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Curl_http_done\: called premature \=\= 0
2018-06-15 08:52:03.559 [ 16] 5 PostConnectSynchronizer:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-06-15 08:52:03.559 [ 16] 5 PostConnectSynchronizer:stop
2018-06-15 08:52:03.559 [ 16] 5 PostConnectSynchronizer:setState:from=SENDING,to=STOPPING
2018-06-15 08:52:03.559 [ 17] 5 PostConnectSynchronizer:mainLoopReturning
2018-06-15 08:52:03.560 [ 16] 5 PostConnectSynchronizer:setState:from=STOPPING,to=STOPPED
2018-06-15 08:52:03.560 [ 16] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2018-06-15 08:52:03.560 [ 16] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2018-06-15 08:52:03.560 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2018-06-15 08:52:03.560 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-06-15 08:52:03.560 [ 16] 0 PostConnectSynchronizer:~PostConnectSynchronizer
2018-06-15 08:52:03.560 [ 16] 5 PostConnectSynchronizer:stop
2018-06-15 08:52:03.560 [ 16] 5 PostConnectSynchronizer:stopIgnored:reason=alreadyStopped
2018-06-15 08:52:03.561 [  5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
########################################
#       Alexa is currently idle!       #
########################################

The light didn’t change color. Still hear nothing.

Thank you for further answer.

Maybe the problem is the "Client not connected! " ? I’ve put the right CLIENT_ID and Product_Name .

For Allowed origins I set http://localhost:3000 ,https://localhost:3000
Allowed return URLs set
http://localhost:3000/authresponse

https://localhost:3000/authresponse

But in my browser local, I couldn’t open site with http://localhost:3000

The output of Client not connected! like this

#####################################
#       Client not connected!       #
#####################################

2018-06-15 09:19:41.257 [  a] 9 HTTP2Stream:libcurl:streamId=1,text=Curl_http_done\: called premature \=\= 1
2018-06-15 09:19:41.257 [  a] 9 HTTP2Stream:libcurl:streamId=1,text=Closing connection 0
2018-06-15 09:19:41.258 [  a] 9 HTTP2Stream:libcurl:streamId=1,text=TLSv1.2 (OUT)\, TLS alert\, Client hello (1)\:
2018-06-15 09:19:41.269 [  a] 0 HTTP2StreamPool:releaseStream:streamId=1,numAcquiredStreams=0
2018-06-15 09:19:41.275 [ 15] 5 CBLAuthDelegate:addAuthObserver:observer=0x71501838
2018-06-15 09:19:41.276 [ 16] 0 PostConnectSynchronizer:doPostConnect
2018-06-15 09:19:41.276 [ 16] 5 PostConnectSynchronizer:setState:from=IDLE,to=RUNNING
2018-06-15 09:19:41.276 [ 15] 0 DirectiveProcessor:onHandlingCompeted:messageId=918bd154-7a88-48fc-a55a-fe4b755272f6,directiveBeingPreHandled=(nullptr)
2018-06-15 09:19:41.277 [ 16] 3 HTTP2Transport:setupDownchannelStream:url=https\://avs-alexa-eu.amazon.com/v20160207/directives
2018-06-15 09:19:41.277 [ 15] 0 CapabilityAgent:removingMessageIdFromMap:messageId=918bd154-7a88-48fc-a55a-fe4b755272f6
2018-06-15 09:19:41.277 [ 16] 0 HTTP2StreamPool:getStream:streamId=5,numAcquiredStreams=1
2018-06-15 09:19:41.278 [ 17] 5 PostConnectSynchronizer:mainLoop
2018-06-15 09:19:41.278 [ 17] 5 PostConnectSynchronizer:setState:from=RUNNING,to=FETCHING
2018-06-15 09:19:41.279 [  c] 5 VisualActivityTracker:provideState
2018-06-15 09:19:41.279 [  d] 5 VisualActivityTracker:executeProvideState
2018-06-15 09:19:41.279 [  e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-15 09:19:41.279 [  c] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=2
2018-06-15 09:19:41.280 [  c] 5 AudioActivityTracker:provideState
2018-06-15 09:19:41.280 [  6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-15 09:19:41.280 [ 12] 5 AudioActivityTracker:executeProvideState
2018-06-15 09:19:41.280 [ 12] 9 ContextManager:updateStateLocked:action=updatedState,state=,namespace=AudioActivityTracker,name=ActivityState
2018-06-15 09:19:41.281 [  d] 9 ContextManager:updateStateLocked:action=updatedState,state=,namespace=VisualActivityTracker,name=ActivityState
2018-06-15 09:19:41.282 [  e] 9 ContextManager:updateStateLocked:action=updatedState,state={"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]},namespace=Alexa.PlaybackStateReporter,name=playbackState
2018-06-15 09:19:41.282 [  6] 9 ContextManager:updateStateLocked:action=updatedState,state={"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false},namespace=Notifications,name=IndicatorState
2018-06-15 09:19:41.283 [  e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2018-06-15 09:19:41.284 [  e] 9 ContextManager:updateStateLocked:action=updatedState,state={"playerInFocus"\:""\,"players"\:[]},namespace=ExternalMediaPlayer,name=ExternalMediaPlayerState
2018-06-15 09:19:41.284 [  c] 9 ContextManager:buildContextIgnored:namespace=VisualActivityTracker,name=ActivityState
2018-06-15 09:19:41.285 [  c] 9 ContextManager:buildContextIgnored:namespace=AudioActivityTracker,name=ActivityState
2018-06-15 09:19:41.287 [  c] 5 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]}
2018-06-15 09:19:41.287 [  c] 5 PostConnectSynchronizer:onContectAvailable:context={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]}
2018-06-15 09:19:41.288 [  c] 5 PostConnectSynchronizer:setState:from=FETCHING,to=SENDING
2018-06-15 09:19:41.289 [  c] 0 EventBuilder:buildJsonEventString:messageId=a55dff5f-55b3-4fea-b9d7-b4a523c9bf3b,namespace=System,name=SynchronizeState
2018-06-15 09:19:41.291 [  c] 9 HTTP2Transport:enqueueRequest:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"SynchronizeState"\,"messageId"\:"a55dff5f-55b3-4fea-b9d7-b4a523c9bf3b"}\,"payload"\:{}}}
2018-06-15 09:19:41.469 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=  Trying 54.239.39.74...
2018-06-15 09:19:41.469 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TCP_NODELAY set
2018-06-15 09:19:41.625 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connected to avs-alexa-eu.amazon.com (54.239.39.74) port 443 (#0)
2018-06-15 09:19:41.625 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, offering h2
2018-06-15 09:19:41.625 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, offering http/1.1
2018-06-15 09:19:41.625 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Cipher selection\: ALL\:!EXPORT\:!EXPORT40\:!EXPORT56\:!aNULL\:!LOW\:!RC4\:@STRENGTH
2018-06-15 09:19:41.676 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=successfully set certificate verify locations\:
2018-06-15 09:19:41.677 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=  CAfile\: /etc/ssl/certs/ca-certificates.crt
  CApath\: /etc/ssl/certs
2018-06-15 09:19:41.677 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS header\, Certificate Status (22)\:
2018-06-15 09:19:41.677 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Client hello (1)\:
2018-06-15 09:19:41.835 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server hello (2)\:
2018-06-15 09:19:41.839 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Certificate (11)\:
2018-06-15 09:19:41.844 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server key exchange (12)\:
2018-06-15 09:19:41.845 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Server finished (14)\:
2018-06-15 09:19:41.853 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Client key exchange (16)\:
2018-06-15 09:19:41.853 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS change cipher\, Client hello (1)\:
2018-06-15 09:19:41.853 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (OUT)\, TLS handshake\, Finished (20)\:
2018-06-15 09:19:42.008 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS change cipher\, Client hello (1)\:
2018-06-15 09:19:42.008 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=TLSv1.2 (IN)\, TLS handshake\, Finished (20)\:
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=SSL connection using TLSv1.2 / ECDHE-RSA-AES256-GCM-SHA384
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=ALPN\, server accepted to use h2
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Server certificate\:
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= subject\: C\=US; ST\=Washington; L\=Seattle; O\=Amazon.com\, Inc.; CN\=latinum-eu.amazon.com
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= start date\: Feb 20 00\:00\:00 2018 GMT
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= expire date\: Apr  9 12\:00\:00 2019 GMT
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= subjectAltName\: host "avs-alexa-eu.amazon.com" matched cert's "avs-alexa-eu.amazon.com"
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= issuer\: C\=US; O\=DigiCert Inc; CN\=DigiCert Global CA G2
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text= SSL certificate verify ok.
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Using HTTP2\, server supports multi-use
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connection state changed (HTTP/2 confirmed)
2018-06-15 09:19:42.009 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Copying HTTP/2 data in stream buffer to connection buffer after upgrade\: len\=0
2018-06-15 09:19:42.010 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Using Stream ID\: 1 (easy handle 0x72801c60)
2018-06-15 09:19:42.010 [ 16] 9 HTTP2Stream:libcurl:streamId=5,text=Connection state changed (MAX_CONCURRENT_STREAMS updated)!
2018-06-15 09:19:42.152 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 068019fffec8c0bc-00003842-00024028-f730d4fd0354ce7e-8de44137-1
2018-06-15 09:19:42.153 [ 16] 0 HTTP2Transport:processNextOutgoingMessage:jsonContent={"context"\:[{"header"\:{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"}\,"payload"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"uncertaintyInMilliseconds"\:0\,"players"\:[]}}\,{"header"\:{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"}\,"payload"\:{"playerInFocus"\:""\,"players"\:[]}}\,{"header"\:{"namespace"\:"Notifications"\,"name"\:"IndicatorState"}\,"payload"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}}\,{"header"\:{"namespace"\:"Speaker"\,"name"\:"VolumeState"}\,"payload"\:{"volume"\:100\,"muted"\:false}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:""}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}]\,"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"SynchronizeState"\,"messageId"\:"a55dff5f-55b3-4fea-b9d7-b4a523c9bf3b"}\,"payload"\:{}}},uriPathExtension=
2018-06-15 09:19:42.153 [ 16] 0 HTTP2StreamPool:getStream:streamId=7,numAcquiredStreams=2
2018-06-15 09:19:42.153 [ 16] 9 HTTP2Transport:insertActiveStream:handle=0x728e05b8
2018-06-15 09:19:42.154 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found bundle for host avs-alexa-eu.amazon.com\: 0x72801910 [can multiplex]
2018-06-15 09:19:42.154 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Conn\: 0 (0x7280a740) Receive pipe weight\: (-1/0)\, penalized\: (nil)
2018-06-15 09:19:42.154 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Multiplexed connection found!
2018-06-15 09:19:42.154 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Found connection 0\, with requests in the pipe (1)
2018-06-15 09:19:42.154 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Re-using existing connection! (#0) with host avs-alexa-eu.amazon.com
2018-06-15 09:19:42.155 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Using Stream ID\: 3 (easy handle 0x728e05b8)
2018-06-15 09:19:43.284 [ 16] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 068019fffec8c0bc-00003842-00021a0e-85b19aba9e32876c-1c9b34dc-9
2018-06-15 09:19:43.284 [ 16] 9 HTTP2Stream:libcurl:streamId=7,text=Curl_http_done\: called premature \=\= 0
2018-06-15 09:19:43.284 [ 16] 5 PostConnectSynchronizer:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-06-15 09:19:43.285 [ 16] 5 PostConnectSynchronizer:stop
2018-06-15 09:19:43.285 [ 16] 5 PostConnectSynchronizer:setState:from=SENDING,to=STOPPING
2018-06-15 09:19:43.285 [ 17] 5 PostConnectSynchronizer:mainLoopReturning
2018-06-15 09:19:43.285 [ 16] 5 PostConnectSynchronizer:setState:from=STOPPING,to=STOPPED
2018-06-15 09:19:43.285 [ 16] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2018-06-15 09:19:43.286 [ 16] 0 HTTP2Transport:cleanupFinishedStream:streamId=7,result=204
2018-06-15 09:19:43.286 [ 16] 0 HTTP2StreamPool:releaseStream:streamId=7,numAcquiredStreams=1
2018-06-15 09:19:43.286 [ 16] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-06-15 09:19:43.286 [ 16] 0 PostConnectSynchronizer:~PostConnectSynchronizer
########################################
#       Alexa is currently idle!       #
########################################

In the new way to setup the authentication I dont remember having to put allowed url’s. Are you following this guide for creating the sec profile ? Create Security Profile · alexa/avs-device-sdk Wiki · GitHub.

You can also try to delete the db files in the db/ folder, see here:

-Yoel

First, check that your Raspberry PI is actually connected successfully to the internet.

I have a similar problem with the authorization and maybe I am to stupid to understand the instructions:

Now, I am confused regarding the IDs for the config.txt:

1. Which is the CLIENT_ID? (1,2, or 3) --> I guess: 3
2. Which is the PRODUCT_ID? (A,B, C or D)? --> I guess: D


Therefore I’ve used:

Thanks for your help.

The official guide form Amazon is https://github.com/alexa/avs-device-sdk/wiki/Create-Security-Profile.

But I think you should use:

  • Client ID: 3
  • Product ID: C

I have used the instruction from your link already and it looks everything is correct on Amazon side. Then I have used “3” and “C” as well, but the result was still:

error_log

I have found this solution: https://github.com/alexa/avs-device-sdk/issues/674

  1. Remove the CBL database file you are using. This will let the SDK prepare a fresh, uncorrupted database file.
    –> I guess it means those files from /home/pi/db --> cblAuthDelegate.db?

I have tested with CLIENT_ID = Example “3” (it looks that this is the corrct one) and varied PRODUCT_ID with example “C”, “D”, and “B”

After each changing of the config.txt file I made:

  1. Clear ALL files in /home/pi/db
  2. bash ./setup.sh ./config.txt`
    
  3. sudo reboot
    
  4. bash ./startsample.sh
    

–> Rasperry Pi is connect to the internet

Nothing helped, still the same problem from picture above

Maybe the problem could be, that I have never seen the “NOT YET AUTHORIZED” message from Step 6 in https://www.hackster.io/matrix-labs/matrix-voice-and-matrix-creator-running-alexa-c-version-9b9d8d ?

So, you never got the a message similar to the one below?

############################################################################################
#     To authorize, browse to: 'https://amazon.com/us/code' and enter the code: {XXXX}     #
############################################################################################

Can you share the full output ?

-Yoel

That’s correct, I never got this message.

Here the code (anonymized) after: bash ./startsample.sh

pi@raspberrypi:~ $ bash ./startsample.sh
2018-08-03 06:30:09.087 [  1] I sdkVersion: 1.7.0
configFile /home/pi/build/Integration/AlexaClientSDKConfig.json
Running app with log level: DEBUG9
2018-08-03 06:30:09.340 [  1] 0 ConfigurationNode:initializeSuccess:configuration={"alertsCapabilityAgent"\:{"databaseFilePath"\:"/home/pi/db/alerts.db"}\,"certifiedSender"\:{"databaseFilePath"\:"/home/pi/db/certifiedSender.db"}\,"settings"\:{"databaseFilePath"\:"/home/pi/db/settings.db"\,"defaultAVSClientSettings"\:{"locale"\:"en-US"}}\,"notifications"\:{"databaseFilePath"\:"/home/pi/db/notifications.db"}\,"cblAuthDelegate"\:{"databaseFilePath"\:"/home/pi/db/cblAuthDelegate.db"}\,"deviceInfo"\:{"clientId"\:"Beli"\,"deviceSerialNumber"\:"123456"\,"productId"\:"Xxx Matrix Alexa Voice"}\,"dcfDelegate"\:{}\,"miscDatabase"\:{"databaseFilePath"\:"/home/pi/db/miscDatabase.db"}\,"gstreamerMediaPlayer"\:{"audioSink"\:"autoaudiosink"}}
2018-08-03 06:30:09.358 [  1] 9 MediaPlayer:createCalled
2018-08-03 06:30:09.612 [  1] 9 MediaPlayer:noOutputConversion	
2018-08-03 06:30:09.614 [  1] 9 MediaPlayer:createCalled
2018-08-03 06:30:09.615 [  1] 9 MediaPlayer:noOutputConversion
2018-08-03 06:30:09.617 [  1] 9 MediaPlayer:createCalled
2018-08-03 06:30:09.618 [  1] 9 MediaPlayer:noOutputConversion
2018-08-03 06:30:09.620 [  1] 9 MediaPlayer:createCalled
2018-08-03 06:30:09.621 [  1] 9 MediaPlayer:noOutputConversion
2018-08-03 06:30:09.625 [  1] 9 MediaPlayer:createCalled
2018-08-03 06:30:09.626 [  1] 9 MediaPlayer:noOutputConversion
2018-08-03 06:30:09.631 [  1] 5 CBLAuthDelegate:create
2018-08-03 06:30:09.632 [  1] 5 CBLAuthDelegate:CBLAuthDelegate
2018-08-03 06:30:09.632 [  1] 5 CBLAuthDelegate:init
2018-08-03 06:30:09.632 [  1] 5 CBLAuthDelegateConfiguration:create
2018-08-03 06:30:09.632 [  1] 5 CBLAuthDelegateConfiguration:init
2018-08-03 06:30:09.633 [  1] 5 CBLAuthDelegateConfiguration:initScopeData
2018-08-03 06:30:09.633 [  1] 9 CBLAuthDelegateConfiguration:initScopeDataSucceeded:scopeData={"alexa\:all"\:{"productID"\:"Xxx Matrix Alexa Voice"\,"productInstanceAttributes"\:{"deviceSerialNumber"\:"123456"}}}
2018-08-03 06:30:09.633 [  1] 5 SQLiteCBLAuthDelegateStorage:open
2018-08-03 06:30:09.674 [  2] 5 CBLAuthDelegate:handleAuthorizationFlow
2018-08-03 06:30:09.674 [  2] 5 CBLAuthDelegate:handleStarting
2018-08-03 06:30:09.674 [  2] 5 SQLiteCBLAuthDelegateStorage:getRefreshToken
2018-08-03 06:30:09.674 [  2] 0 SQLiteCBLAuthDelegateStorage:getRefreshTokenFailed:reason=stepResultWasNotRow
2018-08-03 06:30:09.675 [  2] I CBLAuthDelegate:getRefreshTokenFailed
2018-08-03 06:30:09.675 [  2] 5 CBLAuthDelegate:handleRequestingCodePair
2018-08-03 06:30:09.675 [  2] 5 CBLAuthDelegate:requestCodePair
2018-08-03 06:30:09.677 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0xab6f4c
2018-08-03 06:30:09.678 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0xa9ff38
2018-08-03 06:30:09.683 [  1] 5 DCFDelegate:addDCFObserver:observer=0xa9ff3c
2018-08-03 06:30:09.688 [  1] 5 InternetConnectionMonitor:startMonitoring
2018-08-03 06:30:09.699 [  3] 5 InternetConnectionMonitor:testConnection
2018-08-03 06:30:09.740 [  1] 9 MediaPlayer:setObserverCalled
2018-08-03 06:30:09.741 [  4] 0 MediaPlayer:handleSetObserverCalled
2018-08-03 06:30:09.742 [  1] 9 SpeechSynthesizer:addObserver:observer=0xabb3a4
2018-08-03 06:30:09.743 [  1] 9 PlaybackRouter:create::called
2018-08-03 06:30:09.745 [  1] 9 MediaPlayer:setObserverCalled
2018-08-03 06:30:09.746 [  4] 0 MediaPlayer:handleSetObserverCalled
2018-08-03 06:30:09.746 [  1] 9 MediaPlayer:setObserverCalled
2018-08-03 06:30:09.746 [  4] 0 MediaPlayer:handleSetObserverCalled
2018-08-03 06:30:09.753 [  1] 9 AlertScheduler:setTimerForNextAlertLocked
2018-08-03 06:30:09.753 [  1] 9 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2018-08-03 06:30:09.756 [  5] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2018-08-03 06:30:09.756 [  1] 5 NotificationsRenderer:create
2018-08-03 06:30:09.756 [  1] 9 MediaPlayer:setObserverCalled
2018-08-03 06:30:09.756 [  4] 0 MediaPlayer:handleSetObserverCalled
2018-08-03 06:30:09.758 [  1] 5 NotificationsCapabilityAgent:init
2018-08-03 06:30:09.758 [  1] 5 NotificationsRenderer:addObserver
2018-08-03 06:30:09.761 [  6] 5 NotificationsCapabilityAgent:executeInit
2018-08-03 06:30:09.762 [  6] 5 NotificationsCapabilityAgent:notifyObservers:indicatorState=0
2018-08-03 06:30:09.763 [  6] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0
2018-08-03 06:30:09.764 [  6] 9 ContextManager:updateStateLocked:action=updatedState,state={"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false},namespace=Notifications,name=IndicatorState
2018-08-03 06:30:09.770 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-08-03 06:30:09.771 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-08-03 06:30:09.771 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-08-03 06:30:09.771 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-08-03 06:30:09.771 [  1] 9 MediaPlayer:getSpeakerTypeCalled
2018-08-03 06:30:09.771 [  1] 0 SpeakerManager:mapCreated:numAvsSynced=4,numLocal=1
2018-08-03 06:30:09.772 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-08-03 06:30:09.772 [  4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-08-03 06:30:09.773 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  1] 9 MediaPlayer:getSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  4] 9 MediaPlayer:handleGetSpeakerSettingsCalled
2018-08-03 06:30:09.774 [  1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2018-08-03 06:30:09.775 [  1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2018-08-03 06:30:09.775 [  1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SYNCED
2018-08-03 06:30:09.777 [  1] 0 ExternalMediaPlayer:createAdapters
2018-08-03 06:30:09.783 [  1] 1 AudioPlayer:addObserver
2018-08-03 06:30:09.784 [  7] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2018-08-03 06:30:09.787 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0xac3200,policy=BLOCKING
2018-08-03 06:30:09.787 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0xaccc50,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0xaccc50,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0xaccc50,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Play,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Logout,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Login,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=SetSeekPosition,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Play,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.788 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Unfavorite,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableShuffle,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Pause,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Next,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Rewind,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeat,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Previous,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=StartOver,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=FastForward,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Favorite,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableShuffle,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeatOne,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.789 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableRepeat,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=AdjustSeekPosition,handler=0xad9d70,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0xac2708,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0xac2708,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0xacdbc0,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0xacdbc0,policy=NON_BLOCKING
2018-08-03 06:30:09.790 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetEndpoint,handler=0xadc6b0,policy=NON_BLOCKING
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0xac2464,policy=NON_BLOCKING
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0xad99a0,policy=NON_BLOCKING
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0xad99a0,policy=NON_BLOCKING
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0xad99a0,policy=NON_BLOCKING
2018-08-03 06:30:09.791 [  1] 5 TemplateRuntime:getConfiguration
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0xadbac8,policy=HANDLE_IMMEDIATELY
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0xadbac8,policy=HANDLE_IMMEDIATELY
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=ClearIndicator,handler=0xad1b04,policy=HANDLE_IMMEDIATELY
2018-08-03 06:30:09.791 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=SetIndicator,handler=0xad1b04,policy=HANDLE_IMMEDIATELY
2018-08-03 06:30:09.794 [  1] 5 DCFDelegate:addDCFObserver:observer=0xabb230
2018-08-03 06:30:09.795 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2018-08-03 06:30:09.795 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0xa9ff48
2018-08-03 06:30:09.796 [  1] 5 NotificationsCapabilityAgent:addObserver
2018-08-03 06:30:09.797 [  1] 5 TemplateRuntime:addObserver
2018-08-03 06:30:09.797 [  8] 5 TemplateRuntime:addObserverInExecutor
#################################################
#       NOTIFICATION INDICATOR STATE: OFF       #
#################################################

2018-08-03 06:30:09.801 [  9] E DeviceInfo:createFailed:reason=missingClientId,key=clientId
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.front
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.rear
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.center_lfe
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.side
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround21
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround40
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround41
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround50
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround51
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.surround71
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.iec958
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'defaults.bluealsa.device'
ALSA lib conf.c:4528:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
ALSA lib conf.c:4996:(snd_config_expand) Args evaluate error: No such file or directory
ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM bluealsa
2018-08-03 06:30:09.894 [  1] I SensoryKeywordDetector:License expires on 28 Oct 2018 00:00:00 GMT
2018-08-03 06:30:09.894 [  1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
                  #    #     #  #####      #####  ######  #    #              
                 # #   #     # #     #    #     # #     # #   #               
                #   #  #     # #          #       #     # #  #                
               #     # #     #  #####      #####  #     # ###                 
               #######  #   #        #          # #     # #  #                
               #     #   # #   #     #    #     # #     # #   #               
               #     #    #     #####      #####  ######  #    #              
                                                                              
       #####                                           #                      
      #     #   ##   #    # #####  #      ######      # #   #####  #####      
      #        #  #  ##  ## #    # #      #          #   #  #    # #    #     
       #####  #    # # ## # #    # #      #####     #     # #    # #    #     
            # ###### #    # #####  #      #         ####### #####  #####      
      #     # #    # #    # #      #      #         #     # #      #          
       #####  #    # #    # #      ###### ######    #     # #      #          

       SDK Version 1.7.0

+----------------------------------------------------------------------------+
|                                  Options:                                  |
| Wake word:                                                                 |
|       Simply say Alexa and begin your query.                               |
| Tap to talk:                                                               |
|       Press 't' and Enter followed by your query (no need for the 'Alexa').|
| Hold to talk:                                                              |
|       Press 'h' followed by Enter to simulate holding a button.            |
|       Then say your query (no need for the 'Alexa').                       |
|       Press 'h' followed by Enter to simulate releasing a button.          |
| Stop an interaction:                                                       |
|       Press 's' and Enter to stop an ongoing interaction.                  |
| Privacy mode (microphone off):                                             |
|       Press 'm' and Enter to turn on and off the microphone.               |
| Echo Spatial Perception (ESP): This is for testing purpose only!           |
|       Press 'e' followed by Enter at any time to adjust ESP settings.      |
| Playback Controls:                                                         |
|       Press '1' for a 'PLAY' button press.                                 |
|       Press '2' for a 'PAUSE' button press.                                |
|       Press '3' for a 'NEXT' button press.                                 |
|       Press '4' for a 'PREVIOUS' button press.                             |
| Settings:                                                                  |
|       Press 'c' followed by Enter at any time to see the settings screen.  |
| Speaker Control:                                                           |
|       Press 'p' followed by Enter at any time to adjust speaker settings.  |
| Firmware Version:                                                          |
|       Press 'f' followed by Enter at any time to report a different        |
|       firmware version.                                                    |
| Info:                                                                      |
|       Press 'i' followed by Enter at any time to see the help screen.      |
| Reset device:                                                              |
|       Press 'k' followed by Enter at any time to reset your device. This   |
|       will erase any data stored in the device and you will have to        |
|       register your device with another account.                           |
|       This will kill the application since we don't support login yet.     |
| Quit:                                                                      |
|       Press 'q' followed by Enter at any time to quit the application.     |
+----------------------------------------------------------------------------+

2018-08-03 06:30:09.974 [  3] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-08-03 06:30:09.975 [  3] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
2018-08-03 06:30:09.975 [  3] 5 InternetConnectionMonitor:notifyObserversLocked
2018-08-03 06:30:10.268 [  2] 5 HttpPost:doPostSucceeded:code=200
2018-08-03 06:30:10.268 [  2] 5 CBLAuthDelegate:receiveCodePairResponse:code=200,body={"user_code"\:"BKBQUG"\,"device_code"\:"52d19665-be42-454d-ab95-dd301de593d0"\,"interval"\:30\,"verification_uri"\:"https\://amazon.com/us/code"\,"expires_in"\:600}
2018-08-03 06:30:10.268 [  2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS
2018-08-03 06:30:10.268 [  2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
2018-08-03 06:30:10.269 [  2] 5 CBLAuthDelegate:handleRequestingToken
2018-08-03 06:30:10.269 [  2] 5 CBLAuthDelegate:requestToken
##################################
#       NOT YET AUTHORIZED       #
##################################

################################################################################################
#       To authorize, browse to: 'https://amazon.com/us/code' and enter the code: BKBQUG       #
################################################################################################

#################################################
#       Checking for authorization (1)...       #
#################################################

2018-08-03 06:30:10.424 [  2] 5 HttpPost:doPostSucceeded:code=400
2018-08-03 06:30:10.424 [  2] 5 CBLAuthDelegate:receiveTokenResponse:code=400,body={"error_description"\:"The code pair has not been authorized"\,"error"\:"authorization_pending"}
2018-08-03 06:30:10.424 [  2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=400,error=INVALID_REQUEST
2018-08-03 06:30:10.424 [  2] 5 CBLAuthDelegate:errorInLwaResponseBody:error=authorization_pending,errorCode=AUTHORIZATION_PENDING
2018-08-03 06:30:10.424 [  2] 5 CBLAuthDelegate:setAuthError:authError=AUTHORIZATION_PENDING
2018-08-03 06:30:10.424 [  2] 0 CBLAuthDelegate:receiveTokenResponseFailed:result=AUTHORIZATION_PENDING
2018-08-03 06:30:15.425 [  2] 5 CBLAuthDelegate:requestToken
#################################################
#       Checking for authorization (2)...       #
#################################################

Not sure, but in this last output you posted I do see the message :slight_smile: … please check.

Ha, indeed there it is! thx. The code is “not recognized” by Amazon, maybe hi have to re-install?

If you did enter that code provided in the webste https://amazon.com/us/code and it didnt work my advise is to give it another try from the beginning, this time the setup should be faster because you already did it :slight_smile: !

Let me know

I did:

  1. bash ./setup.sh ./config.txt
  2. bash ./startsample.sh
  3. Went to: https://amazon.com/us/code --> paste code

Result was an error an Amazon side:
error