no audio to Waveshare WM8960 audio HAT - Raspberry Pi

Briefly summarize your issue:
Raspberry Pi 2 with Waveshare WM8960 audio HAT. Alexa is working and using the HAT's microphones, but will not output audio to the HAT, audio only available on the Pi's 3.5mm jack.
What is the expected behavior?
The audio should output to the HAT
What behavior are you observing?
Alexa works fine, uses the HAT microphones as the input, will not output audio to the HAT. It seems that Alexa will only output audio to the 3.5mm jack, a limitation that seems inexplicable.
The HAT audio works for all other applications and it is default for alsa.
arecord and aplay both work fine, using the mic and speakers connected to the HAT.
I have tested using
gst-launch-1.0 -m audiotestsrc ! alsasink
and
gst-launch-1.0 -m audiotestsrc ! autoaudiosink
both produce a tone via the HAT, so gstreamer and alsa are both fine using the HAT for audio
The HAT appears on both aplay -l and arecord -l as card 1, device 0 and I have updated the .asoundrc file with these numbers, just in case, but in fact the HAT worked fine with all apps (except Alexa) even without an .asoundrc file present.
Where is the line of code to point Alexa away from the 3.5mm jack and towards the HAT?
Provide the steps to reproduce the issue, if applicable:

Tell us about your environment:

What version of the AVS Device SDK are you using?
 the latest one

Tell us what hardware you're using:

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

Tell us about your OS (Type & version):

[ ] Linux
[ ] MacOS
[ ] Raspbian Stretch
[ ] Raspbian Jessy
[x ] Other - tell us more: Raspian Buster

Hi @ldritsas thanks for posting.
There isn't a line of code in the SDK that specifies the audio output of the device. Are you running the SampleApp with the correct value set for the env variable PA_ALSA_PLUGHW?
Can you try running the device with DEBUG9 logs enabled and sharing them here?

Thank you for your help. my startample.sh reads
PA_ALSA_PLUGHW=1 ./SampleApp "/home/pi/build/Integration/AlexaClientSDKConfig.json" "/home/pi/third-party/alexa-rpi/models" DEBUG9
I'll get the logs for you, but I cannot see any obvious errors in them as they scroll by.

for more info I followed the instructions
"Set Up the AVS Device SDK on Raspberry Pi with a Script"
and alexa works fine, just not using the speakers I want (via the audio HAT)
in dbug logs I notice the following:
Cannot connect to server socket err = No such file or directory
Cannot connect to server request channel
jack server is not running or cannot be started
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Cannot connect to server socket err = No such file or directory
Cannot connect to server request channel
jack server is not running or cannot be started
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock

and here is the full startup log
2021-04-13 11:24:53.546 [  1] I AlexaClientSdkInit:initialize:sdkversion=1.23.0
2021-04-13 11:24:53.557 [  1] 0 ConfigurationNode:initializeSuccess
2021-04-13 11:24:53.566 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:53.567 [  1] 5 SDKPrimitivesProvider:withTimerDelegateFactory
2021-04-13 11:24:53.567 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:53.568 [  1] 5 SDKPrimitivesProvider:initialize
2021-04-13 11:24:53.568 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:53.574 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2021-04-13 11:24:53.575 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2021-04-13 11:24:53.576 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2021-04-13 11:24:53.577 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2021-04-13 11:24:53.583 [  1] 9 MediaPlayer:createCalled:name=SpeakMediaPlayer
2021-04-13 11:24:53.805 [  1] 9 MediaPlayer:noOutputConversion:name=SpeakMediaPlayer
2021-04-13 11:24:53.814 [  1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.821 [  1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2021-04-13 11:24:53.829 [  1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.831 [  1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2021-04-13 11:24:53.840 [  1] 9 MediaPlayer:createCalled:name=NotificationsMediaPlayer
2021-04-13 11:24:53.842 [  1] 9 MediaPlayer:noOutputConversion:name=NotificationsMediaPlayer
2021-04-13 11:24:53.848 [  1] 9 MediaPlayer:createCalled:name=BluetoothMediaPlayer
2021-04-13 11:24:53.850 [  1] 9 MediaPlayer:noOutputConversion:name=BluetoothMediaPlayer
2021-04-13 11:24:53.856 [  1] 9 MediaPlayer:createCalled:name=RingtoneMediaPlayer
2021-04-13 11:24:53.858 [  1] 9 MediaPlayer:noOutputConversion:name=RingtoneMediaPlayer
2021-04-13 11:24:53.865 [  1] 9 MediaPlayer:createCalled:name=AlertsMediaPlayer
2021-04-13 11:24:53.866 [  1] 9 MediaPlayer:noOutputConversion:name=AlertsMediaPlayer
2021-04-13 11:24:53.873 [  1] 9 MediaPlayer:createCalled:name=SystemSoundMediaPlayer
2021-04-13 11:24:53.875 [  1] 9 MediaPlayer:noOutputConversion:name=SystemSoundMediaPlayer
2021-04-13 11:24:53.883 [  1] 5 SQLiteDeviceSettingStorage:create
2021-04-13 11:24:53.884 [  1] 5 SQLiteBluetoothStorage:create
2021-04-13 11:24:53.889 [  1] I DeviceInfo:create:result=skipDeviceType,key=deviceType
2021-04-13 11:24:53.889 [  1] I DeviceInfo:create:result=skipFriendlyName,key=friendlyName
2021-04-13 11:24:53.890 [  1] I DeviceInfo:create:result=skipRegistrationKey,key=registrationKey
2021-04-13 11:24:53.890 [  1] I DeviceInfo:create:result=skipProductIdKey,key=productIdKey
2021-04-13 11:24:53.893 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:53.894 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:0
2021-04-13 11:24:53.894 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:53.903 [  1] 5 CBLAuthDelegate:createAuthDelegateInterface
2021-04-13 11:24:53.905 [  1] 5 CBLAuthDelegate:CBLAuthDelegate
2021-04-13 11:24:53.905 [  1] 5 CBLAuthDelegate:init
2021-04-13 11:24:53.906 [  1] 5 CBLAuthDelegateConfiguration:create
2021-04-13 11:24:53.906 [  1] 5 CBLAuthDelegateConfiguration:init
2021-04-13 11:24:53.906 [  1] 5 CBLAuthDelegateConfiguration:initScopeData
2021-04-13 11:24:53.907 [  1] 9 CBLAuthDelegateConfiguration:initScopeDataSucceeded
2021-04-13 11:24:53.908 [  1] 5 SQLiteCBLAuthDelegateStorage:open
2021-04-13 11:24:53.912 [  2] 5 CBLAuthDelegate:handleAuthorizationFlow
2021-04-13 11:24:53.912 [  2] 5 CBLAuthDelegate:handleStarting
2021-04-13 11:24:53.912 [  1] 5 SQLiteCapabilitiesDelegateStorage:create
2021-04-13 11:24:53.912 [  2] 5 SQLiteCBLAuthDelegateStorage:getRefreshToken
2021-04-13 11:24:53.914 [  2] 5 CBLAuthDelegate:handleRefreshingToken
2021-04-13 11:24:53.914 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:53.914 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:1
2021-04-13 11:24:53.914 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:53.914 [  2] 5 CBLAuthDelegate:requestRefresh
2021-04-13 11:24:53.915 [  1] 5 SQLiteCapabilitiesDelegateStorage:open
2021-04-13 11:24:53.918 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0x11136ac
2021-04-13 11:24:53.921 [  1] 9 ThreadPool:obtainWorker:created=0,obtained=0,releasedToPool=0,releasedFromPool=0,outstanding=0
2021-04-13 11:24:53.923 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x11136b0
2021-04-13 11:24:53.925 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:53.925 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:53.925 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:53.926 [  1] 5 InternetConnectionMonitor:startMonitoring
2021-04-13 11:24:53.929 [  3] 9 TaskThread:startThread:moniker=  3,duration=7647
2021-04-13 11:24:53.932 [  4] 5 InternetConnectionMonitor:testConnection
2021-04-13 11:24:53.932 [  4] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher
2021-04-13 11:24:53.934 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:53.935 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:2
2021-04-13 11:24:53.935 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:53.935 [  4] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition
2021-04-13 11:24:53.936 [  5] 9 LibCurlHttpContentFetcher:transferThread::start
2021-04-13 11:24:53.936 [  1] 5 AVSGatewayManager:create
2021-04-13 11:24:53.940 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.944 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.944 [  1] 5 AVSGatewayManager:init:avsGateway=https://bob-dispatch-prod-eu.amazon.com
2021-04-13 11:24:53.945 [  1] 5 SynchronizeStateSenderFactory:create
2021-04-13 11:24:53.946 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.949 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.951 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.952 [  1] 5 ExternalCapabilitiesBuilder:ExternalCapabilitiesBuilder
2021-04-13 11:24:53.956 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:53.956 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.957 [  6] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.959 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:53.959 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.959 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.960 [  7] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:53.967 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:53.976 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:54.018 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.018 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.019 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.019 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.020 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:3
2021-04-13 11:24:54.020 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.022 [  1] 5 AVSConnectionManager:create::Subscribing to InternetConnectionMonitor Callbacks
2021-04-13 11:24:54.024 [  1] 5 AVSConnectionManager:onConnectionStatusChanged:connected=false,isEnabled=false
2021-04-13 11:24:54.028 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.028 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:4
2021-04-13 11:24:54.028 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.030 [  1] 5 DefaultEndpointBuilder:createDefaultEndpointBuilderInterface
2021-04-13 11:24:54.031 [  1] 5 EndpointBuilder:finalizeAttributes
2021-04-13 11:24:54.032 [  1] 5 DefaultEndpointBuilder:createDefaultEndpointCapabilitiesRegistrarInterface
2021-04-13 11:24:54.033 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.033 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:5
2021-04-13 11:24:54.033 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.035 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.038 [  1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-04-13 11:24:54.043 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.043 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:6
2021-04-13 11:24:54.043 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.044 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.045 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.045 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.045 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.045 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.045 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.047 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.047 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.047 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.047 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.047 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.047 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.047 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.048 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.048 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.048 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.048 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.048 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.048 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.049 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.049 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.049 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.049 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.049 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.049 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.049 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.049 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.049 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.050 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.050 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.050 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.050 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.050 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.050 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.050 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.050 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.054 [  1] 5 AlexaInterfaceCapabilityAgent:createDefaultAlexaInterfaceCapabilityAgent
2021-04-13 11:24:54.054 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.055 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:7
2021-04-13 11:24:54.055 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.055 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.061 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=0
2021-04-13 11:24:54.061 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.061 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:8
2021-04-13 11:24:54.062 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.062 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.064 [  1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-04-13 11:24:54.065 [  1] 5 StubApplicationAudioPipelineFactory:addCaptionManager::captions disabled
2021-04-13 11:24:54.066 [  1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-04-13 11:24:54.066 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.066 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.067 [  8] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.069 [  1] 5 SpeakerManager:executeInitializeSpeakerSettings:type=AVS_ALERTS_VOLUME
2021-04-13 11:24:54.070 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.070 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.070 [  8] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.071 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=1
2021-04-13 11:24:54.072 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.072 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:9
2021-04-13 11:24:54.072 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.073 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:54.073 [  1] 5 Renderer:resetSourceId
2021-04-13 11:24:54.073 [  1] 9 MediaPlayer:addObserverCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.074 [  8] 0 MediaPlayer:handleAddObserverCalled:name=AlertsMediaPlayer
2021-04-13 11:24:54.080 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=1
2021-04-13 11:24:54.081 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.081 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:10
2021-04-13 11:24:54.081 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.082 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=2
2021-04-13 11:24:54.086 [  1] 9 SQLiteMessageStorage:isDatabaseLegacy:reason=databaseNotLegacy
2021-04-13 11:24:54.087 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.088 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=CertifiedSender
2021-04-13 11:24:54.088 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.090 [  9] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.091 [  9] 9 PowerMonitor:assignThreadPowerResource:threadId=1774158896
2021-04-13 11:24:54.091 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.091 [  9] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.091 [  1] 5 CertifiedSender:onConnectionStatusChanged
2021-04-13 11:24:54.092 [  9] 9 CertifiedSender:mainloop:reason=waitingForMessage
2021-04-13 11:24:54.092 [  9] 5 ConditionVariableWrapper:waitInner
2021-04-13 11:24:54.092 [  1] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:54.095 [  1] 5 SQLiteDeviceSettingStorage:open
2021-04-13 11:24:54.100 [  1] 5 DoNotDisturbCapabilityAgent:createDoNotDisturbCapabilityAgent
2021-04-13 11:24:54.101 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.101 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:11
2021-04-13 11:24:54.101 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.102 [  1] 5 DNDSettingProtocol:create:settingName=enabled
2021-04-13 11:24:54.102 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.102 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:12
2021-04-13 11:24:54.102 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.103 [  1] 5 DNDSettingProtocol:restoreValue:setting=Alexa.DoNotDisturb::enabled
2021-04-13 11:24:54.104 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alexa.DoNotDisturb::enabled
2021-04-13 11:24:54.105 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.107 [  1] I Setting:handleSetValue:value=false3990065800
2021-04-13 11:24:54.108 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.108 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.110 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=3
2021-04-13 11:24:54.111 [  1] 5 SharedAVSSettingProtocol:create:settingName=alarmVolumeRamp
2021-04-13 11:24:54.112 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.112 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:13
2021-04-13 11:24:54.112 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.113 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.114 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=Alerts::alarmVolumeRamp
2021-04-13 11:24:54.114 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts::alarmVolumeRamp
2021-04-13 11:24:54.115 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.117 [  1] I Setting:handleSetValue:value="NONE"3990065800
2021-04-13 11:24:54.117 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=4
2021-04-13 11:24:54.117 [  1] 5 SharedAVSSettingProtocol:create:settingName=wakeWordConfirmation
2021-04-13 11:24:54.118 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.118 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:14
2021-04-13 11:24:54.118 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.118 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.119 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::wakeWordConfirmation
2021-04-13 11:24:54.119 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::wakeWordConfirmation
2021-04-13 11:24:54.120 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.121 [  1] I Setting:handleSetValue:value="NONE"3990065800
2021-04-13 11:24:54.121 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=5
2021-04-13 11:24:54.121 [  1] 5 SharedAVSSettingProtocol:create:settingName=speechConfirmation
2021-04-13 11:24:54.122 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.122 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:15
2021-04-13 11:24:54.122 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.122 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.123 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::speechConfirmation
2021-04-13 11:24:54.123 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::speechConfirmation
2021-04-13 11:24:54.124 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.124 [  1] I Setting:handleSetValue:value="NONE"3990065800
2021-04-13 11:24:54.125 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=6
2021-04-13 11:24:54.125 [  1] 5 SharedAVSSettingProtocol:create:settingName=timeZone
2021-04-13 11:24:54.126 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.126 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:16
2021-04-13 11:24:54.126 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.126 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.127 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=System::timeZone
2021-04-13 11:24:54.127 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::timeZone
2021-04-13 11:24:54.128 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.128 [  1] I Setting:handleSetValue:value="Europe/London"3990065800
2021-04-13 11:24:54.129 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=7
2021-04-13 11:24:54.129 [  1] 5 SharedAVSSettingProtocol:create:settingName=networkInfo
2021-04-13 11:24:54.130 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.130 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:17
2021-04-13 11:24:54.130 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.130 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.131 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=System::networkInfo
2021-04-13 11:24:54.131 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::networkInfo
2021-04-13 11:24:54.132 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.133 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=connectionType
2021-04-13 11:24:54.133 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=ESSID
2021-04-13 11:24:54.133 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=BSSID
2021-04-13 11:24:54.134 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=IPAddress
2021-04-13 11:24:54.134 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=subnetMask
2021-04-13 11:24:54.134 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=MACAddress
2021-04-13 11:24:54.134 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=DHCPServerAddress
2021-04-13 11:24:54.134 [  1] 5 JsonUtils:findNode:reason=missingDirectChild,child=staticIP
2021-04-13 11:24:54.135 [  1] I Setting:handleSetValue:value={}3990065800
2021-04-13 11:24:54.136 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=8
2021-04-13 11:24:54.136 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=9
2021-04-13 11:24:54.136 [  1] 5 LocaleWakeWordsSetting:create:settingName=LocaleWakeWords
2021-04-13 11:24:54.137 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.137 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:18
2021-04-13 11:24:54.137 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.138 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System.locales
2021-04-13 11:24:54.139 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.140 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer.wakeWords
2021-04-13 11:24:54.141 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:54.143 [  1] 2 LocaleWakeWordsSetting:restoreInitialValue:wakeWords=["ALEXA"],locale=["en-US"]
2021-04-13 11:24:54.143 [  1] 9 ThreadPool:obtainWorker:created=1,obtained=1,releasedToPool=0,releasedFromPool=0,outstanding=1
2021-04-13 11:24:54.144 [  a] 9 TaskThread:startThread:moniker=  a,duration=761
2021-04-13 11:24:54.145 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.145 [  a] I LocaleAssetsManager:changeAssets:Locale=["en-US"],WakeWords=["ALEXA"]
2021-04-13 11:24:54.150 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.150 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.150 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.151 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.151 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:19
2021-04-13 11:24:54.151 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.151 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.152 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:20
2021-04-13 11:24:54.152 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.159 [  1] 5 AlertScheduler:setTimerForNextAlertLocked
2021-04-13 11:24:54.159 [  1] 8 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2021-04-13 11:24:54.159 [  1] 9 SpeakerManager:getSpeakerSettingsCalled
2021-04-13 11:24:54.160 [  1] 9 ThreadPool:obtainWorker:created=2,obtained=2,releasedToPool=0,releasedFromPool=0,outstanding=2
2021-04-13 11:24:54.161 [  b] 9 TaskThread:startThread:moniker=  b,duration=758
2021-04-13 11:24:54.161 [  b] 9 SpeakerManager:executeGetSpeakerSettingsCalled
2021-04-13 11:24:54.163 [  1] 5 ContextManager:setState
2021-04-13 11:24:54.164 [  1] 9 ThreadPool:obtainWorker:created=3,obtained=3,releasedToPool=0,releasedFromPool=0,outstanding=3
2021-04-13 11:24:54.164 [  c] 9 TaskThread:startThread:moniker=  c,duration=959
2021-04-13 11:24:54.165 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.166 [  1] 9 ThreadPool:obtainWorker:created=4,obtained=4,releasedToPool=0,releasedFromPool=0,outstanding=4
2021-04-13 11:24:54.167 [  d] 9 TaskThread:startThread:moniker=  d,duration=787
2021-04-13 11:24:54.167 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2021-04-13 11:24:54.167 [  d] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.167 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x11e5fbc
2021-04-13 11:24:54.169 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.170 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:54.172 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.174 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.175 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.175 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.175 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:21
2021-04-13 11:24:54.175 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.176 [  1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-04-13 11:24:54.177 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.177 [  1] 5 PlaybackRouter:createPlaybackRouterInterface
2021-04-13 11:24:54.178 [  1] 9 PlaybackRouter:create::called
2021-04-13 11:24:54.179 [  1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-04-13 11:24:54.180 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.180 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.180 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.180 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.181 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.181 [  5] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=419
2021-04-13 11:24:54.181 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.181 [  5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-04-13 11:24:54.182 [  5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-04-13 11:24:54.183 [  5] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found.
2021-04-13 11:24:54.183 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.183 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:22
2021-04-13 11:24:54.183 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.184 [  5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition
2021-04-13 11:24:54.184 [  4] 9 InternetConnectionMonitor:testConnection:contentLength=419
2021-04-13 11:24:54.184 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.185 [  4] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition
2021-04-13 11:24:54.186 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.194 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.194 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:23
2021-04-13 11:24:54.194 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.196 [  1] 5 ExternalMediaPlayer:init
2021-04-13 11:24:54.196 [  1] 5 AuthorizedSender:create
2021-04-13 11:24:54.196 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.197 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.198 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.199 [  1] 5 ExternalMediaPlayer:createAdapters
2021-04-13 11:24:54.200 [  1] C RequiresShutdown:~RequiresShutdownFailed:reason=notShutdown,name=StaticExternalMediaPlayerAdapterHandler
2021-04-13 11:24:54.202 [  1] 5 SpeakerManager:executeInitializeSpeakerSettings:type=AVS_SPEAKER_VOLUME
2021-04-13 11:24:54.202 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.202 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:54.202 [  6] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-04-13 11:24:54.203 [  1] 9 SpeakerManager:executeGetSpeakerSettingsCalled
2021-04-13 11:24:54.203 [  1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SPEAKER_VOLUME
2021-04-13 11:24:54.204 [  1] 5 ContextManager:setState
2021-04-13 11:24:54.204 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=1
2021-04-13 11:24:54.205 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=2
2021-04-13 11:24:54.207 [  1] 9 ExternalMediaPlayer:startup
2021-04-13 11:24:54.210 [  1] 7 DefaultClient:initialize::metrics disabled
2021-04-13 11:24:54.212 [  1] 7 DefaultClient:initialize::power resource management disabled
2021-04-13 11:24:54.212 [  1] 7 DefaultClient:initialize::using default null SystemTimeZone
2021-04-13 11:24:54.213 [  1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-04-13 11:24:54.213 [  1] 5 DefaultClient:nullCaptionManager::captions disabled
2021-04-13 11:24:54.219 [  1] 5 DefaultClient:nullBluetoothNotifier::Bluetooth disabled
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.220 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.221 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.221 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.221 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.221 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.221 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:24
2021-04-13 11:24:54.221 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.222 [  1] 8 DialogUXStateAggregator:timeout values in milliseconds:m_timeoutForThinkingToIdle=8000,m_shortTimeoutForThinkingToIdle=200,m_timeoutForListeningToIdle=8000
2021-04-13 11:24:54.225 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.226 [  1] 9 ThreadPool:obtainWorker:created=5,obtained=5,releasedToPool=0,releasedFromPool=0,outstanding=5
2021-04-13 11:24:54.227 [  e] 9 TaskThread:startThread:moniker=  e,duration=876
2021-04-13 11:24:54.227 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=10
2021-04-13 11:24:54.227 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.228 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveSequencer
2021-04-13 11:24:54.228 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.228 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=11
2021-04-13 11:24:54.229 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.229 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveProcessor
2021-04-13 11:24:54.229 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.231 [  f] 9 DirectiveProcessor:processingLoop
2021-04-13 11:24:54.231 [ 10] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.231 [  f] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.231 [ 10] 9 PowerMonitor:assignThreadPowerResource:threadId=1723802672
2021-04-13 11:24:54.231 [  f] 9 PowerMonitor:assignThreadPowerResource:threadId=1732195376
2021-04-13 11:24:54.231 [ 10] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.232 [  f] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.232 [  1] 5 CapabilitiesDelegate:setMessageSender
2021-04-13 11:24:54.232 [ 10] 5 ConditionVariableWrapper:waitInner
2021-04-13 11:24:54.232 [  f] 5 ConditionVariableWrapper:waitInner
2021-04-13 11:24:54.232 [  1] 5 AVSGatewayManager:addObserver
2021-04-13 11:24:54.234 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.234 [  1] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=DISCONNECTED
2021-04-13 11:24:54.235 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.235 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:25
2021-04-13 11:24:54.235 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.236 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x12360b4
2021-04-13 11:24:54.237 [  1] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=UNINITIALIZED,error=UNINITIALIZED,callback=true
2021-04-13 11:24:54.237 [  1] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged
2021-04-13 11:24:54.238 [  1] 9 ThreadPool:obtainWorker:created=6,obtained=6,releasedToPool=0,releasedFromPool=0,outstanding=6
2021-04-13 11:24:54.239 [ 11] 9 TaskThread:startThread:moniker= 11,duration=1422
2021-04-13 11:24:54.239 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.240 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.240 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.243 [  1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-04-13 11:24:54.243 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.244 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2021-04-13 11:24:54.244 [ 12] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2021-04-13 11:24:54.245 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=3
2021-04-13 11:24:54.246 [  1] 9 MediaPlayer:addObserverCalled:name=SystemSoundMediaPlayer
2021-04-13 11:24:54.246 [ 12] 0 MediaPlayer:handleAddObserverCalled:name=SystemSoundMediaPlayer
2021-04-13 11:24:54.249 [  1] 5 AudioInputProcessor:getSpeechRecognizerCapabilityConfiguration:wakeWords={"wakeWords":[{"scopes":["DEFAULT"],"values":[["ALEXA"]]}]}
2021-04-13 11:24:54.250 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.251 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.251 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.252 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.252 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:26
2021-04-13 11:24:54.252 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.254 [  1] 9 ThreadPool:obtainWorker:created=7,obtained=7,releasedToPool=0,releasedFromPool=0,outstanding=7
2021-04-13 11:24:54.255 [ 13] 9 TaskThread:startThread:moniker= 13,duration=829
2021-04-13 11:24:54.255 [  1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-04-13 11:24:54.255 [ 13] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=IDLE
2021-04-13 11:24:54.256 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.256 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer
2021-04-13 11:24:54.256 [ 14] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer
2021-04-13 11:24:54.257 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=4
2021-04-13 11:24:54.259 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.259 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:27
2021-04-13 11:24:54.259 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.260 [  1] 9 MediaPlayer:addObserverCalled:name=SpeakMediaPlayer
2021-04-13 11:24:54.261 [ 14] 0 MediaPlayer:handleAddObserverCalled:name=SpeakMediaPlayer
2021-04-13 11:24:54.261 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.262 [  1] 9 SpeechSynthesizer:addObserver:observer=0x1052adc
2021-04-13 11:24:54.263 [  e] 9 ThreadPool:obtainWorker:created=8,obtained=8,releasedToPool=0,releasedFromPool=0,outstanding=8
2021-04-13 11:24:54.264 [ 15] 9 TaskThread:startThread:moniker= 15,duration=1261
2021-04-13 11:24:54.264 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.265 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer
2021-04-13 11:24:54.265 [ 16] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer
2021-04-13 11:24:54.266 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=5
2021-04-13 11:24:54.267 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.267 [  e] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=IDLE,validTransition=false
2021-04-13 11:24:54.267 [  1] 5 NotificationRenderer:create
2021-04-13 11:24:54.268 [  1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-04-13 11:24:54.268 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2021-04-13 11:24:54.268 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer
2021-04-13 11:24:54.269 [ 17] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer
2021-04-13 11:24:54.269 [  1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=2
2021-04-13 11:24:54.270 [  1] 5 NotificationRenderer:create
2021-04-13 11:24:54.270 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.270 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:28
2021-04-13 11:24:54.271 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.271 [  1] 9 MediaPlayer:addObserverCalled:name=NotificationsMediaPlayer
2021-04-13 11:24:54.272 [ 17] 0 MediaPlayer:handleAddObserverCalled:name=NotificationsMediaPlayer
2021-04-13 11:24:54.274 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.274 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:29
2021-04-13 11:24:54.274 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.275 [  1] 5 NotificationsCapabilityAgent:init
2021-04-13 11:24:54.280 [  1] 9 ThreadPool:obtainWorker:created=9,obtained=9,releasedToPool=0,releasedFromPool=0,outstanding=9
2021-04-13 11:24:54.281 [ 18] 9 TaskThread:startThread:moniker= 18,duration=1060
2021-04-13 11:24:54.281 [ 18] 5 NotificationsCapabilityAgent:executeInit
2021-04-13 11:24:54.281 [ 18] 5 NotificationRenderer:addObserver
2021-04-13 11:24:54.282 [  1] 5 InteractionModel:InteractionModelCapabilityAgent
2021-04-13 11:24:54.282 [ 18] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.284 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.284 [ 18] 5 NotificationsCapabilityAgent:notifyObserversOfIndicatorState:indicatorState=0
2021-04-13 11:24:54.284 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:30
2021-04-13 11:24:54.285 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.285 [ 18] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0,isEnabled=false
2021-04-13 11:24:54.285 [  5] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=419,contentLength=419,currentContentReceived=419,remaining=0,totalBytesWritten=419
2021-04-13 11:24:54.286 [  5] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=http://spectrum.s3.amazonaws.com/kindle-wifi/wifistub.html
2021-04-13 11:24:54.286 [  1] 5 ContextManager:addStateProvider
2021-04-13 11:24:54.287 [ 18] 5 ContextManager:setState
2021-04-13 11:24:54.287 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.287 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:31
2021-04-13 11:24:54.288 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.288 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.288 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.288 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.288 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.289 [  1] 5 PowerMonitor:getPowerResourceManager
2021-04-13 11:24:54.289 [  1] E PowerResource:create:error=nullPowerManager
2021-04-13 11:24:54.287 [  5] 9 LibCurlHttpContentFetcher:transferThread::end
2021-04-13 11:24:54.290 [  5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition
2021-04-13 11:24:54.290 [  1] 5 SDKPrimitivesProvider:getInstance
2021-04-13 11:24:54.291 [  1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-04-13 11:24:54.291 [  1] 5 SDKPrimitivesProvider:isInitialized
2021-04-13 11:24:54.292 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.292 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:32
2021-04-13 11:24:54.293 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.293 [  1] 5 ExternalMediaPlayer:setObserver
2021-04-13 11:24:54.294 [  1] 1 AudioPlayer:setObserver
2021-04-13 11:24:54.295 [  1] 9 ThreadPool:obtainWorker:created=10,obtained=10,releasedToPool=0,releasedFromPool=0,outstanding=10
2021-04-13 11:24:54.296 [ 19] 9 TaskThread:startThread:moniker= 19,duration=922
2021-04-13 11:24:54.297 [  1] 5 ExternalCapabilitiesBuilder:withTemplateRunTime
2021-04-13 11:24:54.298 [  e] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2021-04-13 11:24:54.299 [  e] 9 ThreadPool:obtainWorker:created=11,obtained=11,releasedToPool=0,releasedFromPool=0,outstanding=11
2021-04-13 11:24:54.301 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=1841
2021-04-13 11:24:54.302 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.302 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:33
2021-04-13 11:24:54.302 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.304 [  1] 5 ContextManager:setState
2021-04-13 11:24:54.308 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.309 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:34
2021-04-13 11:24:54.309 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.310 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.310 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:35
2021-04-13 11:24:54.311 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.315 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=12
2021-04-13 11:24:54.320 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.320 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:36
2021-04-13 11:24:54.320 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.321 [  1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:54.325 [  1] 5 SystemCapabilityProvider:getSystemCapabilityConfiguration:locales={"locales":["de-DE","en-AU","en-CA","en-GB","en-IN","en-US","es-ES","es-MX","es-US","fr-CA","fr-FR","hi-IN","it-IT","ja-JP","pt-BR"],"localeCombinations":[["en-CA","fr-CA"],["en-IN","hi-IN"],["en-US","es-US"],["es-US","en-US"],["fr-CA","en-CA"],["hi-IN","en-IN"]]}
2021-04-13 11:24:54.326 [  1] 5 ApiGateway:create
2021-04-13 11:24:54.327 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:54.327 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:37
2021-04-13 11:24:54.327 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:54.328 [  1] 0 DefaultClient:initialize::Diagnostics Not Enabled
2021-04-13 11:24:54.328 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.329 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.330 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.330 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.331 [  1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-04-13 11:24:54.331 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.331 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.332 [  1] 5 DefaultEndpointBuilder:withCapability
2021-04-13 11:24:54.333 [  1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-04-13 11:24:54.335 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetLocales,handler=0x1107408,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:54.336 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetTimeZone,handler=0x1231b68,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:54.338 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ReportState,handler=0x1124460,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:54.339 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x10f1cb4,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:54.339 [  1] 5 ExternalCapabilitiesBuilder:buildCapabilities
2021-04-13 11:24:54.340 [  1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-04-13 11:24:54.357 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2021-04-13 11:24:54.357 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x11136bc
2021-04-13 11:24:54.358 [  1] 5 NotificationsCapabilityAgent:addObserver
2021-04-13 11:24:54.359 [  1] 5 DefaultClient:addBluetoothDeviceObserver::Bluetooth disabled
#################################################
NOTIFICATION INDICATOR STATE: OFF
#################################################
2021-04-13 11:24:54.365 [  1] 5 TemplateRuntime:addObserver
2021-04-13 11:24:54.366 [ 1a] 5 TemplateRuntime:addObserverInExecutor
2021-04-13 11:24:54.387 [  4] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes
2021-04-13 11:24:54.388 [  4] 9 InternetConnectionMonitor:testConnection::Finished reading
2021-04-13 11:24:54.388 [  4] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
2021-04-13 11:24:54.388 [  4] 5 InternetConnectionMonitor:notifyObserversLocked
2021-04-13 11:24:54.388 [  4] 5 AVSConnectionManager:onConnectionStatusChanged:connected=true,isEnabled=false
2021-04-13 11:24:54.390 [  4] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher
2021-04-13 11:24:54.569 [  2] 5 HttpPost:doPostSucceeded:code=200
2021-04-13 11:24:54.578 [  2] 5 CBLAuthDelegate:receiveTokenResponse:code=200
2021-04-13 11:24:54.579 [  2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS
2021-04-13 11:24:54.582 [  2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
2021-04-13 11:24:54.582 [  2] 5 CBLAuthDelegate:setRefreshToken
2021-04-13 11:24:54.583 [  2] 5 SQLiteCBLAuthDelegateStorage:setRefreshToken
2021-04-13 11:24:54.609 [  2] 5 CBLAuthDelegate:setAuthState:newAuthState=REFRESHED
2021-04-13 11:24:54.610 [  2] 9 CBLAuthDelegate:callingOnAuthStateChange:state=REFRESHED,error=SUCCESS
###########################
Authorized!
###########################
Cannot connect to server socket err = No such file or directory
Cannot connect to server request channel
jack server is not running or cannot be started
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Cannot connect to server socket err = No such file or directory
Cannot connect to server request channel
jack server is not running or cannot be started
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
2021-04-13 11:24:57.021 [  1] I SensoryKeywordDetector:License expires on 28 Jun 2021 00:00:00 GMT
2021-04-13 11:24:57.022 [  1] I SensoryKeywordDetector:Sensory library license does not expire for at least 60 more days.
2021-04-13 11:24:57.060 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.062 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:38
2021-04-13 11:24:57.063 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.064 [  1] I PortAudioMicrophoneWrapper:startStreamingMicrophoneData
2021-04-13 11:24:57.067 [  1] 9 ThreadPool:obtainWorker:created=12,obtained=12,releasedToPool=12,releasedFromPool=0,outstanding=0
2021-04-13 11:24:57.069 [  a] 9 TaskThread:startThread:moniker=  a,duration=1688
2021-04-13 11:24:57.069 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0x12af658
2021-04-13 11:24:57.069 [  a] 9 ThreadPool:obtainWorker:created=12,obtained=13,releasedToPool=12,releasedFromPool=0,outstanding=1
2021-04-13 11:24:57.070 [  d] 9 TaskThread:startThread:moniker=  d,duration=456
2021-04-13 11:24:57.072 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x12af65c
2021-04-13 11:24:57.080 [  1] 2 EndpointBuilder:buildImplementation:isDefault=true,#capabilities=23
2021-04-13 11:24:57.081 [  1] 5 EndpointRegistrationManager:registerEndpoint
2021-04-13 11:24:57.082 [  1] 9 ThreadPool:obtainWorker:created=12,obtained=14,releasedToPool=12,releasedFromPool=0,outstanding=2
2021-04-13 11:24:57.085 [ 11] 9 TaskThread:startThread:moniker= 11,duration=3025
2021-04-13 11:24:57.087 [ 11] 5 EndpointRegistrationManager:executeRegisterEndpoint
2021-04-13 11:24:57.090 [ 11] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=Alexa.SoftwareComponentReporter
2021-04-13 11:24:57.091 [ 11] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=System
2021-04-13 11:24:57.091 [ 11] 5 EqualizerController:getConfiguration
2021-04-13 11:24:57.093 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=SetMode,handler=0x1059f84,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.095 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=SetBands,handler=0x1059f84,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.096 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=ResetBands,handler=0x1059f84,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.097 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=AdjustBands,handler=0x1059f84,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.100 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=ClearIndicator,handler=0x123aafc,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.101 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=SetIndicator,handler=0x123aafc,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.103 [ 11] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=VisualActivityTracker
2021-04-13 11:24:57.103 [ 11] 5 TemplateRuntime:getConfiguration
2021-04-13 11:24:57.104 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x1254a30,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2021-04-13 11:24:57.105 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x1254a30,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2021-04-13 11:24:57.105 [ 11] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=PlaybackController
2021-04-13 11:24:57.106 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Unfavorite,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.106 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=AdjustSeekPosition,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.107 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=SetSeekPosition,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.107 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableShuffle,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.108 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableShuffle,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.108 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableRepeat,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.108 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Favorite,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.108 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeatOne,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.109 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=StartOver,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.109 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Stop,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.109 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Previous,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.110 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Pause,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.110 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=FastForward,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.110 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Play,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.110 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Rewind,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.111 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Logout,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.111 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeat,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.111 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Login,handler=0x1233b78,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.111 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Play,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.112 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Next,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.112 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=AuthorizeDiscoveredPlayers,handler=0x1233b78,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.113 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=RequestProcessingCompleted,handler=0x123e868,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.113 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=NewDialogRequest,handler=0x123e868,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.113 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=RequestProcessingStarted,handler=0x123e868,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.114 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlarmVolumeRamp,handler=0x11e5f50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.114 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=AdjustVolume,handler=0x11e5f50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.115 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetVolume,handler=0x11e5f50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.115 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlerts,handler=0x11e5f50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.115 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x11e5f50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.115 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x11e5f50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.116 [ 11] 5 DoNotDisturbCapabilityAgent:getConfiguration
2021-04-13 11:24:57.116 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.DoNotDisturb,name=SetDoNotDisturb,handler=0x106b010,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.117 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=UpdateProgressReportInterval,handler=0x1232d08,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.117 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x1232d08,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.117 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x1232d08,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.118 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x1232d08,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2021-04-13 11:24:57.118 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x11df568,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.118 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x11df568,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.119 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x11df568,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.119 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x1239518,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:True
2021-04-13 11:24:57.119 [ 11] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=AudioActivityTracker
2021-04-13 11:24:57.120 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetSpeechConfirmation,handler=0x1238018,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.120 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetWakeWordConfirmation,handler=0x1238018,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.120 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetEndOfSpeechOffset,handler=0x1238018,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.120 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetWakeWords,handler=0x1238018,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.121 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x1238018,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.121 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x1238018,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:True
2021-04-13 11:24:57.121 [ 11] 5 AlexaInterfaceCapabilityAgent:getConfiguration
2021-04-13 11:24:57.121 [ 11] 5 AlexaInterfaceCapabilityAgent:registeringEventProcessedDirective:reason=defaultEndpoint
2021-04-13 11:24:57.122 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa,name=ReportState,handler=0x113f880,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.122 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa,name=EventProcessed,handler=0x113f880,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.123 [ 11] 5 ApiGateway:getConfiguration
2021-04-13 11:24:57.123 [ 11] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.ApiGateway,name=SetGateway,handler=0x1053850,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-04-13 11:24:57.125 [ 11] 5 CapabilitiesDelegate:addOrUpdateEndpoint
2021-04-13 11:24:57.131 [ 11] 9 ThreadPool:obtainWorker:created=12,obtained=15,releasedToPool=12,releasedFromPool=0,outstanding=3
2021-04-13 11:24:57.132 [ 13] 9 TaskThread:startThread:moniker= 13,duration=461
2021-04-13 11:24:57.132 [ 11] 2 EndpointRegistrationManager:executeRegisterEndpoint:result=finished
2021-04-13 11:24:57.132 [ 13] 5 CapabilitiesDelegate:executeSendPendingEndpoints
2021-04-13 11:24:57.133 [ 13] 5 CapabilitiesDelegate:executeSendPendingEndpoints:Deferred=Not connected
2021-04-13 11:24:57.133 [  1] 5 AVSGatewayManager:setAVSGatewayAssigner
2021-04-13 11:24:57.133 [  1] I MessageRouter:setAVSGateway:avsGateway=https://bob-dispatch-prod-eu.amazon.com
2021-04-13 11:24:57.134 [  1] 5 AVSConnectionManager:enable
2021-04-13 11:24:57.134 [  1] I MessageRouter:enable
2021-04-13 11:24:57.134 [  1] I MessageRouter:setConnectionStatusLocked:status=PENDING,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.134 [  1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2021-04-13 11:24:57.134 [  1] 9 ThreadPool:obtainWorker:created=12,obtained=16,releasedToPool=12,releasedFromPool=0,outstanding=4
2021-04-13 11:24:57.135 [  1] 5 LibcurlHTTP2ConnectionFactory:createHTTP2Connection
2021-04-13 11:24:57.135 [ 15] 9 TaskThread:startThread:moniker= 15,duration=763
2021-04-13 11:24:57.137 [ 1b] 5 LibcurlHTTP2Connection:networkLoop
2021-04-13 11:24:57.137 [  1] 5 HTTP2Transport:create:authDelegate=0x10f2cc0,avsGateway=https://bob-dispatch-prod-eu.amazon.com,http2Connection=0x128fc58,messageConsumer=0xff8900,attachmentManager=0x10b82b0,transportObserver=0xff88fc,postConnectFactory=0x11a8ec0,sharedRequestQueue=0x10b7dac
2021-04-13 11:24:57.138 [  1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=13
2021-04-13 11:24:57.139 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.139 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=HTTP2Transport_mainLoop
2021-04-13 11:24:57.139 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.139 [  1] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.140 [  1] 9 PowerMonitor:createLocalPowerResource:identifier=HTTP2Transport_requestActivityResource
2021-04-13 11:24:57.140 [  1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.140 [  1] I MessageRouter:createActiveTransportLocked:transport=0x12b15a8,sizeOf m_transports=0
2021-04-13 11:24:57.140 [  1] I HTTP2Transport:connect:this=0x12b15a8
2021-04-13 11:24:57.141 [  1] I HTTP2Transport:setStateLocked:this=0x12b15a8,currentState=INIT,newState=AUTHORIZING,changedReason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.141 [  1] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.142 [  1] I MessageRouter:setAsActiveTransport:transport=0x12b15a8,sizeOf m_transports=1
2021-04-13 11:24:57.142 [ 15] 0 AVSConnectionManager:onConnectionStatusChanged:status=PENDING,engine_count=1
2021-04-13 11:24:57.143 [ 15] 5 AbstractAVSConnectionManager:notifyObservers::EngineConnectionStatusDetail:engineType=1,status=PENDING,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.142 [ 1c] 7 HTTP2Transport:mainLoop:this=0x12b15a8
2021-04-13 11:24:57.144 [ 15] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=PENDING
2021-04-13 11:24:57.144 [ 1c] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.144 [ 15] 9 ThreadPool:obtainWorker:created=12,obtained=17,releasedToPool=12,releasedFromPool=0,outstanding=5
2021-04-13 11:24:57.145 [ 15] 5 CertifiedSender:onConnectionStatusChanged
2021-04-13 11:24:57.145 [ 19] 9 TaskThread:startThread:moniker= 19,duration=708
#############################
Connecting...
#############################
              #    #     #  #####      #####  ######  #    #
             # #   #     # #     #    #     # #     # #   #
            #   #  #     # #          #       #     # #  #
           #     # #     #  #####      #####  #     # ###
           #######  #   #        #          # #     # #  #
           #     #   # #   #     #    #     # #     # #   #
           #     #    #     #####      #####  ######  #    #

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

   SDK Version 1.23.0

   WARNING! THIS DEVICE HAS BEEN COMPILED IN DEBUG MODE.

   RELEASING A PRODUCTION DEVICE IN DEBUG MODE MAY IMPACT DEVICE PERFORMANCE,
   DOES NOT COMPLY WITH THE AVS SECURITY REQUIREMENTS,
   AND COULD RESULT IN SUSPENSION OR TERMINATION OF THE ALEXA SERVICE ON YOUR DEVICES.

2021-04-13 11:24:57.144 [ 1c] 9 PowerMonitor:assignThreadPowerResource:threadId=1549661232
+----------------------------------------------------------------------------+
|                                  Options:                                  |
| Wake word:                                                                 |
|       Simply say Alexa and begin your query.                               |
| Tap to talk:                                                               |
|       Press 't' and Enter followed by your query (no need for the 'Alexa').|
| Hold to talk:                                                              |
|       Press 'h' followed by Enter to simulate holding a button.            |
|       Then say your query (no need for the 'Alexa').                       |
|       Press 'h' followed by Enter to simulate releasing a button.          |
| Stop an interaction:                                                       |
|       Press 's' and Enter to stop an ongoing interaction.                  |
| Privacy mode (microphone off):                                             |
|       Press 'm' and Enter to turn on and off the microphone.               |
| Playback Controls:                                                         |
|       Press '1' for a 'PLAY' button press.                                 |
|       Press '2' for a 'PAUSE' button press.                                |
|       Press '3' for a 'NEXT' button press.                                 |
|       Press '4' for a 'PREVIOUS' button press.                             |
| Settings:                                                                  |
|       Press 'c' followed by Enter at any time to see the settings screen.  |
| Speaker Control:                                                           |
|       Press 'p' followed by Enter at any time to adjust speaker settings.  |
| Firmware Version:                                                          |
|       Press 'f' followed by Enter at any time to report a different        |
|       firmware version.                                                    |
| Info:                                                                      |
|       Press 'i' followed by Enter at any time to see the help screen.      |
| Reset device:                                                              |
|       Press 'k' followed by Enter at any time to reset your device. This   |
|       will erase any data stored in the device and you will have to        |
|       re-register your device.                                             |
|       This option will also exit the application.                          |
| Reauthorize device:                                                        |
|       Press 'z' followed by Enter at any time to re-authorize your device. |
|       This will erase any data stored in the device and initiate           |
|       re-authorization.                                                    |
|                                                                            |
| Quit:                                                                      |
|       Press 'q' followed by Enter at any time to quit the application.     |
+----------------------------------------------------------------------------+
2021-04-13 11:24:57.149 [ 1c] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.147 [ 15] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.151 [ 15] 9 ThreadPool:obtainWorker:created=12,obtained=18,releasedToPool=12,releasedFromPool=0,outstanding=6
2021-04-13 11:24:57.151 [  a] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=IDLE,validTransition=false
2021-04-13 11:24:57.152 [  e] 9 TaskThread:startThread:moniker=  e,duration=1464
2021-04-13 11:24:57.152 [  e] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.154 [ 1c] 5 SynchronizeStateSenderFactory:createPostConnectOperation
2021-04-13 11:24:57.154 [ 1c] 5 PostConnectSynchronizeStateSender:create
2021-04-13 11:24:57.155 [ 1c] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=14
2021-04-13 11:24:57.155 [ 1c] 5 AVSGatewayManager:createPostConnectOperation
2021-04-13 11:24:57.156 [ 1c] 5 AVSGatewayManager:createPostConnectOperation::Gateway already verified, skipping gateway verification step
2021-04-13 11:24:57.156 [ 1c] 5 CapabilitiesDelegate:createPostConnectOperation
2021-04-13 11:24:57.156 [ 1c] 5 CapabilitiesDelegate:resetCurrentDiscoveryEventSender
2021-04-13 11:24:57.156 [ 1c] 5 CapabilitiesDelegate:moveInFlightEndpointsToPendingLocked
2021-04-13 11:24:57.157 [ 1c] 5 SQLiteCapabilitiesDelegateStorage:load
2021-04-13 11:24:57.158 [ 1c] 5 CapabilitiesDelegate:createPostConnectOperation:num endpoints stored=1
2021-04-13 11:24:57.158 [ 1c] 5 CapabilitiesDelegate:filterUnchangedPendingAddOrUpdateEndpointsLocked
2021-04-13 11:24:57.163 [ 1c] 9 CapabilitiesDelegate:filterUnchangedPendingAddOrUpdateEndpointsLocked:step=endpoint not be included in addOrUpdateReport
2021-04-13 11:24:57.164 [ 1c] 5 CapabilitiesDelegate:addStaleEndpointsToPendingDeleteLocked
2021-04-13 11:24:57.164 [ 1c] 5 CapabilitiesDelegate:setCapabilitiesState:newCapabilitiesState=SUCCESS
2021-04-13 11:24:57.165 [ 1c] 9 CapabilitiesDelegate:callingOnCapabilitiesStateChange:state=SUCCESS,error=SUCCESS
2021-04-13 11:24:57.166 [ 1c] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=SUCCESS,error=SUCCESS,callback=true
#######################################################
Successfully registered 1 endpoint(s).
#######################################################
2021-04-13 11:24:57.166 [ 1c] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged
2021-04-13 11:24:57.168 [ 11] 9 EndpointRegistrationManager:updateAddedOrUpdatedEndpoints:result=success
2021-04-13 11:24:57.169 [ 1c] 5 CapabilitiesDelegate:createPostConnectOperation::No change in Capabilities, skipping post connect step
2021-04-13 11:24:57.170 [ 1c] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.170 [ 1c] 9 PowerMonitor:createLocalPowerResource:identifier=PostConnectSequencer_mainLoop
2021-04-13 11:24:57.170 [ 1c] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.171 [ 1c] 5 PostConnectSequencer:doPostConnect
2021-04-13 11:24:57.172 [ 1c] I HTTP2Transport:handleAuthorizing:this=0x12b15a8
2021-04-13 11:24:57.172 [ 1d] 5 PostConnectSequencer:mainLoop
2021-04-13 11:24:57.173 [ 1d] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.173 [ 1c] 5 CBLAuthDelegate:addAuthObserver:observer=0x12b15f0
2021-04-13 11:24:57.173 [ 1d] 9 PowerMonitor:assignThreadPowerResource:threadId=1541268528
2021-04-13 11:24:57.174 [ 1d] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.174 [ 1d] 5 PostConnectSynchronizeStateSender:performOperation
2021-04-13 11:24:57.174 [ 1c] I HTTP2Transport:onAuthStateChange:this=0x12b15a8,newState=REFRESHED,error=SUCCESS
2021-04-13 11:24:57.175 [ 1c] I HTTP2Transport:setStateLocked:this=0x12b15a8,currentState=AUTHORIZING,newState=CONNECTING,changedReason=SUCCESS
2021-04-13 11:24:57.176 [ 1c] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.175 [ 1d] 5 PostConnectSynchronizeStateSender:fetchContext
2021-04-13 11:24:57.176 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=3599999999999839897
2021-04-13 11:24:57.176 [ 1d] 5 ContextManager:getContext
2021-04-13 11:24:57.177 [ 1c] I HTTP2Transport:handleConnecting:this=0x12b15a8
2021-04-13 11:24:57.177 [ 1d] 5 ContextManager:getContextInternal
2021-04-13 11:24:57.177 [ 1c] 9 DownchannelHandler:create:context=0x12b15f8
2021-04-13 11:24:57.178 [ 1d] 9 ThreadPool:obtainWorker:created=12,obtained=19,releasedToPool=12,releasedFromPool=0,outstanding=7
2021-04-13 11:24:57.178 [ 1c] 5 ExchangeHandler:ExchangeHandler:context=0x12b15f8
2021-04-13 11:24:57.178 [ 1c] 9 DownchannelHandler:DownchannelHandler:context=0x12b15f8
2021-04-13 11:24:57.179 [  c] 9 TaskThread:startThread:moniker=  c,duration=922
2021-04-13 11:24:57.182 [  c] 9 ThreadPool:obtainWorker:created=12,obtained=20,releasedToPool=12,releasedFromPool=0,outstanding=8
2021-04-13 11:24:57.183 [  b] 9 TaskThread:startThread:moniker=  b,duration=1202
2021-04-13 11:24:57.185 [  c] 9 SpeechSynthesizer:provideState:token=1
2021-04-13 11:24:57.186 [  c] 9 ThreadPool:obtainWorker:created=12,obtained=21,releasedToPool=12,releasedFromPool=0,outstanding=9
2021-04-13 11:24:57.187 [ 18] 9 TaskThread:startThread:moniker= 18,duration=866
2021-04-13 11:24:57.187 [ 18] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1,state=FINISHED
2021-04-13 11:24:57.188 [ 18] 5 ContextManager:setState
2021-04-13 11:24:57.179 [ 1c] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.188 [ 1c] 9 PowerMonitor:createLocalPowerResource:identifier=DownchannelHandler
2021-04-13 11:24:57.189 [ 1c] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-04-13 11:24:57.189 [  c] 5 VisualActivityTracker:provideState
2021-04-13 11:24:57.179 [ 1d] 9 ConditionVariableWrapper:waitForInner:duration=2000000000
2021-04-13 11:24:57.190 [  c] 9 ThreadPool:obtainWorker:created=12,obtained=22,releasedToPool=12,releasedFromPool=0,outstanding=10
2021-04-13 11:24:57.191 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=637
2021-04-13 11:24:57.191 [ 1c] 9 MimeResponseSink:MimeResponseSink:handler=0x6d706510
2021-04-13 11:24:57.191 [ 1a] 5 VisualActivityTracker:executeProvideState
2021-04-13 11:24:57.191 [ 1a] 5 ContextManager:setState
2021-04-13 11:24:57.192 [  c] 0 AudioPlayer:provideState:stateRequestToken=1
2021-04-13 11:24:57.192 [  c] 9 ThreadPool:obtainWorker:created=12,obtained=23,releasedToPool=12,releasedFromPool=0,outstanding=11
2021-04-13 11:24:57.193 [ 1c] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder
2021-04-13 11:24:57.193 [  3] 9 TaskThread:startThread:moniker=  3,duration=363
2021-04-13 11:24:57.193 [ 1c] 7 HTTP2Transport:createAndSendRequest:this=0x12b15a8,type=GET
2021-04-13 11:24:57.193 [  3] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2021-04-13 11:24:57.193 [  c] 9 ThreadPool:obtainWorker:created=12,obtained=24,releasedToPool=12,releasedFromPool=0,outstanding=12
2021-04-13 11:24:57.195 [  3] 5 ContextManager:setState
2021-04-13 11:24:57.195 [ 1c] 9 DownchannelHandler:getRequestHeaderLines
2021-04-13 11:24:57.196 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=2206
2021-04-13 11:24:57.196 [  c] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=1
2021-04-13 11:24:57.198 [  c] 9 ThreadPool:obtainWorker:created=13,obtained=25,releasedToPool=12,releasedFromPool=0,outstanding=13
2021-04-13 11:24:57.197 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=3599999999979143560
2021-04-13 11:24:57.198 [ 1b] 9 LibcurlHTTP2Connection:insertActiveStream:handle=0x6d70cca8,streamId=AVSDownChannel-1
2021-04-13 11:24:57.196 [ 1e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2021-04-13 11:24:57.202 [ 1e] 0 ExternalMediaPlayer:executeProvideState:provideSessionState={"agent":"CQCAFYNYDC","spiVersion":"1.0","playerInFocus":"","players":[]}
2021-04-13 11:24:57.203 [ 1e] 5 ContextManager:setState
2021-04-13 11:24:57.199 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=1211
2021-04-13 11:24:57.204 [ 1f] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=1,isEnabled=false
2021-04-13 11:24:57.205 [ 1f] 5 ContextManager:setState
2021-04-13 11:24:57.199 [  c] 5 AudioActivityTracker:provideState
2021-04-13 11:24:57.207 [  c] 9 ThreadPool:obtainWorker:created=14,obtained=26,releasedToPool=12,releasedFromPool=0,outstanding=14
2021-04-13 11:24:57.209 [ 20] 9 TaskThread:startThread:moniker= 20,duration=1765
2021-04-13 11:24:57.209 [ 20] 5 AudioActivityTracker:executeProvideState
2021-04-13 11:24:57.209 [ 20] 5 ContextManager:setState
2021-04-13 11:24:57.210 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=7
2021-04-13 11:24:57.213 [ 1e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2021-04-13 11:24:57.215 [ 1e] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers
2021-04-13 11:24:57.216 [ 1e] 0 ExternalMediaPlayer:executeProvideState:providePlaybackState={"state":"IDLE","supportedOperations":[],"shuffle":"NOT_SHUFFLED","repeat":"NOT_REPEATED","favorite":"NOT_RATED","positionMilliseconds":0,"players":[]}
2021-04-13 11:24:57.216 [ 1e] 5 ContextManager:setState
2021-04-13 11:24:57.220 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=6
2021-04-13 11:24:57.221 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=5
2021-04-13 11:24:57.222 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=4
2021-04-13 11:24:57.223 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=3
2021-04-13 11:24:57.224 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=2
2021-04-13 11:24:57.227 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=1
2021-04-13 11:24:57.228 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2021-04-13 11:24:57.228 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.231 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.231 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.232 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:VisualActivityTracker,name:ActivityState}
2021-04-13 11:24:57.232 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.232 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.232 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.233 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:AudioActivityTracker,name:ActivityState}
2021-04-13 11:24:57.233 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.233 [  c] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2021-04-13 11:24:57.243 [  c] 5 AVSContext:toJson
2021-04-13 11:24:57.243 [  c] 5 PostConnectSynchronizeStateSender:onContextAvailable
2021-04-13 11:24:57.243 [  c] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.250 [ 1d] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32
2021-04-13 11:24:57.252 [ 1d] 0 EventBuilder:buildJsonEventString:messageId=9fbdcaa2-5ae9-4d89-ab67-80e4db04eba9,namespace=System,name=SynchronizeState
2021-04-13 11:24:57.252 [ 1d] 0 EventBuilder:buildJsonEventString:event={"context":{"properties":[{"namespace":"Alerts","name":"AlertsState","value":{"allAlerts":[],"activeAlerts":[]},"timeOfSample":"2021-04-13T11:24:54.166Z","uncertaintyInMilliseconds":0},{"namespace":"Alexa.PlaybackStateReporter","name":"playbackState","value":{"state":"IDLE","supportedOperations":[],"shuffle":"NOT_SHUFFLED","repeat":"NOT_REPEATED","favorite":"NOT_RATED","positionMilliseconds":0,"players":[]},"timeOfSample":"2021-04-13T11:24:57.224Z","uncertaintyInMilliseconds":0},{"namespace":"AudioPlayer","name":"PlaybackState","value":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"},"timeOfSample":"2021-04-13T11:24:57.222Z","uncertaintyInMilliseconds":0},{"namespace":"EqualizerController","name":"EqualizerState","value":{"bands":[{"name":"TREBLE","level":0},{"name":"BASS","level":0},{"name":"MIDRANGE","level":0}]},"timeOfSample":"2021-04-13T11:24:54.305Z","uncertaintyInMilliseconds":0},{"namespace":"ExternalMediaPlayer","name":"ExternalMediaPlayerState","value":{"agent":"CQCAFYNYDC","spiVersion":"1.0","playerInFocus":"","players":[]},"timeOfSample":"2021-04-13T11:24:57.222Z","uncertaintyInMilliseconds":0},{"namespace":"Notifications","name":"IndicatorState","value":{"isEnabled":false,"isVisualIndicatorPersisted":false},"timeOfSample":"2021-04-13T11:24:57.226Z","uncertaintyInMilliseconds":0},{"namespace":"Speaker","name":"VolumeState","value":{"volume":100,"muted":false},"timeOfSample":"2021-04-13T11:24:54.204Z","uncertaintyInMilliseconds":0},{"namespace":"SpeechSynthesizer","name":"SpeechState","value":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"},"timeOfSample":"2021-04-13T11:24:57.212Z","uncertaintyInMilliseconds":0}]},"event":{"header":{"namespace":"System","name":"SynchronizeState","messageId":"9fbdcaa2-5ae9-4d89-ab67-80e4db04eba9"},"payload":{}}}
2021-04-13 11:24:57.255 [ 1d] 7 HTTP2Transport:sendMessage:this=0x12b15a8
2021-04-13 11:24:57.256 [ 1d] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.522 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=13,userData=0x6d7066a8
2021-04-13 11:24:57.523 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=200
2021-04-13 11:24:57.524 [ 1b] 9 MimeResponseSink:onReceiveResponseCode:responseCode=200
2021-04-13 11:24:57.524 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.524 [ 1b] 5 DownchannelHandler:onReceiveResponseCode:responseCode=200
2021-04-13 11:24:57.524 [ 1b] I HTTP2Transport:onDownchannelConnected:this=0x12b15a8
2021-04-13 11:24:57.525 [ 1b] I HTTP2Transport:setStateLocked:this=0x12b15a8,currentState=CONNECTING,newState=POST_CONNECTING,changedReason=SUCCESS
2021-04-13 11:24:57.525 [ 1b] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.525 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 200
2021-04-13 11:24:57.526 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 200
2021-04-13 11:24:57.526 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.526 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=64,userData=0x6d7066a8
2021-04-13 11:24:57.526 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-headers: authorization,x-amz-access-token
2021-04-13 11:24:57.526 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-headers: authorization,x-amz-access-token
2021-04-13 11:24:57.526 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.527 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=32,userData=0x6d7066a8
2021-04-13 11:24:57.527 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:57.527 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:57.527 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.527 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=81,userData=0x6d7066a8
2021-04-13 11:24:57.528 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=content-type: multipart/related; boundary=------abcde123; type=application/json
2021-04-13 11:24:57.527 [ 1c] I HTTP2Transport:handlePostConnecting:this=0x12b15a8
2021-04-13 11:24:57.529 [ 1c] 7 HTTP2Transport:sendMessagesAndPings:this=0x12b15a8,whileState=POST_CONNECTING
2021-04-13 11:24:57.529 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:boundary=------abcde123
2021-04-13 11:24:57.529 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=content-type: multipart/related; boundary=------abcde123; type=application/json
2021-04-13 11:24:57.530 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.529 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=299998193248
2021-04-13 11:24:57.531 [ 1c] 7 MessageRequestHandler:create:context=0x12b15f8,messageRequest=0x6eb051f0
2021-04-13 11:24:57.531 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=20,userData=0x6d7066a8
2021-04-13 11:24:57.533 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid:
2021-04-13 11:24:57.533 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid:
2021-04-13 11:24:57.533 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.534 [ 1b] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid:
2021-04-13 11:24:57.534 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=2,userData=0x6d7066a8
2021-04-13 11:24:57.534 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=
2021-04-13 11:24:57.534 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=
2021-04-13 11:24:57.534 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.534 [ 1b] 9 LibcurlHTTP2Request:writeCallback:id=AVSDownChannel-1,size=1,nmemb=18,userData=0x6d7066a8
2021-04-13 11:24:57.535 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveData:size=18
2021-04-13 11:24:57.533 [ 1c] 5 ExchangeHandler:ExchangeHandler:context=0x12b15f8
2021-04-13 11:24:57.536 [ 1c] 7 MessageRequestHandler:MessageRequestHandler:context=0x12b15f8,messageRequest=0x6eb051f0
2021-04-13 11:24:57.538 [ 1c] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie=,source=0x6d70a63c
2021-04-13 11:24:57.539 [ 1c] 9 MimeResponseSink:MimeResponseSink:handler=0x6d70a640
2021-04-13 11:24:57.540 [ 1c] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder
2021-04-13 11:24:57.540 [ 1c] 7 HTTP2Transport:onMessageRequestSent:this=0x12b15a8,countOfUnfinishedMessageHandlers=1
2021-04-13 11:24:57.541 [ 1c] 7 HTTP2Transport:createAndSendRequest:this=0x12b15a8,type=POST
2021-04-13 11:24:57.541 [ 1c] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines
2021-04-13 11:24:57.542 [ 1c] 9 MessageRequestHandler:getRequestHeaderLines
2021-04-13 11:24:57.542 [ 1c] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.542 [ 1c] 0 MessageRequestHandler:EventSent
2021-04-13 11:24:57.543 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=299999187613
2021-04-13 11:24:57.637 [ 1b] 9 LibcurlHTTP2Connection:insertActiveStream:handle=0x6d7131c8,streamId=AVSEvent-3
2021-04-13 11:24:57.638 [ 1b] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-3,size=1,nmemb=65536,userData=0x6d711c78
2021-04-13 11:24:57.639 [ 1b] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW
2021-04-13 11:24:57.641 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS
2021-04-13 11:24:57.642 [ 1b] 9 MessageRequestHandler:getMimePartHeaderLines
2021-04-13 11:24:57.642 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.643 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY
2021-04-13 11:24:57.643 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS
2021-04-13 11:24:57.643 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA
2021-04-13 11:24:57.643 [ 1b] 9 MessageRequestHandler:onSendMimePartData:size=65429
2021-04-13 11:24:57.643 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.643 [ 1b] 9 MessageRequestHandler:onSendMimePartData:size=63614
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS
2021-04-13 11:24:57.644 [ 1b] 9 MessageRequestHandler:getMimePartHeaderLines
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES
2021-04-13 11:24:57.644 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE
2021-04-13 11:24:57.645 [ 1b] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-3,size=1,nmemb=65536,userData=0x6d711c78
2021-04-13 11:24:57.645 [ 1b] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE
2021-04-13 11:24:57.823 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=13,userData=0x6d711c78
2021-04-13 11:24:57.824 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:57.824 [ 1b] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:57.824 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.824 [ 1b] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:57.824 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:57.824 [ 1b] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x12b15a8
2021-04-13 11:24:57.824 [ 1b] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.825 [ 1b] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS_NO_CONTENT
2021-04-13 11:24:57.826 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204
2021-04-13 11:24:57.827 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204
2021-04-13 11:24:57.827 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.827 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=56,userData=0x6d711c78
2021-04-13 11:24:57.827 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: a41f0b8f-b795-4cb8-b52b-a84678bc7876
2021-04-13 11:24:57.827 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: a41f0b8f-b795-4cb8-b52b-a84678bc7876
2021-04-13 11:24:57.828 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.828 [ 1b] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: a41f0b8f-b795-4cb8-b52b-a84678bc7876
2021-04-13 11:24:57.829 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=82,userData=0x6d711c78
2021-04-13 11:24:57.829 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-3
2021-04-13 11:24:57.829 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-3
2021-04-13 11:24:57.829 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.829 [ 1b] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-3
2021-04-13 11:24:57.829 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=32,userData=0x6d711c78
2021-04-13 11:24:57.830 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:57.830 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:57.830 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.830 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=2,userData=0x6d711c78
2021-04-13 11:24:57.830 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=
2021-04-13 11:24:57.831 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=
2021-04-13 11:24:57.831 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.832 [ 1b] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE
2021-04-13 11:24:57.833 [ 1b] 9 MimeResponseSink:onResponseFinished:status=COMPLETE
2021-04-13 11:24:57.833 [ 1b] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204
2021-04-13 11:24:57.833 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:57.833 [ 1b] 7 MessageRequestHandler:reportMessageRequestFinished
2021-04-13 11:24:57.833 [ 1b] 7 HTTP2Transport:onMessageRequestFinished:this=0x12b15a8,countOfUnfinishedMessageHandlers=0
2021-04-13 11:24:57.834 [ 1b] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.834 [ 1d] 5 PostConnectSynchronizeStateSender:performOperation:SynchronizeState event status=SUCCESS_NO_CONTENT
2021-04-13 11:24:57.835 [ 1d] I HTTP2Transport:onPostConnected:this=0x12b15a8
2021-04-13 11:24:57.835 [ 1d] I HTTP2Transport:setStateLocked:this=0x12b15a8,currentState=POST_CONNECTING,newState=CONNECTED,changedReason=SUCCESS
2021-04-13 11:24:57.836 [ 1d] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.836 [ 1d] 5 PostConnectSequencer:mainLoopReturning
2021-04-13 11:24:57.836 [ 1d] 9 PowerMonitor:getInstance
2021-04-13 11:24:57.836 [ 1c] I HTTP2Transport:handleConnected:this=0x12b15a8
2021-04-13 11:24:57.837 [ 1d] 9 PowerMonitor:removeThreadPowerResource:threadId=1541268528
2021-04-13 11:24:57.837 [ 1c] 5 PostConnectSequencer:~PostConnectSequencer
2021-04-13 11:24:57.838 [ 1c] 5 PostConnectSequencer:stop
2021-04-13 11:24:57.837 [ 1b] 7 LibcurlHTTP2Connection:streamFinished:streamId=AVSEvent-3,result=No error,CURLcode=0
2021-04-13 11:24:57.840 [ 1b] 9 LibcurlHTTP2Connection:releaseStream:streamId=AVSEvent-3
2021-04-13 11:24:57.840 [ 1c] 7 HTTP2Transport:notifyObserversOnConnected:this=0x12b15a8
2021-04-13 11:24:57.840 [ 1c] I MessageRouter:onConnected:transport=0x12b15a8,m_activeTransport=0x12b15a8
2021-04-13 11:24:57.841 [ 1c] I MessageRouter:setConnectionStatusLocked:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.841 [ 1c] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2021-04-13 11:24:57.841 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:57.842 [ 1b] 7 MessageRequestHandler:reportMessageRequestFinished
2021-04-13 11:24:57.842 [ 1c] 7 HTTP2Transport:sendMessagesAndPings:this=0x12b15a8,whileState=CONNECTED
2021-04-13 11:24:57.842 [ 15] 0 AVSConnectionManager:onConnectionStatusChanged:status=CONNECTED,engine_count=1
2021-04-13 11:24:57.843 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=299988114088
2021-04-13 11:24:57.843 [ 15] 5 AbstractAVSConnectionManager:notifyObservers::EngineConnectionStatusDetail:engineType=1,status=CONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.844 [ 15] 9 ThreadPool:obtainWorker:created=15,obtained=27,releasedToPool=12,releasedFromPool=0,outstanding=15
2021-04-13 11:24:57.845 [ 21] 9 TaskThread:startThread:moniker= 21,duration=662
2021-04-13 11:24:57.845 [ 15] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=CONNECTED
2021-04-13 11:24:57.845 [ 21] 5 ReportStateHandler:sendReportState:pendingReport=false
2021-04-13 11:24:57.845 [ 13] 5 CapabilitiesDelegate:executeSendPendingEndpoints
2021-04-13 11:24:57.846 [ 15] 9 ThreadPool:obtainWorker:created=16,obtained=28,releasedToPool=12,releasedFromPool=0,outstanding=16
2021-04-13 11:24:57.846 [ 13] 5 CapabilitiesDelegate:executeSendPendingEndpoints:Skipped=No endpoints to register or delete
########################################
Alexa is currently idle!
########################################
2021-04-13 11:24:57.847 [ 22] 9 TaskThread:startThread:moniker= 22,duration=1041
2021-04-13 11:24:57.847 [ 15] 9 ThreadPool:obtainWorker:created=17,obtained=29,releasedToPool=12,releasedFromPool=0,outstanding=17
2021-04-13 11:24:57.849 [ 15] 9 ThreadPool:obtainWorker:created=18,obtained=30,releasedToPool=12,releasedFromPool=0,outstanding=18
2021-04-13 11:24:57.849 [ 23] 9 TaskThread:startThread:moniker= 23,duration=2217
2021-04-13 11:24:57.850 [ 15] 9 ThreadPool:obtainWorker:created=19,obtained=31,releasedToPool=12,releasedFromPool=0,outstanding=19
2021-04-13 11:24:57.850 [ 23] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::networkInfo
2021-04-13 11:24:57.851 [ 15] 5 CertifiedSender:onConnectionStatusChanged
2021-04-13 11:24:57.851 [ 15] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.852 [ 15] 9 ThreadPool:obtainWorker:created=20,obtained=32,releasedToPool=12,releasedFromPool=0,outstanding=20
2021-04-13 11:24:57.853 [ 15] 9 ThreadPool:obtainWorker:created=21,obtained=33,releasedToPool=12,releasedFromPool=0,outstanding=21
2021-04-13 11:24:57.854 [ 27] 9 TaskThread:startThread:moniker= 27,duration=1028
2021-04-13 11:24:57.855 [ 15] 9 ThreadPool:obtainWorker:created=22,obtained=34,releasedToPool=12,releasedFromPool=0,outstanding=22
2021-04-13 11:24:57.855 [ 27] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::wakeWordConfirmation
2021-04-13 11:24:57.855 [ 28] 9 TaskThread:startThread:moniker= 28,duration=930
2021-04-13 11:24:57.856 [ 26] 9 TaskThread:startThread:moniker= 26,duration=4147
2021-04-13 11:24:57.856 [ 28] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::speechConfirmation
2021-04-13 11:24:57.856 [ 26] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::timeZone
2021-04-13 11:24:57.856 [ 25] 9 TaskThread:startThread:moniker= 25,duration=6102
2021-04-13 11:24:57.857 [ 25] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts::alarmVolumeRamp
2021-04-13 11:24:57.857 [ 24] 9 TaskThread:startThread:moniker= 24,duration=8178
2021-04-13 11:24:57.857 [  e] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2021-04-13 11:24:57.859 [ 24] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32
2021-04-13 11:24:57.860 [ 24] 0 EventBuilder:buildJsonEventString:messageId=9b163e93-e18a-488e-bfb3-5afa23cfe3d6,namespace=Alexa.DoNotDisturb,name=ReportDoNotDisturb
2021-04-13 11:24:57.861 [  e] 9 SQLiteAlertStorage:Loading offline alerts
2021-04-13 11:24:57.861 [ 24] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"Alexa.DoNotDisturb","name":"ReportDoNotDisturb","messageId":"9b163e93-e18a-488e-bfb3-5afa23cfe3d6"},"payload":{"enabled":false}}}
2021-04-13 11:24:57.862 [ 24] 7 HTTP2Transport:onRequestEnqueued:this=0x12b15a8
2021-04-13 11:24:57.862 [ 24] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:57.863 [ 1c] 7 MessageRequestHandler:create:context=0x12b15f8,messageRequest=0xfcb624
2021-04-13 11:24:57.864 [ 1c] 5 ExchangeHandler:ExchangeHandler:context=0x12b15f8
2021-04-13 11:24:57.864 [ 1c] 7 MessageRequestHandler:MessageRequestHandler:context=0x12b15f8,messageRequest=0xfcb624
2021-04-13 11:24:57.864 [ 1c] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie=,source=0x6d7126b4
2021-04-13 11:24:57.865 [ 1c] 9 MimeResponseSink:MimeResponseSink:handler=0x6d7126b8
2021-04-13 11:24:57.865 [ 1c] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder
2021-04-13 11:24:57.865 [ 1c] 7 HTTP2Transport:onMessageRequestSent:this=0x12b15a8,countOfUnfinishedMessageHandlers=1
2021-04-13 11:24:57.865 [ 1c] 7 HTTP2Transport:createAndSendRequest:this=0x12b15a8,type=POST
2021-04-13 11:24:57.866 [ 1c] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines
2021-04-13 11:24:57.866 [ 1c] 9 MessageRequestHandler:getRequestHeaderLines
2021-04-13 11:24:57.866 [ 1c] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.867 [ 1c] 0 MessageRequestHandler:EventSent
2021-04-13 11:24:57.867 [ 1c] 9 ConditionVariableWrapper:waitForInner:duration=299999642452
2021-04-13 11:24:57.868 [ 23] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:57.869 [ 27] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:57.869 [ 27] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:setting=SpeechRecognizer::wakeWordConfirmation,synchronized=true
2021-04-13 11:24:57.869 [ 23] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:setting=System::networkInfo,synchronized=true
2021-04-13 11:24:57.870 [ 23] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:result=alreadySynchronized
2021-04-13 11:24:57.870 [ 28] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:57.871 [ 28] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:setting=SpeechRecognizer::speechConfirmation,synchronized=true
2021-04-13 11:24:57.870 [ 27] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:result=alreadySynchronized
2021-04-13 11:24:57.871 [ 28] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:result=alreadySynchronized
2021-04-13 11:24:57.871 [ 26] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:57.872 [ 26] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:setting=System::timeZone,synchronized=true
2021-04-13 11:24:57.872 [ 26] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:result=alreadySynchronized
2021-04-13 11:24:57.873 [ 25] 5 SQLiteDeviceSettingStorage:loadSetting
2021-04-13 11:24:57.874 [ 25] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:setting=Alerts::alarmVolumeRamp,synchronized=true
2021-04-13 11:24:57.874 [ 25] 5 SharedAVSSettingProtocol:executeSynchronizeOnConnected:result=alreadySynchronized
2021-04-13 11:24:57.944 [ 1b] 9 LibcurlHTTP2Connection:insertActiveStream:handle=0x6d712ce8,streamId=AVSEvent-5
2021-04-13 11:24:57.946 [ 1b] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-5,size=1,nmemb=65536,userData=0x6d712c80
2021-04-13 11:24:57.947 [ 1b] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW
2021-04-13 11:24:57.948 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS
2021-04-13 11:24:57.949 [ 1b] 9 MessageRequestHandler:getMimePartHeaderLines
2021-04-13 11:24:57.950 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.951 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY
2021-04-13 11:24:57.951 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS
2021-04-13 11:24:57.952 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA
2021-04-13 11:24:57.953 [ 1b] 9 MessageRequestHandler:onSendMimePartData:size=65429
2021-04-13 11:24:57.953 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.954 [ 1b] 9 MessageRequestHandler:onSendMimePartData:size=65267
2021-04-13 11:24:57.956 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.957 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY
2021-04-13 11:24:57.959 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS
2021-04-13 11:24:57.960 [ 1b] 9 MessageRequestHandler:getMimePartHeaderLines
2021-04-13 11:24:57.960 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:57.961 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES
2021-04-13 11:24:57.962 [ 1b] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE
2021-04-13 11:24:57.964 [ 1b] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-5,size=1,nmemb=65536,userData=0x6d712c80
2021-04-13 11:24:57.966 [ 1b] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE
2021-04-13 11:24:58.047 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-5,size=1,nmemb=13,userData=0x6d712c80
2021-04-13 11:24:58.048 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:58.049 [ 1b] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:58.050 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.051 [ 1b] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204
2021-04-13 11:24:58.052 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:58.052 [ 1b] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x12b15a8
2021-04-13 11:24:58.053 [ 1b] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:58.054 [ 1b] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS_NO_CONTENT
2021-04-13 11:24:58.055 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204
2021-04-13 11:24:58.056 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204
2021-04-13 11:24:58.057 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.058 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-5,size=1,nmemb=56,userData=0x6d712c80
2021-04-13 11:24:58.059 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: d8eccdcb-81b6-4f02-b2ba-eb27830678d4
2021-04-13 11:24:58.061 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: d8eccdcb-81b6-4f02-b2ba-eb27830678d4
2021-04-13 11:24:58.062 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.063 [ 1b] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: d8eccdcb-81b6-4f02-b2ba-eb27830678d4
2021-04-13 11:24:58.064 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-5,size=1,nmemb=82,userData=0x6d712c80
2021-04-13 11:24:58.065 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-5
2021-04-13 11:24:58.066 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-5
2021-04-13 11:24:58.067 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.068 [ 1b] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: 027409fffe19f8d6-00008904-01ade959-2dfc93856db99c3f-cb778a65-5
2021-04-13 11:24:58.069 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-5,size=1,nmemb=32,userData=0x6d712c80
2021-04-13 11:24:58.070 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:58.070 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin: *
2021-04-13 11:24:58.071 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.071 [ 1b] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-5,size=1,nmemb=2,userData=0x6d712c80
2021-04-13 11:24:58.072 [ 1b] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=
2021-04-13 11:24:58.073 [ 1b] 9 MimeResponseSink:onReceiveHeaderLine:line=
2021-04-13 11:24:58.074 [ 1b] 9 HTTP2Transport:onActivity:this=0x12b15a8
2021-04-13 11:24:58.074 [ 1b] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE
2021-04-13 11:24:58.075 [ 1b] 9 MimeResponseSink:onResponseFinished:status=COMPLETE
2021-04-13 11:24:58.075 [ 1b] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204
2021-04-13 11:24:58.076 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:58.076 [ 1b] 7 MessageRequestHandler:reportMessageRequestFinished
2021-04-13 11:24:58.076 [ 1b] 7 HTTP2Transport:onMessageRequestFinished:this=0x12b15a8,countOfUnfinishedMessageHandlers=0
2021-04-13 11:24:58.077 [ 1b] 5 ConditionVariableWrapper:notifyAll
2021-04-13 11:24:58.078 [ 1b] 9 DNDMessageRequest:sendCompleted:Completed with status=SUCCESS_NO_CONTENT
2021-04-13 11:24:58.079 [ 1b] 7 LibcurlHTTP2Connection:streamFinished:streamId=AVSEvent-5,result=No error,CURLcode=0
2021-04-13 11:24:58.079 [ 1b] 9 LibcurlHTTP2Connection:releaseStream:streamId=AVSEvent-5
2021-04-13 11:24:58.079 [ 1b] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2021-04-13 11:24:58.079 [ 1b] 7 MessageRequestHandler:reportMessageRequestFinished

I'd like to bump this back up as I am still having the same problem., Alexa is stubborn and will only output audio via the HDMI and no amount of tinkering on my part helps.

Hi,
Please try following the instructions here to configure HDMI as the output: https://www.raspberrypi.com/documentation/computers/configuration.html.
As well, please install PulseAudio, and remove any installations of Jack.

Hello @ldritsas,
Due to the inactivity of this ticket, this ticket will be closed. Please feel free to re-open the ticket if you have more questions or feel that your question has not been answered fully.