alexa / avs-device-sdk

An SDK for commercial device makers to integrate Alexa directly into connected products.
https://developer.amazon.com/alexa/alexa-voice-service
Apache License 2.0
1.26k stars 604 forks source link

Another "Failed to create SampleApplication" #2085

Open amore75 opened 1 year ago

amore75 commented 1 year ago

IMPORTANT: Before you create an issue, please take a look at our Issue Reporting Guide.

Briefly summarize your issue:

Failed to create sampleapplication. Error looks to be: Failed to open PortAudio Error Code 9998

What is the expected behavior?

Error running the sample app

What behavior are you observing?

Not able to create sampleapp

Provide the steps to reproduce the issue, if applicable:

cd /home/pi/sdk-folder/sdk-build PA_ALSA_PLUGHW=1 ./SampleApp/src/SampleApp ./Integration/AlexaClientSDKConfig.json DEBUG9

Tell us about your environment:

Raspberry Pi 3B with Buster, SDK 1.2X

What version of the AVS Device SDK are you using?

  1.2x

Tell us what hardware you're using:

Tell us about your OS (Type & version):

amore75 commented 1 year ago

I searched everywhere I could on google/github and tried all the fixes but nothing has worked so far. I also tried searching for the error code 9998 but didn't see any. I'd really appreciate any help.

`pi@raspberrypi:~/sdk-folder/sdk-build $ export PA_ALSA_PLUGHW=1 pi@raspberrypi:~/sdk-folder/sdk-build $ cd $HOME/sdk-folder/sdk-build/ pi@raspberrypi:~/sdk-folder/sdk-build $ ./SampleApplications/ConsoleSampleApplication/src/SampleApp ./Integration/AlexaClientSDKConfig.json DEBUG9 configFile ./Integration/AlexaClientSDKConfig.json Running app with log level: DEBUG9 2023-04-20 18:34:07.519 [ 1] I AlexaClientSdkInit:initialize:sdkversion=3.0.0 2023-04-20 18:34:07.521 [ 1] I AlexaClientSdkInit:initialize:curlVersion=7.67.0 2023-04-20 18:34:07.528 [ 1] 0 ConfigurationNode:initializeSuccess 2023-04-20 18:34:07.534 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.535 [ 1] 5 SDKPrimitivesProvider:withTimerDelegateFactory 2023-04-20 18:34:07.535 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.535 [ 1] 5 SDKPrimitivesProvider:initialize 2023-04-20 18:34:07.535 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.539 [ 1] 5 CustomerDataManager:createCustomerDataManagerInteface 2023-04-20 18:34:07.539 [ 1] 5 AuthorizationManager:create 2023-04-20 18:34:07.540 [ 1] 5 AuthorizationManagerStorage:create 2023-04-20 18:34:07.540 [ 1] 5 AuthorizationManagerStorage:initializeDatabase 2023-04-20 18:34:07.540 [ 1] 5 AuthorizationManagerStorage:openLocked 2023-04-20 18:34:07.547 [ 1] 5 Executor:created:moniker= e\:2 2023-04-20 18:34:07.547 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.548 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:2 2023-04-20 18:34:07.548 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.548 [ 1] 5 AuthorizationManager:AuthorizationManager 2023-04-20 18:34:07.549 [ 1] 5 AuthorizationManager:init 2023-04-20 18:34:07.549 [ 1] 5 AuthorizationManagerStorage:load 2023-04-20 18:34:07.551 [ 1] 0 AuthorizationManagerStorage:loadFailed:reason=missingAuthAdapterId 2023-04-20 18:34:07.551 [ 1] 0 AuthorizationManagerStorage:loadFailed:reason=missingUserId 2023-04-20 18:34:07.551 [ 1] 5 AuthorizationManagerStorage:loadFailed:authAdapterId=,userId= 2023-04-20 18:34:07.551 [ 1] I AuthorizationManager:init:activeAuthAdapter= 2023-04-20 18:34:07.555 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration 2023-04-20 18:34:07.555 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state 2023-04-20 18:34:07.557 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration 2023-04-20 18:34:07.558 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state 2023-04-20 18:34:07.560 [ 1] 9 MediaPlayer:createCalled:name=SpeakMediaPlayer 2023-04-20 18:34:07.701 [ 1] 9 MediaPlayer:noOutputConversion:name=SpeakMediaPlayer 2023-04-20 18:34:07.707 [ 1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.713 [ 1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer 2023-04-20 18:34:07.718 [ 1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.720 [ 1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer 2023-04-20 18:34:07.726 [ 1] 9 MediaPlayer:createCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:07.727 [ 1] 9 MediaPlayer:noOutputConversion:name=NotificationsMediaPlayer 2023-04-20 18:34:07.731 [ 1] 9 MediaPlayer:createCalled:name=BluetoothMediaPlayer 2023-04-20 18:34:07.732 [ 1] 9 MediaPlayer:noOutputConversion:name=BluetoothMediaPlayer 2023-04-20 18:34:07.736 [ 1] 9 MediaPlayer:createCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:07.737 [ 1] 9 MediaPlayer:noOutputConversion:name=RingtoneMediaPlayer 2023-04-20 18:34:07.741 [ 1] 9 MediaPlayer:createCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.742 [ 1] 9 MediaPlayer:noOutputConversion:name=AlertsMediaPlayer 2023-04-20 18:34:07.748 [ 1] 9 MediaPlayer:createCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:07.749 [ 1] 9 MediaPlayer:noOutputConversion:name=SystemSoundMediaPlayer 2023-04-20 18:34:07.754 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=0 2023-04-20 18:34:07.756 [ 1] 5 SQLiteDeviceSettingStorage:create 2023-04-20 18:34:07.756 [ 1] 5 SQLiteBluetoothStorage:create 2023-04-20 18:34:07.757 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.757 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.757 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.758 [ 1] 5 InternetConnectionMonitor:startMonitoring 2023-04-20 18:34:07.760 [ 1] 5 TimerDelegate:init:moniker= t\:3 2023-04-20 18:34:07.765 [ t:3] 5 InternetConnectionMonitor:testConnection 2023-04-20 18:34:07.765 [ t:3] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher 2023-04-20 18:34:07.766 [ t:3] 0 CurlEasyHandleWrapper:initializeNetworkInterfaceName:m_interfaceName= 2023-04-20 18:34:07.768 [ 1] E ConfigurationNode:getArrayFailed:reason=notAnArray 2023-04-20 18:34:07.769 [ 1] I LocaleAssetsManager:initialize:supportedWakeWords=NONE 2023-04-20 18:34:07.770 [ 1] I DeviceInfo:create:result=skipDeviceType,key=deviceType 2023-04-20 18:34:07.771 [ 1] I DeviceInfo:create:result=skipFriendlyName,key=friendlyName 2023-04-20 18:34:07.771 [ 1] I DeviceInfo:create:result=skipRegistrationKey,key=registrationKey 2023-04-20 18:34:07.771 [ 1] I DeviceInfo:create:result=skipProductIdKey,key=productIdKey 2023-04-20 18:34:07.776 [ 1] 5 Executor:created:moniker= e\:4 2023-04-20 18:34:07.777 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.777 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:4 2023-04-20 18:34:07.777 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.769 [ t:3] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition 2023-04-20 18:34:07.780 [ 1] 5 SQLiteCapabilitiesDelegateStorage:create 2023-04-20 18:34:07.783 [ 1] 5 Executor:created:moniker= e\:5 2023-04-20 18:34:07.783 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.783 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:5 2023-04-20 18:34:07.784 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.785 [ 1] 5 SQLiteCapabilitiesDelegateStorage:open 2023-04-20 18:34:07.789 [ 1] 5 AuthorizationManager:addAuthObserver 2023-04-20 18:34:07.784 [ 6] 9 LibCurlHttpContentFetcher:transferThread::start 2023-04-20 18:34:07.792 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.798 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x10f7f78 2023-04-20 18:34:07.799 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.803 [ 1] 5 MultiTimer:init:moniker= t\:7 2023-04-20 18:34:07.804 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.805 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.806 [ 1] 5 Executor:created:moniker= e\:8 2023-04-20 18:34:07.807 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.807 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:8 2023-04-20 18:34:07.807 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.808 [ 1] 5 AVSGatewayManager:create 2023-04-20 18:34:07.809 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.810 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.813 [ 1] 5 AVSGatewayManager:init:avsGateway=https\://alexa.na.gateway.devices.a2z.com 2023-04-20 18:34:07.814 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.815 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.815 [ 1] 5 SynchronizeStateSenderFactory:create 2023-04-20 18:34:07.818 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.824 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.827 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.828 [ 1] E ConfigurationNode:getArrayFailed:reason=notAnArray 2023-04-20 18:34:07.828 [ 1] I LocaleAssetsManager:initialize:supportedWakeWords=NONE 2023-04-20 18:34:07.829 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.831 [ 1] 5 ExternalCapabilitiesBuilder:ExternalCapabilitiesBuilder 2023-04-20 18:34:07.833 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.839 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:07.839 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.840 [ 9] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.842 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:07.843 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.843 [ a] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:07.854 [ 6] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2023-04-20 18:34:07.855 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.856 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.857 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.857 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.858 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.859 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.859 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.860 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.860 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.861 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.861 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.861 [ 6] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=67 2023-04-20 18:34:07.861 [ 6] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2023-04-20 18:34:07.861 [ 6] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found. 2023-04-20 18:34:07.863 [ 6] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition 2023-04-20 18:34:07.865 [ t:3] 9 InternetConnectionMonitor:testConnection:contentLength=67 2023-04-20 18:34:07.866 [ t:3] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition 2023-04-20 18:34:07.903 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.903 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.903 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.904 [ 1] 5 Executor:created:moniker= e\:b 2023-04-20 18:34:07.904 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.904 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:b 2023-04-20 18:34:07.904 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.910 [ 1] 5 AVSConnectionManager:AVSConnectionManager:this=0xf98800 2023-04-20 18:34:07.911 [ 1] 5 AVSConnectionManager:create::Subscribing to InternetConnectionMonitor Callbacks 2023-04-20 18:34:07.912 [ 1] 5 AVSConnectionManager:onConnectionStatusChanged:this=0xf98800,connected=false,isEnabled=false 2023-04-20 18:34:07.916 [ 1] 5 DirectiveSequencer:createDirectiveSequencerInterface 2023-04-20 18:34:07.918 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=1 2023-04-20 18:34:07.919 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.919 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveSequencer 2023-04-20 18:34:07.919 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.920 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=2 2023-04-20 18:34:07.922 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.922 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveProcessor 2023-04-20 18:34:07.922 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.923 [ c] 9 DirectiveProcessor:processingLoop 2023-04-20 18:34:07.924 [ c] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.924 [ c] 9 PowerMonitor:assignThreadPowerResource:threadId=1812968448 2023-04-20 18:34:07.924 [ c] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.925 [ c] 5 ConditionVariableWrapper:waitInner 2023-04-20 18:34:07.925 [ d] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.926 [ d] 9 PowerMonitor:assignThreadPowerResource:threadId=1796183040 2023-04-20 18:34:07.926 [ d] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.926 [ d] 5 ConditionVariableWrapper:waitInner 2023-04-20 18:34:07.928 [ 1] 5 Executor:created:moniker= e\:e 2023-04-20 18:34:07.928 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.928 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:e 2023-04-20 18:34:07.928 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.930 [ 1] 5 DefaultEndpointBuilder:createDefaultEndpointBuilderInterface 2023-04-20 18:34:07.932 [ 1] 5 EndpointBuilder:finalizeAttributes 2023-04-20 18:34:07.933 [ 1] 5 DefaultEndpointBuilder:createDefaultEndpointCapabilitiesRegistrarInterface 2023-04-20 18:34:07.935 [ 1] 5 Executor:created:moniker= e\:f 2023-04-20 18:34:07.936 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.936 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:f 2023-04-20 18:34:07.937 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.943 [ 1] 5 ContextManager:addStateProvider 2023-04-20 18:34:07.946 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2023-04-20 18:34:07.953 [ 1] 5 Executor:created:moniker= e\:10 2023-04-20 18:34:07.953 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.954 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:10 2023-04-20 18:34:07.954 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.955 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.956 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.956 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.956 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.956 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.957 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.959 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.959 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.960 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.960 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.960 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.960 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.961 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.961 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.961 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.961 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.961 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.961 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.961 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.961 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.962 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.962 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.962 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.962 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.962 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.962 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.962 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.962 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.963 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.963 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:07.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:07.963 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:07.963 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.963 [ 1] 5 PowerMonitor:getPowerResourceManager 2023-04-20 18:34:07.963 [ 1] E PowerResource:create:error=nullPowerManager 2023-04-20 18:34:07.966 [ 6] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=67,contentLength=67,currentContentReceived=67,remaining=0,totalBytesWritten=67 2023-04-20 18:34:07.967 [ 6] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=http\://spectrum.s3.amazonaws.com/kindle-wifi/wifistub.html 2023-04-20 18:34:07.967 [ 6] 9 LibCurlHttpContentFetcher:transferThread::end 2023-04-20 18:34:07.968 [ 6] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition 2023-04-20 18:34:07.971 [ 1] 5 AlexaInterfaceCapabilityAgent:createDefaultAlexaInterfaceCapabilityAgent 2023-04-20 18:34:07.972 [ 1] 5 Executor:created:moniker= e\:11 2023-04-20 18:34:07.972 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.972 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:11 2023-04-20 18:34:07.972 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.973 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:07.978 [ t:3] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes 2023-04-20 18:34:07.978 [ t:3] 9 InternetConnectionMonitor:testConnection::Finished reading 2023-04-20 18:34:07.978 [ t:3] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true 2023-04-20 18:34:07.979 [ t:3] 5 AVSConnectionManager:onConnectionStatusChanged:this=0xf98800,connected=true,isEnabled=false 2023-04-20 18:34:07.979 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=3 2023-04-20 18:34:07.980 [ 1] 5 Executor:created:moniker= e\:12 2023-04-20 18:34:07.980 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:07.980 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:12 2023-04-20 18:34:07.980 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:07.980 [ 1] 5 SpeakerManager:SpeakerManager::Persistent Storage is enabled. 2023-04-20 18:34:07.980 [ t:3] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher 2023-04-20 18:34:07.980 [ 1] 5 SpeakerManager:configureDefaults::Loading configuration 2023-04-20 18:34:07.988 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:07.990 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled 2023-04-20 18:34:07.991 [ 1] 5 StubApplicationAudioPipelineFactory:addCaptionManager::captions disabled 2023-04-20 18:34:07.992 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2023-04-20 18:34:07.992 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:07.992 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.992 [ 13] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.995 [ 1] 5 SpeakerManager:executeInitializeSpeakerSettings:type=AVS_ALERTS_VOLUME 2023-04-20 18:34:07.996 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:07.996 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.996 [ 13] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.997 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=1 2023-04-20 18:34:07.998 [ 1] 9 SpeakerManager:operator():speaker id=16422644,speaker type=AVS_ALERTS_VOLUME,default volume set to =( 2023-04-20 18:34:07.998 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:07.998 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:07.998 [ 1] 9 MediaPlayer:setVolumeCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.999 [ 13] 9 MediaPlayer:handleSetVolumeCalled:name=AlertsMediaPlayer 2023-04-20 18:34:07.999 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:07.999 [ 1] 9 MediaPlayer:setMuteCalled:name=AlertsMediaPlayer 2023-04-20 18:34:08.002 [ 13] 9 MediaPlayer:handleSetMuteCalled:name=AlertsMediaPlayer 2023-04-20 18:34:08.008 [ 1] 5 Executor:created:moniker= e\:14 2023-04-20 18:34:08.008 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.009 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:14 2023-04-20 18:34:08.009 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.010 [ 1] 5 Renderer:resetSourceId 2023-04-20 18:34:08.010 [ 1] 9 MediaPlayer:addObserverCalled:name=AlertsMediaPlayer 2023-04-20 18:34:08.011 [ 13] 0 MediaPlayer:handleAddObserverCalled:name=AlertsMediaPlayer 2023-04-20 18:34:08.014 [ 1] 5 Renderer:onConnectionStatusChanged:Network connected=true 2023-04-20 18:34:08.018 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=4 2023-04-20 18:34:08.019 [ 1] 5 Executor:created:moniker= e\:15 2023-04-20 18:34:08.020 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.020 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:15 2023-04-20 18:34:08.020 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.020 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=5 2023-04-20 18:34:08.026 [ 1] 9 SQLiteMessageStorage:isDatabaseLegacy:reason=databaseNotLegacy 2023-04-20 18:34:08.028 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.028 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=CertifiedSender 2023-04-20 18:34:08.028 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.029 [ 16] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.030 [ 16] 9 PowerMonitor:assignThreadPowerResource:threadId=1822413824 2023-04-20 18:34:08.030 [ 16] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.030 [ 16] 9 CertifiedSender:mainloop:reason=waitingForMessage 2023-04-20 18:34:08.030 [ 16] 5 ConditionVariableWrapper:waitInner 2023-04-20 18:34:08.030 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.031 [ 1] 5 CertifiedSender:onConnectionStatusChanged 2023-04-20 18:34:08.031 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:08.031 [ 1] 5 SQLiteDeviceSettingStorage:open 2023-04-20 18:34:08.034 [ 1] 5 DoNotDisturbCapabilityAgent:createDoNotDisturbCapabilityAgent 2023-04-20 18:34:08.034 [ 1] 5 Executor:created:moniker= e\:17 2023-04-20 18:34:08.035 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.035 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:17 2023-04-20 18:34:08.035 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.035 [ 1] 5 DNDSettingProtocol:create:settingName=enabled 2023-04-20 18:34:08.036 [ 1] 5 Executor:created:moniker= e\:18 2023-04-20 18:34:08.036 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.036 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:18 2023-04-20 18:34:08.036 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.036 [ 1] 5 DNDSettingProtocol:restoreValue:setting=Alexa.DoNotDisturb\:\:enabled 2023-04-20 18:34:08.037 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alexa.DoNotDisturb\:\:enabled 2023-04-20 18:34:08.037 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.039 [ 1] I Setting:handleSetValue:value=false3990065800 2023-04-20 18:34:08.040 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.041 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.043 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=6 2023-04-20 18:34:08.044 [ 1] 5 SharedAVSSettingProtocol:create:settingName=alarmVolumeRamp 2023-04-20 18:34:08.044 [ 1] 5 Executor:created:moniker= e\:19 2023-04-20 18:34:08.045 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.045 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:19 2023-04-20 18:34:08.045 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.046 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.047 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=Alerts\:\:alarmVolumeRamp 2023-04-20 18:34:08.047 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts\:\:alarmVolumeRamp 2023-04-20 18:34:08.048 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.049 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=Alerts\:\:alarmVolumeRamp 2023-04-20 18:34:08.050 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=7 2023-04-20 18:34:08.051 [ 1] 5 SharedAVSSettingProtocol:create:settingName=wakeWordConfirmation 2023-04-20 18:34:08.051 [ 1] 5 Executor:created:moniker= e\:1a 2023-04-20 18:34:08.051 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.051 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1a 2023-04-20 18:34:08.051 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.052 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.052 [ e:19] I Setting:handleSetValue:value="NONE"3990065800 2023-04-20 18:34:08.052 [ e:19] 5 SQLiteDeviceSettingStorage:storeSetting:key=Alerts\:\:alarmVolumeRamp,status=LOCAL_CHANGE_IN_PROGRESS 2023-04-20 18:34:08.052 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer\:\:wakeWordConfirmation 2023-04-20 18:34:08.052 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer\:\:wakeWordConfirmation 2023-04-20 18:34:08.076 [ e:19] 5 SettingEventSender:buildEventJson:eventName=AlarmVolumeRampChanged 2023-04-20 18:34:08.077 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.077 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer\:\:wakeWordConfirmation 2023-04-20 18:34:08.078 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=8 2023-04-20 18:34:08.079 [ 1] 5 SharedAVSSettingProtocol:create:settingName=speechConfirmation 2023-04-20 18:34:08.079 [ 1] 5 Executor:created:moniker= e\:1b 2023-04-20 18:34:08.079 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.079 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1b 2023-04-20 18:34:08.079 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.080 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.080 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer\:\:speechConfirmation 2023-04-20 18:34:08.080 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer\:\:speechConfirmation 2023-04-20 18:34:08.081 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.081 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer\:\:speechConfirmation 2023-04-20 18:34:08.082 [ e:19] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.083 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=9 2023-04-20 18:34:08.083 [ 1] 5 SharedAVSSettingProtocol:create:settingName=timeZone 2023-04-20 18:34:08.084 [ 1] 5 Executor:created:moniker= e\:1c 2023-04-20 18:34:08.084 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.084 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1c 2023-04-20 18:34:08.084 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.084 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.084 [ e:19] 0 EventBuilder:buildJsonEventString:messageId=26bd78b4-1d97-4b19-87b1-9744d1903a91,namespace=Alerts,name=AlarmVolumeRampChanged 2023-04-20 18:34:08.085 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System\:\:timeZone 2023-04-20 18:34:08.085 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System\:\:timeZone 2023-04-20 18:34:08.085 [ e:19] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlarmVolumeRampChanged"\,"messageId"\:"26bd78b4-1d97-4b19-87b1-9744d1903a91"}\,"payload"\:{"alarmVolumeRamp"\:"NONE"}}} 2023-04-20 18:34:08.085 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.086 [ e:1b] I Setting:handleSetValue:value="NONE"3990065800 2023-04-20 18:34:08.086 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System\:\:timeZone 2023-04-20 18:34:08.087 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=10 2023-04-20 18:34:08.087 [ 1] 5 SharedAVSSettingProtocol:create:settingName=networkInfo 2023-04-20 18:34:08.087 [ 1] 5 Executor:created:moniker= e\:1d 2023-04-20 18:34:08.088 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.088 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1d 2023-04-20 18:34:08.088 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.088 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.089 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System\:\:networkInfo 2023-04-20 18:34:08.090 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System\:\:networkInfo 2023-04-20 18:34:08.091 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.091 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System\:\:networkInfo 2023-04-20 18:34:08.092 [ e:1a] I Setting:handleSetValue:value="NONE"3990065800 2023-04-20 18:34:08.093 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=11 2023-04-20 18:34:08.093 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=12 2023-04-20 18:34:08.093 [ e:19] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.093 [ 1] 5 LocaleWakeWordsSetting:create:settingName=LocaleWakeWords 2023-04-20 18:34:08.093 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=connectionType 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=ESSID 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=BSSID 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=IPAddress 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=subnetMask 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=MACAddress 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=DHCPServerAddress 2023-04-20 18:34:08.094 [ e:1d] 5 JsonUtils:findNode:reason=missingDirectChild,child=staticIP 2023-04-20 18:34:08.094 [ 1] 5 Executor:created:moniker= e\:1e 2023-04-20 18:34:08.094 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.094 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1e 2023-04-20 18:34:08.094 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.094 [ e:1d] I Setting:handleSetValue:value={}3990065800 2023-04-20 18:34:08.093 [ e:1a] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer\:\:wakeWordConfirmation,status=LOCAL_CHANGE_IN_PROGRESS 2023-04-20 18:34:08.095 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System.locales 2023-04-20 18:34:08.094 [ e:1d] 5 SQLiteDeviceSettingStorage:storeSetting:key=System\:\:networkInfo,status=LOCAL_CHANGE_IN_PROGRESS 2023-04-20 18:34:08.086 [ e:1b] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer\:\:speechConfirmation,status=LOCAL_CHANGE_IN_PROGRESS 2023-04-20 18:34:08.093 [ e:19] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.117 [ e:19] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.094 [ e:1c] I Setting:handleSetValue:value="America/Vancouver"3990065800 2023-04-20 18:34:08.120 [ e:1c] 5 SQLiteDeviceSettingStorage:storeSetting:key=System\:\:timeZone,status=LOCAL_CHANGE_IN_PROGRESS 2023-04-20 18:34:08.122 [ e:19] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.124 [ e:1a] 5 SettingEventSender:buildEventJson:eventName=WakeWordConfirmationChanged 2023-04-20 18:34:08.125 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2023-04-20 18:34:08.126 [ e:1a] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.127 [ e:1a] 0 EventBuilder:buildJsonEventString:messageId=40d8c187-f4fb-4b03-9784-f685302d2646,namespace=SpeechRecognizer,name=WakeWordConfirmationChanged 2023-04-20 18:34:08.127 [ e:1a] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"WakeWordConfirmationChanged"\,"messageId"\:"40d8c187-f4fb-4b03-9784-f685302d2646"}\,"payload"\:{"wakeWordConfirmation"\:"NONE"}}} 2023-04-20 18:34:08.127 [ e:19] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2023-04-20 18:34:08.127 [ e:1a] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.128 [ e:1a] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.128 [ e:1a] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.127 [ 1] 2 LocaleWakeWordsSetting:restoreInitialValue:wakeWords=[],locale=["en-US"] 2023-04-20 18:34:08.128 [ e:1a] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.128 [ e:1a] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2023-04-20 18:34:08.129 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.132 [ e:1e] 5 LocaleWakeWordsSetting:executeChangeValue:RequestId=1,wwRequest=[],localeRequest=["en-US"] 2023-04-20 18:34:08.133 [ e:1e] I LocaleAssetsManager:changeAssets:Locale=["en-US"],WakeWords=[] 2023-04-20 18:34:08.140 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.141 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.141 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.141 [ 1] 5 Executor:created:moniker= e\:1f 2023-04-20 18:34:08.141 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.142 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:1f 2023-04-20 18:34:08.142 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.143 [ 1] 5 Executor:created:moniker= e\:20 2023-04-20 18:34:08.143 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.143 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:20 2023-04-20 18:34:08.143 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.148 [ 1] 5 SQLiteAlertStorage:migrateAlertsDbFromV2ToV3::Alerts v3 table already exists. 2023-04-20 18:34:08.150 [ 1] 0 SQLiteDatabase:tableExistsFailed:reason=table doesn't exist or there was an error checking,table=alerts_v2 2023-04-20 18:34:08.151 [ 1] 5 SQLiteAlertStorage:migrateOfflineAlertsDbFromV1ToV2::Offline alerts v2 table already exists. 2023-04-20 18:34:08.152 [ 1] 0 SQLiteDatabase:tableExistsFailed:reason=table doesn't exist or there was an error checking,table=offlineAlerts 2023-04-20 18:34:08.155 [ e:1d] 5 SettingEventSender:buildEventJson:eventName=NetworkInfoChanged 2023-04-20 18:34:08.156 [ 1] 5 AlertScheduler:setTimerForNextAlertLocked 2023-04-20 18:34:08.156 [ 1] I AlertScheduler:executeScheduleNextAlertForRendering::no work to do. 2023-04-20 18:34:08.156 [ 1] 9 SpeakerManager:getSpeakerSettingsCalled 2023-04-20 18:34:08.158 [ e:1d] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.160 [ e:1d] 0 EventBuilder:buildJsonEventString:messageId=88c63ee5-fab7-4565-a0fd-3329da8d47b8,namespace=System,name=NetworkInfoChanged 2023-04-20 18:34:08.161 [ e:1d] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"NetworkInfoChanged"\,"messageId"\:"88c63ee5-fab7-4565-a0fd-3329da8d47b8"}\,"payload"\:{"networkInfo"\:{}}}} 2023-04-20 18:34:08.162 [ e:1d] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.163 [ e:12] 9 SpeakerManager:executeGetSpeakerSettingsCalled 2023-04-20 18:34:08.166 [ e:1d] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.166 [ 1] 5 ContextManager:setState:token=0 2023-04-20 18:34:08.166 [ e:1d] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.167 [ e:8] I ContextManager:updateCapabilityState 2023-04-20 18:34:08.168 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.169 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled 2023-04-20 18:34:08.169 [ e:20] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.170 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1150b84 2023-04-20 18:34:08.171 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.171 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.173 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.172 [ e:1d] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.174 [ e:1d] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2023-04-20 18:34:08.177 [ 1] 5 Executor:created:moniker= e\:21 2023-04-20 18:34:08.177 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.177 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:21 2023-04-20 18:34:08.177 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.178 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2023-04-20 18:34:08.179 [ 1] 5 PlaybackRouter:createPlaybackRouterInterface 2023-04-20 18:34:08.180 [ 1] 9 PlaybackRouter:create::called 2023-04-20 18:34:08.187 [ 1] 5 Executor:created:moniker= e\:22 2023-04-20 18:34:08.187 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.187 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:22 2023-04-20 18:34:08.187 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.189 [ e:1b] 5 SettingEventSender:buildEventJson:eventName=SpeechConfirmationChanged 2023-04-20 18:34:08.199 [ e:1b] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.200 [ e:1b] 0 EventBuilder:buildJsonEventString:messageId=cb88ded1-2200-4047-9264-17e84bf6bfbf,namespace=SpeechRecognizer,name=SpeechConfirmationChanged 2023-04-20 18:34:08.200 [ e:1b] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"SpeechConfirmationChanged"\,"messageId"\:"cb88ded1-2200-4047-9264-17e84bf6bfbf"}\,"payload"\:{"speechConfirmation"\:"NONE"}}} 2023-04-20 18:34:08.201 [ e:1b] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.201 [ e:1b] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.201 [ e:1b] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.201 [ e:1b] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.202 [ e:1b] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2023-04-20 18:34:08.192 [ 1] 5 AudioPlayer:AudioPlayer 2023-04-20 18:34:08.203 [ 1] 5 ContextManager:addStateProvider 2023-04-20 18:34:08.206 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.209 [ e:1c] 5 SettingEventSender:buildEventJson:eventName=TimeZoneChanged 2023-04-20 18:34:08.209 [ e:1e] 5 SQLiteDeviceSettingStorage:storeSettings 2023-04-20 18:34:08.211 [ e:1e] 5 SQLiteDeviceSettingStorage:storeSettings 2023-04-20 18:34:08.211 [ e:1c] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.212 [ e:1c] 0 EventBuilder:buildJsonEventString:messageId=809347d5-1527-4431-a14f-17e50a22e970,namespace=System,name=TimeZoneChanged 2023-04-20 18:34:08.212 [ e:1c] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"TimeZoneChanged"\,"messageId"\:"809347d5-1527-4431-a14f-17e50a22e970"}\,"payload"\:{"timeZone"\:"America/Vancouver"}}} 2023-04-20 18:34:08.213 [ e:1c] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.213 [ e:1c] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.213 [ e:1c] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.213 [ e:1c] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.214 [ e:1c] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2023-04-20 18:34:08.220 [ 1] 5 Executor:created:moniker= e\:23 2023-04-20 18:34:08.220 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.221 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:23 2023-04-20 18:34:08.221 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.223 [ 1] 5 ExternalMediaPlayer:init 2023-04-20 18:34:08.223 [ 1] 5 AuthorizedSender:create 2023-04-20 18:34:08.226 [ 1] 5 ContextManager:addStateProvider 2023-04-20 18:34:08.226 [ 1] 5 ContextManager:addStateProvider 2023-04-20 18:34:08.227 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.228 [ 1] 5 ExternalMediaPlayer:createAdapters 2023-04-20 18:34:08.230 [ e:1e] 5 LocaleWakeWordsSetting:notifyObserversOfSuccess:id=1 2023-04-20 18:34:08.230 [ e:1e] 5 SettingEventSender:buildEventJson:eventName=LocalesChanged 2023-04-20 18:34:08.232 [ e:1e] I UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2023-04-20 18:34:08.233 [ e:1e] 0 EventBuilder:buildJsonEventString:messageId=435c22c1-eefd-4186-924e-a313368c6ea0,namespace=System,name=LocalesChanged 2023-04-20 18:34:08.233 [ e:1e] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"LocalesChanged"\,"messageId"\:"435c22c1-eefd-4186-924e-a313368c6ea0"}\,"payload"\:{"locales"\:["en-US"]}}} 2023-04-20 18:34:08.233 [ e:1e] 7 SettingEventSender:sendEvent:attempt=0 2023-04-20 18:34:08.234 [ e:1e] 7 AVSConnectionManager:sendMessage:this=0xf98800 2023-04-20 18:34:08.234 [ 1] 5 InteractionModel:InteractionModelCapabilityAgent 2023-04-20 18:34:08.234 [ e:1e] E MessageRouter:sendFailed:reason=noActiveTransport 2023-04-20 18:34:08.234 [ e:1e] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2023-04-20 18:34:08.235 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.235 [ e:1e] E LocaleWakeWordsSetting:synchronizeLocaleFailed:reason=sendEventFailed 2023-04-20 18:34:08.257 [ 1] 5 NotificationRenderer:createNotificationRendererInterface 2023-04-20 18:34:08.257 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2023-04-20 18:34:08.258 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:08.258 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.265 [ 24] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.267 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=2 2023-04-20 18:34:08.267 [ 1] 9 SpeakerManager:operator():speaker id=16302524,speaker type=AVS_ALERTS_VOLUME,default volume set to =( 2023-04-20 18:34:08.267 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.267 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.267 [ 1] 9 MediaPlayer:setVolumeCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.268 [ 24] 9 MediaPlayer:handleSetVolumeCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.268 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.268 [ 1] 9 MediaPlayer:setMuteCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.269 [ 24] 9 MediaPlayer:handleSetMuteCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.269 [ 1] 5 NotificationRenderer:create 2023-04-20 18:34:08.270 [ 1] 5 Executor:created:moniker= e\:25 2023-04-20 18:34:08.271 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.271 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:25 2023-04-20 18:34:08.271 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.271 [ 1] 9 MediaPlayer:addObserverCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.272 [ 24] 0 MediaPlayer:handleAddObserverCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:08.273 [ 1] 5 NotificationsCapabilityAgent:createNotificationsCapabilityAgent 2023-04-20 18:34:08.276 [ 1] 5 Executor:created:moniker= e\:26 2023-04-20 18:34:08.276 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.277 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:26 2023-04-20 18:34:08.277 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.278 [ 1] 5 NotificationsCapabilityAgent:init 2023-04-20 18:34:08.283 [ e:26] 5 NotificationsCapabilityAgent:executeInit 2023-04-20 18:34:08.283 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.283 [ e:26] 5 NotificationRenderer:addObserver 2023-04-20 18:34:08.284 [ e:26] 5 ContextManager:addStateProvider 2023-04-20 18:34:08.284 [ 1] 5 UserInactivityMonitor:createUserInactivityMonitorInterface 2023-04-20 18:34:08.285 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.285 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.285 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.285 [ e:26] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0,isEnabled=false 2023-04-20 18:34:08.286 [ 1] 5 TimerDelegate:init:moniker= t\:27 2023-04-20 18:34:08.287 [ e:26] 5 ContextManager:setState:token=0 2023-04-20 18:34:08.287 [ e:8] I ContextManager:updateCapabilityState 2023-04-20 18:34:08.287 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.287 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.288 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.288 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.288 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.288 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.291 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0xf79cdc,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:08.292 [ 1] 5 SpeakerManager:executeInitializeSpeakerSettings:type=AVS_SPEAKER_VOLUME 2023-04-20 18:34:08.293 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:08.293 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.293 [ 9] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.294 [ 1] 9 SpeakerManager:executeGetSpeakerSettingsCalled 2023-04-20 18:34:08.294 [ 1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SPEAKER_VOLUME 2023-04-20 18:34:08.294 [ 1] 5 ContextManager:setState:token=0 2023-04-20 18:34:08.295 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=1 2023-04-20 18:34:08.295 [ 1] 9 SpeakerManager:operator():speaker id=16714812,speaker type=AVS_SPEAKER_VOLUME,default volume set to =( 2023-04-20 18:34:08.295 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.295 [ e:8] I ContextManager:updateCapabilityState 2023-04-20 18:34:08.295 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.295 [ 1] 9 MediaPlayer:setVolumeCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.295 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.295 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.296 [ 9] 9 MediaPlayer:handleSetVolumeCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.296 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.296 [ 1] 9 MediaPlayer:setMuteCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.305 [ 9] 9 MediaPlayer:handleSetMuteCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.306 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=2 2023-04-20 18:34:08.306 [ 1] 9 SpeakerManager:operator():speaker id=16467684,speaker type=AVS_SPEAKER_VOLUME,default volume set to =( 2023-04-20 18:34:08.306 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.306 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.306 [ 1] 9 MediaPlayer:setVolumeCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.306 [ a] 9 MediaPlayer:handleSetVolumeCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.307 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.307 [ 1] 9 MediaPlayer:setMuteCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.307 [ a] 9 MediaPlayer:handleSetMuteCalled:name=AudioMediaPlayer 2023-04-20 18:34:08.312 [ 1] I ExternalMediaPlayer:startup 2023-04-20 18:34:08.317 [ 1] 7 DefaultClient:initialize::metrics disabled 2023-04-20 18:34:08.318 [ 1] 7 DefaultClient:initialize::power resource management disabled 2023-04-20 18:34:08.318 [ 1] 7 DefaultClient:initialize::using default null SystemTimeZone 2023-04-20 18:34:08.319 [ 1] I DefaultClient:initialize::Supply m_alexaMessageSender to externalCapabilitiesBuilder 2023-04-20 18:34:08.320 [ 1] 5 DefaultClient:nullCaptionManager::captions disabled 2023-04-20 18:34:08.322 [ 1] 5 DefaultClient:nullBluetooth::Bluetooth disabled 2023-04-20 18:34:08.323 [ 1] 5 DefaultClient:nullBluetoothNotifier::Bluetooth disabled 2023-04-20 18:34:08.323 [ 1] 5 DialogUXStateAggregator:createDialogUXStateAggregator 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.325 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.326 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.326 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.326 [ 1] 5 Executor:created:moniker= e\:28 2023-04-20 18:34:08.326 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.326 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:28 2023-04-20 18:34:08.326 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.326 [ 1] 8 DialogUXStateAggregator:timeout values in milliseconds:m_timeoutForThinkingToIdle=8000,m_shortTimeoutForThinkingToIdle=200,m_timeoutForListeningToIdle=8000 2023-04-20 18:34:08.327 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.327 [ 1] 0 DialogUXStateAggregator:onConnectionStatusChanged:engineAggregatedStatus=DISCONNECTED 2023-04-20 18:34:08.328 [ e:28] 0 DialogUXStateAggregator:onConnectionStatusChangedLambda:engineType=1,engineStatus=DISCONNECTED 2023-04-20 18:34:08.328 [ e:28] 0 DialogUXStateAggregator:onConnectionStatusChangedLambda:isConnected=false 2023-04-20 18:34:08.328 [ e:28] 0 DialogUXStateAggregator:onConnectionStatusChangedLambda::Setting state to idle 2023-04-20 18:34:08.329 [ e:28] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=IDLE,validTransition=false 2023-04-20 18:34:08.329 [ 1] 0 DialogUXStateAggregator:onConnectionStatusChanged:status=DISCONNECTED:deprecated method. Nothing done. 2023-04-20 18:34:08.335 [ 1] 5 DeviceSetup:createDeviceSetupInterface 2023-04-20 18:34:08.336 [ 1] 5 DeviceSetup:create 2023-04-20 18:34:08.339 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.342 [ 1] 5 CapabilitiesDelegate:setMessageSender 2023-04-20 18:34:08.343 [ 1] 5 AVSGatewayManager:addObserver 2023-04-20 18:34:08.344 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.344 [ 1] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=DISCONNECTED 2023-04-20 18:34:08.347 [ 1] 5 Executor:created:moniker= e\:29 2023-04-20 18:34:08.347 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.347 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:29 2023-04-20 18:34:08.347 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.349 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x11b21fc 2023-04-20 18:34:08.351 [ 1] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=UNINITIALIZED,error=UNINITIALIZED,callback=true 2023-04-20 18:34:08.353 [ 1] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged 2023-04-20 18:34:08.355 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2023-04-20 18:34:08.356 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:08.356 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.357 [ 2a] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.357 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=3 2023-04-20 18:34:08.357 [ 1] 9 SpeakerManager:operator():speaker id=16368884,speaker type=AVS_SPEAKER_VOLUME,default volume set to =( 2023-04-20 18:34:08.357 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.358 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.358 [ 1] 9 MediaPlayer:setVolumeCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.358 [ 2a] 9 MediaPlayer:handleSetVolumeCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.358 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.358 [ 1] 9 MediaPlayer:setMuteCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.359 [ 2a] 9 MediaPlayer:handleSetMuteCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.360 [ 1] 9 MediaPlayer:addObserverCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.361 [ 2a] 0 MediaPlayer:handleAddObserverCalled:name=SystemSoundMediaPlayer 2023-04-20 18:34:08.363 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:08.363 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2023-04-20 18:34:08.364 [ 1] 5 SDKPrimitivesProvider:isInitialized 2023-04-20 18:34:08.365 [ 1] 5 Executor:created:moniker= e\:2b 2023-04-20 18:34:08.365 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.365 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:2b 2023-04-20 18:34:08.365 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.368 [ e:2b] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=IDLE 2023-04-20 18:34:08.369 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2023-04-20 18:34:08.370 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:08.370 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.370 [ 2c] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.370 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=4 2023-04-20 18:34:08.370 [ 1] 9 SpeakerManager:operator():speaker id=16207468,speaker type=AVS_SPEAKER_VOLUME,default volume set to =( 2023-04-20 18:34:08.371 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.371 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.371 [ 1] 9 MediaPlayer:setVolumeCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.371 [ 2c] 9 MediaPlayer:handleSetVolumeCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.371 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.371 [ 1] 9 MediaPlayer:setMuteCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.371 [ 2c] 9 MediaPlayer:handleSetMuteCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.374 [ 1] 5 Executor:created:moniker= e\:2d 2023-04-20 18:34:08.374 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.374 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:2d 2023-04-20 18:34:08.374 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.376 [ 1] 9 MediaPlayer:addObserverCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.376 [ 2c] 0 MediaPlayer:handleAddObserverCalled:name=SpeakMediaPlayer 2023-04-20 18:34:08.376 [ 1] 5 ContextManager:addStateProvider 2023-04-20 18:34:08.378 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x110af84 2023-04-20 18:34:08.379 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2023-04-20 18:34:08.379 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.379 [ 2e] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.380 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=5 2023-04-20 18:34:08.380 [ 1] 9 SpeakerManager:operator():speaker id=16500796,speaker type=AVS_SPEAKER_VOLUME,default volume set to =( 2023-04-20 18:34:08.380 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:volume=40 2023-04-20 18:34:08.380 [ 1] 5 ChannelVolumeManager:setUnduckedVolume:Unducked Channel Volume=40 2023-04-20 18:34:08.381 [ 1] 9 MediaPlayer:setVolumeCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.381 [ 2e] 9 MediaPlayer:handleSetVolumeCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.381 [ 1] 5 ChannelVolumeManager:setMute:mute=0 2023-04-20 18:34:08.381 [ 1] 9 MediaPlayer:setMuteCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.382 [ 2e] 9 MediaPlayer:handleSetMuteCalled:name=RingtoneMediaPlayer 2023-04-20 18:34:08.384 [ 1] 5 Executor:created:moniker= e\:2f 2023-04-20 18:34:08.385 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.385 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:2f 2023-04-20 18:34:08.385 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.387 [ 1] 5 ExternalMediaPlayer:setObserver 2023-04-20 18:34:08.387 [ 1] 1 AudioPlayer:setObserver 2023-04-20 18:34:08.388 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.389 [ 1] 5 ExternalCapabilitiesBuilder:withTemplateRunTime 2023-04-20 18:34:08.394 [ 1] 5 Executor:created:moniker= e\:30 2023-04-20 18:34:08.394 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.395 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:30 2023-04-20 18:34:08.395 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.399 [ 1] 5 ContextManager:setState:token=0 2023-04-20 18:34:08.400 [ e:8] I ContextManager:updateCapabilityState 2023-04-20 18:34:08.400 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.401 [ e:8] 5 ContextManager:updateCapabilityStateDetailed 2023-04-20 18:34:08.405 [ 1] 5 Executor:created:moniker= e\:31 2023-04-20 18:34:08.406 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.406 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:31 2023-04-20 18:34:08.406 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.406 [ 1] 5 Executor:created:moniker= e\:32 2023-04-20 18:34:08.406 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.407 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:32 2023-04-20 18:34:08.407 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.411 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=13 2023-04-20 18:34:08.415 [ 1] 5 Executor:created:moniker= e\:33 2023-04-20 18:34:08.415 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.415 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:33 2023-04-20 18:34:08.416 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.417 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:this=0xf98800,engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:08.420 [ 1] 5 SystemCapabilityProvider:getSystemCapabilityConfiguration:locales={"locales"\:["ar-SA"\,"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"\:[["de-DE"\,"en-GB"]\,["de-DE"\,"en-US"]\,["en-CA"\,"fr-CA"]\,["en-GB"\,"de-DE"]\,["en-GB"\,"es-ES"]\,["en-GB"\,"fr-FR"]\,["en-GB"\,"it-IT"]\,["en-IN"\,"hi-IN"]\,["en-US"\,"de-DE"]\,["en-US"\,"es-ES"]\,["en-US"\,"es-MX"]\,["en-US"\,"es-US"]\,["en-US"\,"fr-FR"]\,["en-US"\,"hi-IN"]\,["en-US"\,"it-IT"]\,["en-US"\,"ja-JP"]\,["en-US"\,"pt-BR"]\,["es-ES"\,"en-GB"]\,["es-ES"\,"en-US"]\,["es-MX"\,"en-US"]\,["es-US"\,"en-US"]\,["fr-CA"\,"en-CA"]\,["fr-FR"\,"en-GB"]\,["fr-FR"\,"en-US"]\,["hi-IN"\,"en-IN"]\,["hi-IN"\,"en-US"]\,["it-IT"\,"en-GB"]\,["it-IT"\,"en-US"]\,["ja-JP"\,"en-US"]\,["pt-BR"\,"en-US"]]} 2023-04-20 18:34:08.422 [ 1] 5 ApiGateway:create 2023-04-20 18:34:08.423 [ 1] 5 Executor:created:moniker= e\:34 2023-04-20 18:34:08.424 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:08.424 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Exee\:34 2023-04-20 18:34:08.424 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2023-04-20 18:34:08.425 [ 1] 0 DefaultClient:initialize::Diagnostics Not Enabled 2023-04-20 18:34:08.425 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.425 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.426 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.426 [ 1] 5 DefaultEndpointBuilder:withCapability 2023-04-20 18:34:08.426 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2023-04-20 18:34:08.427 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetLocales,handler=0x11b81b8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:08.428 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetTimeZone,handler=0x11b73c8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:08.429 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ReportState,handler=0x11b93d0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:08.429 [ 1] 5 ExternalCapabilitiesBuilder:buildCapabilities 2023-04-20 18:34:08.430 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2023-04-20 18:34:08.442 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled 2023-04-20 18:34:08.443 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x10f7f84 2023-04-20 18:34:08.445 [ 1] 5 DefaultClient:addBluetoothDeviceObserver::Bluetooth disabled #################################################

NOTIFICATION INDICATOR STATE: OFF

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

2023-04-20 18:34:08.451 [ 1] 5 TemplateRuntime:addObserver Expression 'alsa_snd_pcm_hw_params_set_channels( pcm, hwParams, self->numHostChannels )' failed in 'src/hostapi/alsa/pa_linux_alsa.c', line: 2051 Expression 'PaAlsaStreamComponent_InitialConfigure( &self->capture, inParams, self->primeBuffers, hwParamsCapture, &realSr )' failed in 'src/hostapi/alsa/pa_linux_alsa.c', line: 2714 Expression 'PaAlsaStream_Configure( stream, inputParameters, outputParameters, sampleRate, framesPerBuffer, &inputLatency, &outputLatency, &hostBufferSizeMode )' failed in 'src/hostapi/alsa/pa_linux_alsa.c', line: 2838 2023-04-20 18:34:09.254 [ 1] C PortAudioMicrophoneWrapper:Failed to open PortAudio default stream:errorCode=-9998 2023-04-20 18:34:09.254 [ 1] C PortAudioMicrophoneWrapper:Failed to initialize PortAudioMicrophoneWrapper 2023-04-20 18:34:09.267 [ 1] C SampleApplication:Failed to create microphone wrapper! 2023-04-20 18:34:09.269 [ 1] C SampleApplication:Failed to initialize SampleApplication 2023-04-20 18:34:09.271 [ 1] 5 NotificationsCapabilityAgent:doShutdown 2023-04-20 18:34:09.271 [ e:26] 5 NotificationsCapabilityAgent:executeShutdown:currentState=IDLE 2023-04-20 18:34:09.271 [ e:26] 5 NotificationsCapabilityAgent:executeSetState:previousState=IDLE,newState=SHUTDOWN 2023-04-20 18:34:09.272 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.273 [ 1] 5 NotificationRenderer:doShutdown 2023-04-20 18:34:09.273 [ 1] 9 MediaPlayer:removeObserverCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:09.274 [ 24] 0 MediaPlayer:handleRemoveObserverCalled:name=NotificationsMediaPlayer 2023-04-20 18:34:09.275 [ 1] 1 FocusManager:releaseChannel:channelName=Earcon 2023-04-20 18:34:09.276 [ e:10] 5 FocusManager:releaseChannelHelper:channelToRelease=Earcon 2023-04-20 18:34:09.276 [ e:10] 0 Channel:releaseActivityFailed::Observer not found 2023-04-20 18:34:09.276 [ e:10] E FocusManager:releaseChannelHelper:reason=releaseActivityFailed,channel=0xf95c28,interface= 2023-04-20 18:34:09.276 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.276 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.277 [ 1] 5 ProgressTimer:stop 2023-04-20 18:34:09.277 [ 1] 1 AudioPlayer:executeStop:playNextItem=false,m_currentState=IDLE,sourceId=0 2023-04-20 18:34:09.278 [ 1] 9 PooledMediaPlayerFactory:~PooledMediaPlayerFactory 2023-04-20 18:34:09.278 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.279 [ 1] 9 AlertsCapabilityAgent:releaseChannel 2023-04-20 18:34:09.281 [ 1] 5 Renderer:doShutdown 2023-04-20 18:34:09.281 [ 1] 9 MediaPlayer:removeObserverCalled:name=AlertsMediaPlayer 2023-04-20 18:34:09.281 [ 13] 0 MediaPlayer:handleRemoveObserverCalled:name=AlertsMediaPlayer 2023-04-20 18:34:09.282 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.282 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.283 [ 1] 9 DirectiveSequencer:doShutdown 2023-04-20 18:34:09.283 [ 1] 5 ConditionVariableWrapper:notifyOne 2023-04-20 18:34:09.283 [ d] 9 PowerMonitor:getInstance 2023-04-20 18:34:09.283 [ d] 9 PowerMonitor:removeThreadPowerResource:threadId=1796183040 2023-04-20 18:34:09.284 [ 1] 9 DirectiveProcessor:queueAllDirectivesForCancellationLocked 2023-04-20 18:34:09.284 [ 1] 5 ConditionVariableWrapper:notifyOne 2023-04-20 18:34:09.285 [ c] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2023-04-20 18:34:09.285 [ c] 9 PowerMonitor:getInstance 2023-04-20 18:34:09.285 [ c] 9 PowerMonitor:removeThreadPowerResource:threadId=1812968448 2023-04-20 18:34:09.286 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ReportState,handler=0x11b93d0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:09.287 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetTimeZone,handler=0x11b73c8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:09.287 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ResetUserInactivity,handler=0xf79cdc,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:09.287 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetLocales,handler=0x11b81b8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2023-04-20 18:34:09.287 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x11b93d0 2023-04-20 18:34:09.288 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x11b73c8 2023-04-20 18:34:09.288 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0xf79cdc 2023-04-20 18:34:09.288 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x11b81b8 2023-04-20 18:34:09.288 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.288 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.289 [ 1] 5 Executor:destroyed:moniker= e\:33 2023-04-20 18:34:09.290 [ 1] 5 Executor:destroyed:moniker= e\:31 2023-04-20 18:34:09.290 [ 1] 5 Executor:destroyed:moniker= e\:32 2023-04-20 18:34:09.290 [ 1] 5 AVSConnectionManager:doShutdown:this=0xf98800 2023-04-20 18:34:09.290 [ 1] 5 AVSConnectionManager:disable:this=0xf98800 2023-04-20 18:34:09.290 [ 1] I MessageRouter:disable 2023-04-20 18:34:09.291 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0 2023-04-20 18:34:09.291 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:09.291 [ 1] I MessageRouter:disable 2023-04-20 18:34:09.291 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0 2023-04-20 18:34:09.291 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2023-04-20 18:34:09.292 [ 1] 5 Executor:destroyed:moniker= e\:b 2023-04-20 18:34:09.293 [ 1] 5 Executor:destroyed:moniker= e\:14 2023-04-20 18:34:09.293 [ 1] 5 Executor:destroyed:moniker= e\:21 2023-04-20 18:34:09.294 [ 1] 5 Executor:destroyed:moniker= e\:25 2023-04-20 18:34:09.294 [ 1] 5 AuthorizationManager:doShutdown 2023-04-20 18:34:09.295 [ 1] 9 MediaPlayer:doShutdown:name=SpeakMediaPlayer 2023-04-20 18:34:09.296 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer 2023-04-20 18:34:09.296 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer 2023-04-20 18:34:09.296 [ 1] 9 MediaPlayer:doShutdown:name=NotificationsMediaPlayer 2023-04-20 18:34:09.296 [ 1] 9 MediaPlayer:doShutdown:name=BluetoothMediaPlayer 2023-04-20 18:34:09.297 [ 1] 9 MediaPlayer:doShutdown:name=RingtoneMediaPlayer 2023-04-20 18:34:09.297 [ 1] 9 MediaPlayer:doShutdown:name=AlertsMediaPlayer 2023-04-20 18:34:09.297 [ 1] 9 MediaPlayer:doShutdown:name=SystemSoundMediaPlayer 2023-04-20 18:34:09.297 [ 1] 5 CapabilitiesDelegate:doShutdown 2023-04-20 18:34:09.297 [ 1] 5 CapabilitiesDelegate:resetCurrentDiscoveryEventSender 2023-04-20 18:34:09.298 [ 1] 9 PowerMonitor:getInstance 2023-04-20 18:34:09.298 [ 1] 9 PowerMonitor:deactivate 2023-04-20 18:34:09.298 [ 1] 5 SDKPrimitivesProvider:getInstance 2023-04-20 18:34:09.298 [ 1] 5 SDKPrimitivesProvider:terminate 2023-04-20 18:34:09.298 [ 1] 5 SDKPrimitivesProvider:reset 2023-04-20 18:34:09.298 [ 1] 9 MediaPlayer:~MediaPlayer:name=NotificationsMediaPlayer 2023-04-20 18:34:09.300 [ 1] 9 MediaPlayer:resetPipeline:name=NotificationsMediaPlayer 2023-04-20 18:34:09.301 [ 1] 9 MediaPlayer:~MediaPlayer:name=BluetoothMediaPlayer 2023-04-20 18:34:09.303 [ 1] 9 MediaPlayer:resetPipeline:name=BluetoothMediaPlayer 2023-04-20 18:34:09.303 [ 1] 9 MediaPlayer:~MediaPlayer:name=RingtoneMediaPlayer 2023-04-20 18:34:09.304 [ 1] 9 MediaPlayer:resetPipeline:name=RingtoneMediaPlayer 2023-04-20 18:34:09.305 [ 1] 9 MediaPlayer:~MediaPlayer:name=AlertsMediaPlayer 2023-04-20 18:34:09.306 [ 1] 9 MediaPlayer:resetPipeline:name=AlertsMediaPlayer 2023-04-20 18:34:09.307 [ 1] E ShutdownManager:shutdownAlreadyCalled 2023-04-20 18:34:09.308 [ 1] 5 ExternalMediaPlayer:setObserver 2023-04-20 18:34:09.308 [ 1] 1 AudioPlayer:setObserver 2023-04-20 18:34:09.308 [ 1] W Executor:execute:reason=shutdownState 2023-04-20 18:34:09.308 [ 1] 5 DefaultClient:EndpointRegistrationManagerShutdown 2023-04-20 18:34:09.309 [ 1] 5 CapabilitiesDelegate:removeCapabilitiesObserver:observer=0x11b21fc 2023-04-20 18:34:09.309 [ 1] W CapabilitiesDelegate:removeCapabilitiesObserverFailed:reason=observerNotAdded 2023-04-20 18:34:09.309 [ 1] 5 DefaultClient:AIPShutdown 2023-04-20 18:34:09.310 [ 1] 0 AudioInputProcessor:executeResetState 2023-04-20 18:34:09.311 [ 1] 5 DefaultClient:SpeechSynthesizerShutdown 2023-04-20 18:34:09.311 [ 1] 9 SpeechSynthesizer:doShutdown 2023-04-20 18:34:09.311 [ 1] 5 ContextManager:removeStateProvider 2023-04-20 18:34:09.312 [ 1] 9 MediaPlayer:removeObserverCalled:name=SpeakMediaPlayer 2023-04-20 18:34:09.312 [ 1] 9 MediaPlayer:~MediaPlayer:name=SpeakMediaPlayer 2023-04-20 18:34:09.315 [ 1] 9 MediaPlayer:resetPipeline:name=SpeakMediaPlayer 2023-04-20 18:34:09.317 [ 1] 5 DefaultClient:CertifiedSenderShutdown. 2023-04-20 18:34:09.317 [ 1] 5 DefaultClient:CallApiGatewayCapabilityAgentShutdown. 2023-04-20 18:34:09.318 [ 1] 5 DefaultClient:EqualizerCapabilityAgentShutdown 2023-04-20 18:34:09.320 [ 1] 5 Executor:destroyed:moniker= e\:11 2023-04-20 18:34:09.321 [ 1] 5 InteractionModel:~InteractionModelCapabilityAgent 2023-04-20 18:34:09.322 [ 1] 5 Executor:destroyed:moniker= e\:26 2023-04-20 18:34:09.325 [ 1] 5 Executor:destroyed:moniker= e\:30 2023-04-20 18:34:09.326 [ 1] 5 Executor:destroyed:moniker= e\:28 2023-04-20 18:34:09.328 [ 1] 5 Executor:destroyed:moniker= e\:4 2023-04-20 18:34:09.330 [ 1] 5 Executor:destroyed:moniker= e\:34 2023-04-20 18:34:09.330 [ 1] 5 AVSGatewayManager:~AVSGatewayManager 2023-04-20 18:34:09.332 [ 1] 5 Executor:destroyed:moniker= e\:8 2023-04-20 18:34:09.334 [ 1] 5 Executor:destroyed:moniker= e\:e 2023-04-20 18:34:09.335 [ 1] 5 Executor:destroyed:moniker= e\:23 2023-04-20 18:34:09.337 [ 1] 5 AudioPlayer:~AudioPlayer 2023-04-20 18:34:09.338 [ 1] 5 Executor:destroyed:moniker= e\:22 2023-04-20 18:34:09.338 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer 2023-04-20 18:34:09.339 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer 2023-04-20 18:34:09.340 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer 2023-04-20 18:34:09.341 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer 2023-04-20 18:34:09.342 [ 1] 5 Executor:destroyed:moniker= e\:2f 2023-04-20 18:34:09.344 [ 1] 5 ProgressTimer:stop 2023-04-20 18:34:09.345 [ 1] 5 Executor:destroyed:moniker= e\:2d 2023-04-20 18:34:09.346 [ 1] 5 ConditionVariableWrapper:notifyOne 2023-04-20 18:34:09.346 [ 1] 5 ConditionVariableWrapper:notifyOne 2023-04-20 18:34:09.346 [ 16] 9 CertifiedSender:CertifiedSender worker thread done. Exiting mainloop. 2023-04-20 18:34:09.346 [ 16] 9 PowerMonitor:getInstance 2023-04-20 18:34:09.346 [ 16] 9 PowerMonitor:removeThreadPowerResource:threadId=1822413824 2023-04-20 18:34:09.347 [ 1] 5 Executor:destroyed:moniker= e\:15 2023-04-20 18:34:09.348 [ 1] 5 Executor:destroyed:moniker= e\:10 2023-04-20 18:34:09.348 [ 1] 5 Executor:destroyed:moniker= e\:f 2023-04-20 18:34:09.350 [ 1] 5 Executor:destroyed:moniker= e\:18 2023-04-20 18:34:09.350 [ 1] 5 Executor:destroyed:moniker= e\:17 2023-04-20 18:34:09.351 [ 1] 5 Executor:destroyed:moniker= e\:1c 2023-04-20 18:34:09.351 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.351 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.351 [ 1] 5 Executor:destroyed:moniker= e\:1e 2023-04-20 18:34:09.351 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.352 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.352 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.352 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.352 [ 1] 5 Executor:destroyed:moniker= e\:1d 2023-04-20 18:34:09.352 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.352 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.352 [ 1] 5 Executor:destroyed:moniker= e\:19 2023-04-20 18:34:09.353 [ 1] 9 SettingEventSender:cancel 2023-04-20 18:34:09.353 [ 1] 5 ConditionVariableWrapper:notifyAll 2023-04-20 18:34:09.353 [ 1] 5 Executor:destroyed:moniker= e\:20 2023-04-20 18:34:09.353 [ 1] 5 Executor:destroyed:moniker= e\:1f 2023-04-20 18:34:09.353 [ 1] 5 Executor:destroyed:moniker= e\:12 Failed to create SampleApplication! 2023-04-20 18:34:09.436 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=DirectiveSequencer 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=AVSConnectionManager 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=AuthorizationManager 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=AudioInputProcessor 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=no shutdown() call,name: =LocaleAssetsManager 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=LocaleAssetsManager 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=DirectiveRouter 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=EndpointRegistrationManager 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=SystemSoundMediaPlayer 2023-04-20 18:34:09.437 [0] C RequiresShutdown:ShutdownMonitor:reason=never deleted,name=CapabilitiesDelegate `