question

Grover M Inks avatar image
Grover M Inks asked

The provided sessionId is not ready to use.

I am building an alexa powered raspberry pi 3. I used the amazon github site for instructions and everything seemed to go smooth until the last few steps. I run the server with npm start command and it starts successfully. Then when I go to run the client app with mvn exec:exec, it opens and runs but I get an error. It says ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client. Then goes on to say that the provided sessionId is not ready to use. The only time I see sessionId mentioned is on the github page in the config.json example. On my raspberry when I open up config.json there is no sessionId line in mine. Is it supposed to be there and am I even referring to the right thing or am I missing something else? Thanks so much!
alexa voice service
10 |5000

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Eric@Amazon avatar image
Eric@Amazon answered
Can you provide logs?
10 |5000

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Grover M Inks avatar image
Grover M Inks answered
Sorry, I did not realize that anyone replied. 12:21:39.717 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 12:21:40.753 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 12:21:41.819 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"f9d4cdc34bbe430041a13484","sessionId":"48405e24-ca67-47a9-a84e-dcecfda3fa89"} 12:21:41.941 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "07b82d6c-1623-4cea-9120-ec0917bb3c47" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:24:57.969 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:59) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 12:41:00.138 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 12:41:01.440 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 12:41:02.760 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "27bbbc6b-debc-40f7-ba12-67050d477496" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:44:00.497 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 12:44:01.541 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 12:44:02.652 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"f27b7f0058117885b2cdc3be","sessionId":"f6151f70-72d7-4b48-aa58-88f51461575d"} 12:44:02.828 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "f1fcf59b-b070-4e7f-a284-335ab66fb050" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:44:06.801 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:59) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 12:44:20.756 [AWT-EventQueue-0] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "SpeechRecognizer", "name" : "Recognize", "messageId" : "c464ab74-cb51-4dfd-8be9-dc38bcfc497b", "dialogRequestId" : "7497968d-5390-4c87-b7e2-a1b9fb659ff2" }, "payload" : { "profile" : "CLOSE_TALK", "format" : "AUDIO_L16_RATE_16000_CHANNELS_1" } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:38.156 [SwingWorker-pool-7-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "962dc72d-75e6-4808-931f-3da5fd02c11a" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:41.975 [SwingWorker-pool-7-thread-2] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PreviousCommandIssued", "messageId" : "94453be7-7c53-4155-949a-f3dc8208b39d" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:43.382 [SwingWorker-pool-7-thread-3] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "NextCommandIssued", "messageId" : "5f301d03-0049-4106-b80e-e44c15107168" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:44.494 [SwingWorker-pool-7-thread-4] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "d6de1757-4c9a-419c-8f17-e6994aed2989" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:46.072 [SwingWorker-pool-7-thread-5] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "d4424150-4409-416d-b813-bbde43385a52" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:46.736 [SwingWorker-pool-7-thread-6] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "b5467ed9-5af5-456e-896b-38ebd0345802" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:46.983 [SwingWorker-pool-7-thread-7] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "713dca07-dd71-4fec-992e-5f5f85f7f9e9" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:47.819 [SwingWorker-pool-7-thread-8] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "f90d7b3e-83a4-4a6c-9b6c-bcf9ab98f0ef" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 12:46:50.459 [SwingWorker-pool-7-thread-9] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "fc53216d-9a3e-4550-a797-38c150d83a34" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 13:13:50.551 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 13:13:51.593 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 13:13:52.577 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"210643e47bf4ba2aa2907685","sessionId":"410c1f15-7e92-4840-bfc8-0d127883726f"} 13:13:52.767 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "488551df-ea46-4e1f-8ae3-2ee3cba78105" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 13:14:25.804 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:56) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 13:24:41.626 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 13:24:42.708 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 13:24:43.759 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"52b20733d362f33c28e419cc","sessionId":"28a343b7-a798-465c-81b3-b13d103288ce"} 13:24:43.861 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "51060d37-571c-41c4-9ef9-c4ee22bd0790" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 13:24:56.924 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:59) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 14:24:42.734 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "e9410141-184f-4428-8078-50aec42a3290" }, "payload" : { "inactiveTimeInSeconds" : 3600 } } } 15:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "62f412be-4251-46ab-aa95-12f7abb0ee28" }, "payload" : { "inactiveTimeInSeconds" : 7200 } } } 16:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "7e52a254-7c81-463b-b5e7-a541ea3df9fd" }, "payload" : { "inactiveTimeInSeconds" : 10800 } } } 17:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "c2d5083f-8fcc-4f9c-bd04-65669c6e4cf9" }, "payload" : { "inactiveTimeInSeconds" : 14400 } } } 18:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "4e7da977-7beb-4e4a-af9f-db02299f3192" }, "payload" : { "inactiveTimeInSeconds" : 18000 } } } 19:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "be237760-df1e-4917-9b8d-ed9e8d7cfd08" }, "payload" : { "inactiveTimeInSeconds" : 21600 } } } 20:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "4a15117f-0c25-4710-9bca-5f7e3f76d985" }, "payload" : { "inactiveTimeInSeconds" : 25200 } } } 21:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "21505e2c-73ef-43b0-b5e3-0023008d15c4" }, "payload" : { "inactiveTimeInSeconds" : 28800 } } } 22:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "c42c9dfe-ef70-4dea-9e30-500c563479c7" }, "payload" : { "inactiveTimeInSeconds" : 32400 } } } 23:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "f0a48402-1401-44fd-b300-9b8dda42e72c" }, "payload" : { "inactiveTimeInSeconds" : 36000 } } } 00:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "43d001cd-51b6-4441-b421-477f6ac85345" }, "payload" : { "inactiveTimeInSeconds" : 39600 } } } 01:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "51f002b6-c6db-47c8-ba54-f269e6baa242" }, "payload" : { "inactiveTimeInSeconds" : 43200 } } } 02:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "0be5e7eb-24de-43c6-b524-53b18158b35b" }, "payload" : { "inactiveTimeInSeconds" : 46800 } } } 03:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "4f59a39f-f7ff-4768-9473-e76e43cb77c1" }, "payload" : { "inactiveTimeInSeconds" : 50400 } } } 04:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "ad73fce4-2c94-4927-8e38-d1aadaa3d315" }, "payload" : { "inactiveTimeInSeconds" : 54000 } } } 05:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "73b6390a-c343-47b7-8c3b-ef47c4f4335d" }, "payload" : { "inactiveTimeInSeconds" : 57600 } } } 06:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "9079d365-ad08-47e8-a348-45b8635a4b98" }, "payload" : { "inactiveTimeInSeconds" : 61200 } } } 07:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "633877f6-29f6-43fa-836a-276a204bbc89" }, "payload" : { "inactiveTimeInSeconds" : 64800 } } } 08:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "873f1f2e-d994-4377-8560-791f4e4bfd3f" }, "payload" : { "inactiveTimeInSeconds" : 68400 } } } 09:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "806be61d-3923-4845-9e1c-bb58fdc1079e" }, "payload" : { "inactiveTimeInSeconds" : 72000 } } } 10:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "858bf179-e456-4a7b-b2be-dac14c64bc8a" }, "payload" : { "inactiveTimeInSeconds" : 75600 } } } 11:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "09eefc9a-71c2-43b9-a90d-372ccb2ef260" }, "payload" : { "inactiveTimeInSeconds" : 79200 } } } 12:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "8011fd71-25c4-4a0e-962d-72ea922f1449" }, "payload" : { "inactiveTimeInSeconds" : 82800 } } } 13:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "eaa18266-5cc1-407b-8a6e-d0fe293821f1" }, "payload" : { "inactiveTimeInSeconds" : 86400 } } } 14:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "9225d472-e699-4609-a31c-383f9c40b809" }, "payload" : { "inactiveTimeInSeconds" : 90000 } } } 15:24:42.727 [pool-2-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "UserInactivityReport", "messageId" : "26d62a54-47d3-4387-a536-c3fabb71eb30" }, "payload" : { "inactiveTimeInSeconds" : 93600 } } } 16:08:07.068 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 16:08:08.113 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 16:08:09.092 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"c9b0e65d2cb266616e8d10b0","sessionId":"d9d6b1f7-4240-4e30-836a-4b8a2196c305"} 16:08:09.384 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "d63e222b-7bf5-41ef-9620-4b0029f34aad" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 16:08:11.803 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:59) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 09:34:22.267 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 09:34:23.606 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 09:34:24.766 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"fdb53579cdb694fd044ed327","sessionId":"aa35523c-181b-42c2-bb5b-10e673871153"} 09:34:24.779 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "a1849610-6f9d-4d71-8e6f-3e61426fb9ad" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:37:46.746 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"access_token":"Atza|IQEBLjAsAhRqNV4fwiX_QaityTpkZjterfWKyQIUFs0y5F0J6qtd6j-_pz5nGrOulRuMjkb-3SiUn4Ycxj1kvj89gbaM47nX8CMcWavCfWR92zLevmnjOWNMfZ1GkmuP6sFVgck3QVN3uMuJMFYhv2Qvym8w2rWnugWCH8vcDFdtJ0M0ZVTQDw51Qq7ohb3icXFVhcUrUmpXvNqqUt6FrSwqCwIn0gj3tO7bmaHOULNBBhHM2lZCULEfFd3mIKqrUH4WPXDNOK6LHFBk-gUjYxT-uQN4jDginqohluRzVFjGbYomm8CK4JAL2w_3LLESyTsbG8I1MnS0pRjh4rc8qeRzup27q2a7uqPqLlT2YAlqBadVe2_6WcSVyHsA-W0rG0LCUmgVea5moFFuQIAYlY1dS7BcE5nWuy6yCh8xtAWoIMEKeUk_3Bk8jnTEWi-4IkX2BS1diDqC0fGHh-AOj-t31kKKvoqF8-r7M_dTJzeqJjOcLGkAQvthqsSa_auWJOD0RzGPCQctZpZIjh20zrfpYhXST81eiSCQlMWrPbL2gfRwGJwUDK_25rSizGEtn0GJ","expires_in":3600} 09:37:46.828 [DownchannelRequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Establishing downchannel 09:37:49.374 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:37:49.375 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-1- 09:37:49.384 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:37:49.850 [DownchannelRequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 200 09:37:49.850 [DownchannelRequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-3- content-type: multipart/related; boundary=------abcde123; type=application/json 09:39:04.957 [AWT-EventQueue-0] ERROR com.amazon.alexa.avs.AVSApp - An error occured creating speech request java.lang.NullPointerException at com.amazon.alexa.avs.AudioCapture.startCapture(AudioCapture.java:74) ~[classes/:?] at com.amazon.alexa.avs.AudioCapture.getAudioInputStream(AudioCapture.java:56) ~[classes/:?] at com.amazon.alexa.avs.AVSController.startRecording(AVSController.java:167) [classes/:?] at com.amazon.alexa.avs.AVSApp$2.actionPerformed(AVSApp.java:204) [classes/:?] at javax.swing.AbstractButton.fireActionPerformed(AbstractButton.java:2022) [?:1.8.0_77] at javax.swing.AbstractButton$Handler.actionPerformed(AbstractButton.java:2348) [?:1.8.0_77] at javax.swing.DefaultButtonModel.fireActionPerformed(DefaultButtonModel.java:402) [?:1.8.0_77] at javax.swing.DefaultButtonModel.setPressed(DefaultButtonModel.java:259) [?:1.8.0_77] at javax.swing.plaf.basic.BasicButtonListener.mouseReleased(BasicButtonListener.java:252) [?:1.8.0_77] at java.awt.Component.processMouseEvent(Component.java:6535) [?:1.8.0_77] at javax.swing.JComponent.processMouseEvent(JComponent.java:3324) [?:1.8.0_77] at java.awt.Component.processEvent(Component.java:6300) [?:1.8.0_77] at java.awt.Container.processEvent(Container.java:2236) [?:1.8.0_77] at java.awt.Component.dispatchEventImpl(Component.java:4891) [?:1.8.0_77] at java.awt.Container.dispatchEventImpl(Container.java:2294) [?:1.8.0_77] at java.awt.Component.dispatchEvent(Component.java:4713) [?:1.8.0_77] at java.awt.LightweightDispatcher.retargetMouseEvent(Container.java:4888) [?:1.8.0_77] at java.awt.LightweightDispatcher.processMouseEvent(Container.java:4525) [?:1.8.0_77] at java.awt.LightweightDispatcher.dispatchEvent(Container.java:4466) [?:1.8.0_77] at java.awt.Container.dispatchEventImpl(Container.java:2280) [?:1.8.0_77] at java.awt.Window.dispatchEventImpl(Window.java:2750) [?:1.8.0_77] at java.awt.Component.dispatchEvent(Component.java:4713) [?:1.8.0_77] at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:758) [?:1.8.0_77] at java.awt.EventQueue.access$500(EventQueue.java:97) [?:1.8.0_77] at java.awt.EventQueue$3.run(EventQueue.java:709) [?:1.8.0_77] at java.awt.EventQueue$3.run(EventQueue.java:703) [?:1.8.0_77] at java.security.AccessController.doPrivileged(Native Method) ~[?:1.8.0_77] at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:76) [?:1.8.0_77] at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:86) [?:1.8.0_77] at java.awt.EventQueue$4.run(EventQueue.java:731) [?:1.8.0_77] at java.awt.EventQueue$4.run(EventQueue.java:729) [?:1.8.0_77] at java.security.AccessController.doPrivileged(Native Method) ~[?:1.8.0_77] at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:76) [?:1.8.0_77] at java.awt.EventQueue.dispatchEvent(EventQueue.java:728) [?:1.8.0_77] at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:201) [?:1.8.0_77] at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116) [?:1.8.0_77] at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105) [?:1.8.0_77] at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101) [?:1.8.0_77] at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93) [?:1.8.0_77] at java.awt.EventDispatchThread.run(EventDispatchThread.java:82) [?:1.8.0_77] 09:40:17.971 [SwingWorker-pool-7-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "40d24814-a4e1-4f74-8c32-20d0eba1efbe" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:18.477 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:18.478 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-5- 09:40:18.478 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:19.819 [SwingWorker-pool-7-thread-2] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "6d7fb1e8-2c4f-433b-baf5-70340a4f92ff" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:20.218 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:20.218 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-7- 09:40:20.219 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:24.162 [SwingWorker-pool-7-thread-3] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "20e3aaee-a105-4b6d-ab52-fc353962d767" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:24.586 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:24.587 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-9- 09:40:24.588 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:25.469 [SwingWorker-pool-7-thread-4] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "40881230-f80c-4261-8e85-b47e2b48a602" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:25.850 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:25.854 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-11- 09:40:25.855 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:26.035 [SwingWorker-pool-7-thread-5] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "a4dac12d-e631-4025-9c90-96f86a670d23" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:26.447 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:26.448 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-13- 09:40:26.448 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:26.491 [SwingWorker-pool-7-thread-6] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "100ee94a-3d58-4e86-b84a-9fd7974fa2c0" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:26.704 [SwingWorker-pool-7-thread-7] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "7c5583e1-f866-45a0-ba60-1d39509b8045" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:26.897 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:26.898 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-15- 09:40:26.899 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:26.920 [SwingWorker-pool-7-thread-8] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "9a6cf9f7-004f-4302-9fbe-0d64f8f6ea27" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:27.168 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:27.170 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-17- 09:40:27.169 [SwingWorker-pool-7-thread-9] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "f4364b1d-ed2d-4126-8149-ee48e43ee8c2" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:27.171 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:27.558 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:27.559 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-19- 09:40:27.560 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:27.860 [SwingWorker-pool-7-thread-10] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "0e91bfd4-5591-4724-826a-8a2f4f3427b9" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:40:27.929 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:27.930 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-21- 09:40:27.930 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:40:28.199 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response code: 204 09:40:28.200 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - Response headers: access-control-allow-origin: * x-amzn-requestid: 0e8b90fffeeff8c7-000040be-000374e5-4b5d8e1562332a6c-d44dd096-23- 09:40:28.200 [RequestThread] INFO com.amazon.alexa.avs.http.AVSClient - This response successfully had no content. 09:52:01.920 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient starting 09:52:03.186 [main] INFO com.amazon.alexa.avs.http.AVSClient - HttpClient started 09:52:04.444 [Thread-15] INFO com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient - Received response from companion service: {"regCode":"e7873a0d020f74ea3bb3450a","sessionId":"a33f837a-e35f-45f4-b966-386932d15d85"} 09:52:04.888 [pool-6-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "System", "name" : "SynchronizeState", "messageId" : "0239c282-d2bf-465b-a349-7d74e1ae179b" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:52:11.812 [Thread-15] ERROR com.amazon.alexa.avs.auth.AuthSetup - Failed to start companion service client com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient$RemoteServiceException: InvalidSessionId: The provided sessionId is not ready to use. at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.callService(CompanionServiceClient.java:225) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceClient.getAccessToken(CompanionServiceClient.java:175) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.requestAccessToken(CompanionServiceAuthManager.java:95) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startNewProvisioningRequest(CompanionServiceAuthManager.java:65) ~[classes/:?] at com.amazon.alexa.avs.auth.companionservice.CompanionServiceAuthManager.startRemoteProvisioning(CompanionServiceAuthManager.java:56) ~[classes/:?] at com.amazon.alexa.avs.auth.AuthSetup$2.run(AuthSetup.java:90) [classes/:?] 09:52:38.460 [AWT-EventQueue-0] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "SpeechRecognizer", "name" : "Recognize", "messageId" : "326ce95c-c450-414c-89fd-313f86989054", "dialogRequestId" : "ac6b44e5-38b2-47e5-a890-41057d31e0e7" }, "payload" : { "profile" : "CLOSE_TALK", "format" : "AUDIO_L16_RATE_16000_CHANNELS_1" } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:54:20.137 [SwingWorker-pool-7-thread-1] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "ee98e3a6-18a6-4f98-ac66-1a64f2c063b9" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] } 09:54:20.805 [SwingWorker-pool-7-thread-2] INFO com.amazon.alexa.avs.http.AVSClient - Request metadata: { "event" : { "header" : { "namespace" : "PlaybackController", "name" : "PlayCommandIssued", "messageId" : "0c1dc944-d1b2-45e8-958c-6ba7dabc58f9" }, "payload" : { } }, "context" : [ { "header" : { "namespace" : "AudioPlayer", "name" : "PlaybackState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "IDLE" } }, { "header" : { "namespace" : "SpeechSynthesizer", "name" : "SpeechState" }, "payload" : { "token" : "", "offsetInMilliseconds" : 0, "playerActivity" : "FINISHED" } }, { "header" : { "namespace" : "Alerts", "name" : "AlertsState" }, "payload" : { "allAlerts" : [ ], "activeAlerts" : [ ] } }, { "header" : { "namespace" : "Speaker", "name" : "VolumeState" }, "payload" : { "volume" : 50, "muted" : false } } ] }
10 |5000

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

Grover M Inks avatar image
Grover M Inks answered
10 |5000

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.

swasey@amazon avatar image
swasey@amazon answered
When using the companion service example the sessionId will become invalid if the service is restarted. It's supposed to request a new sessionId and prompt you to go through the initial process again if the sessionId is ever invalid, however that doesn't seem to be working for you. Could you please test the following: 1. Remove sessionId from your config.json 2. Start the companion service (npm start) 3. Start the app (mvn exec:exec) 4. Go through the normal process to sign in and make voice requests 5. Stop the companion service 6. Stop the app 7. Restart the app (mvn exec:exec) If you get just the error and no prompt to redo the login process, then we have a problem. I'll investigate, but your feedback on this would be helpful.
10 |5000

Up to 2 attachments (including images) can be used with a maximum of 512.0 KiB each and 1.0 MiB total.