-
Notifications
You must be signed in to change notification settings - Fork 604
M1 Mac AVS SDK SampleApp Crash Before Authorization #1861
Comments
all the dependency listed in documentation were installed, the build process went through with out any error. |
Hi @zzk135246 , The error is due to your PortAudio microphone:
Sample App will quit if the microphone fails. Unfortunately the error number (-9999) doesn't tell us very much, since that's the fall-through error. Please confirm your Portaudio installation worked without error. You can try deleting your current Portaudio install directory and re-running these steps from our guide:
Please post the output when you run those steps. |
A quick follow-up -- I think this error you're seeing is the key to the issue:
I googled the error, and the error means there is something wrong with the way your microphone is configured on your Mac. It may be a permissions problem; for example, you might not have given permission for your terminal to access your microphone. That's one of the possibilities I found on Google. What MacOS and version are you using? |
I have the same issue, reconfiguring portaudio doesn't help. Microphone permissions are granted for both Terminal & iTerm. I'm on Big Sur, which I assume is the same for M1 Mac. I'm on Intel though. BTW everything was working fine on Catalina. |
I'm on the M1 MacBook running big sur |
checking build system type... arm-apple-darwin20.1.0 Configuration summary: Target ...................... arm-apple-darwin20.1.0 Mac debug flags ............. no mkdir lib |
I've been trying to figure out how grant microphone access to the terminal. It does not seems to give me an option in security and privacy to add terminal to microphone access. |
how to you grant microphone access to terminal? I've trying to google it, but nothing seems related to this. |
Hi @zzk135246 and @yacula , we have not tested the SDK Sample App with Big Sur macOS, so I can't give you much (tested) advice on how to set up your microphone correctly on that operating system. My advice at this time is to search for possible resolutions to this error: This post looks promising: https://apple.stackexchange.com/questions/390970/can-terminal-access-the-microphone-in-catalina-for-sox-ffmpeg-ecasound-pyau?noredirect=1&lq=1 But again, like I said, the SDK team has not tested this solution and we also have not tested the SDK Sample App on Big Sur. |
@yacula Can you find a way to test if you can record microphone data from your terminal (or iTerm, wherever you're running the Sample App) without the SDK? You can google ways to do that. That would narrow down if this is a problem with general microphone configuration on your computer, or whether it's something specific to the Sample App. @zzk135246 My understanding from Google is that you need to launch some application from your terminal that will proactively request microphone access. Then Terminal will show up in that Security & Privacy list. Until then, you won't see the Terminal application in Security & Privacy. Please see the Stackoverflow post I shared above, which gives some idea of how to do that. |
@caleighatamazon I confirm that I can record audio from iTerm & Terminal using sox. |
|
Hi @zzk135246 @yacula We have not tried building the SDK on BigSur so the following is untested. Could you please run the Sample App using sudo and provide the DEBUG9 logs. Since microphone permission has been granted for terminal now, I would like to see if PortAudio initialization in SampleApp is still throwing the same error code (-9999) as before or if its something else. |
Hi there! The error code seems to be different now, however the behaviour is the same. I'm attaching the DEBUG9 logs running SampleApp with sudo:
|
Seems like this is an issue with PortAudio that is being tracked on this ticket here: PortAudio/portaudio#356 Looks like they have a fix ready and once that is merged, you could download the latest version of portaudio, compile it and try again. |
Thank you very much! |
If anyone is interested, I've downloaded the latest portaudio version (from 20/01/2021) http://files.portaudio.com/archives/pa_snapshot.tgz and built the whole sdk with it and it now seems to be working fine. Thanks for all your help! |
IMPORTANT: Before you create an issue, please take a look at our Issue Reporting Guide.
Briefly summarize your issue:
SampleApp crashes before authorization.
What is the expected behavior?
should let me go to the link and authorize.
What behavior are you observing?
Running app with log level: DEBUG9
2021-01-06 20:41:46.934 [ 1] I AlexaClientSdkInit:initialize:sdkversion=1.22.0
2021-01-06 20:41:46.935 [ 1] 0 ConfigurationNode:initializeSuccess
2021-01-06 20:41:46.937 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.937 [ 1] 5 SDKPrimitivesProvider:withTimerDelegateFactory
2021-01-06 20:41:46.937 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.937 [ 1] 5 SDKPrimitivesProvider:initialize
2021-01-06 20:41:46.937 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.937 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2021-01-06 20:41:46.937 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2021-01-06 20:41:46.937 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2021-01-06 20:41:46.937 [ 1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2021-01-06 20:41:46.938 [ 1] 9 MediaPlayer:createCalled:name=SpeakMediaPlayer
2021-01-06 20:41:46.950 [ 1] 9 MediaPlayer:noOutputConversion:name=SpeakMediaPlayer
2021-01-06 20:41:46.950 [ 1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.951 [ 1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:createCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:noOutputConversion:name=NotificationsMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:createCalled:name=BluetoothMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:noOutputConversion:name=BluetoothMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:createCalled:name=RingtoneMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:noOutputConversion:name=RingtoneMediaPlayer
2021-01-06 20:41:46.952 [ 1] 9 MediaPlayer:createCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.953 [ 1] 9 MediaPlayer:noOutputConversion:name=AlertsMediaPlayer
2021-01-06 20:41:46.953 [ 1] 9 MediaPlayer:createCalled:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.953 [ 1] 9 MediaPlayer:noOutputConversion:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.953 [ 1] 5 SQLiteDeviceSettingStorage:create
2021-01-06 20:41:46.953 [ 1] 5 SQLiteBluetoothStorage:create
2021-01-06 20:41:46.953 [ 1] I LocaleAssetsManager:initialize:supportedWakeWords=NONE
2021-01-06 20:41:46.953 [ 1] I DeviceInfo:create:result=skipDeviceType,key=deviceType
2021-01-06 20:41:46.953 [ 1] I DeviceInfo:create:result=skipFriendlyName,key=friendlyName
2021-01-06 20:41:46.953 [ 1] I DeviceInfo:create:result=skipRegistrationKey,key=registrationKey
2021-01-06 20:41:46.953 [ 1] I DeviceInfo:create:result=skipProductIdKey,key=productIdKey
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:0
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegate:createAuthDelegateInterface
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegate:CBLAuthDelegate
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegate:init
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegateConfiguration:create
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegateConfiguration:init
2021-01-06 20:41:46.953 [ 1] 5 CBLAuthDelegateConfiguration:initScopeData
2021-01-06 20:41:46.953 [ 1] 9 CBLAuthDelegateConfiguration:initScopeDataSucceeded
2021-01-06 20:41:46.953 [ 1] 5 SQLiteCBLAuthDelegateStorage:open
2021-01-06 20:41:46.953 [ 1] 5 SQLiteCapabilitiesDelegateStorage:create
2021-01-06 20:41:46.953 [ 2] 5 CBLAuthDelegate:handleAuthorizationFlow
2021-01-06 20:41:46.953 [ 2] 5 CBLAuthDelegate:handleStarting
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:1
2021-01-06 20:41:46.953 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.953 [ 1] 5 SQLiteCapabilitiesDelegateStorage:open
2021-01-06 20:41:46.953 [ 2] 5 SQLiteCBLAuthDelegateStorage:getRefreshToken
2021-01-06 20:41:46.954 [ 2] 0 SQLiteCBLAuthDelegateStorage:getRefreshTokenFailed:reason=stepResultWasNotRow
2021-01-06 20:41:46.954 [ 2] 0 CBLAuthDelegate:getRefreshTokenFailed
2021-01-06 20:41:46.954 [ 2] 5 CBLAuthDelegate:handleRequestingCodePair
2021-01-06 20:41:46.954 [ 2] 5 CBLAuthDelegate:requestCodePair
2021-01-06 20:41:46.954 [ 1] 5 CBLAuthDelegate:addAuthObserver:observer=0x149775728
2021-01-06 20:41:46.954 [ 1] 9 ThreadPool:obtainWorker:created=0,obtained=0,releasedToPool=0,releasedFromPool=0,outstanding=0
2021-01-06 20:41:46.954 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x149775730
2021-01-06 20:41:46.954 [ 3] 9 TaskThread:startThread:moniker= 3,duration=45
2021-01-06 20:41:46.954 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.954 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.954 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.954 [ 1] 5 InternetConnectionMonitor:startMonitoring
2021-01-06 20:41:46.954 [ 4] 5 InternetConnectionMonitor:testConnection
2021-01-06 20:41:46.954 [ 4] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher
2021-01-06 20:41:46.954 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.954 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:2
2021-01-06 20:41:46.954 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.954 [ 1] 5 AVSGatewayManager:create
2021-01-06 20:41:46.954 [ 4] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition
2021-01-06 20:41:46.954 [ 5] 9 LibCurlHttpContentFetcher:transferThread::start
2021-01-06 20:41:46.954 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.954 [ 1] 5 AVSGatewayManager:init:avsGateway=https://alexa.na.gateway.devices.a2z.com
2021-01-06 20:41:46.954 [ 1] 5 SynchronizeStateSenderFactory:create
2021-01-06 20:41:46.955 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.957 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.957 [ 1] 5 ExternalCapabilitiesBuilder:ExternalCapabilitiesBuilder
2021-01-06 20:41:46.957 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.957 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.957 [ 6] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.957 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.957 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.957 [ 7] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.958 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.958 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.958 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.958 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:3
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.958 [ 1] 5 AVSConnectionManager:create::Subscribing to InternetConnectionMonitor Callbacks
2021-01-06 20:41:46.958 [ 1] 5 AVSConnectionManager:onConnectionStatusChanged:connected=false,isEnabled=false
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:4
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.958 [ 1] 5 DefaultEndpointBuilder:createDefaultEndpointBuilderInterface
2021-01-06 20:41:46.958 [ 1] 5 EndpointBuilder:finalizeAttributes
2021-01-06 20:41:46.958 [ 1] 5 DefaultEndpointBuilder:createDefaultEndpointCapabilitiesRegistrarInterface
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:5
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.958 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-01-06 20:41:46.958 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=0
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:6
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.958 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.958 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-01-06 20:41:46.958 [ 1] 5 StubApplicationAudioPipelineFactory:addCaptionManager::captions disabled
2021-01-06 20:41:46.958 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-01-06 20:41:46.958 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.958 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.958 [ 8] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.958 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=1
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:7
2021-01-06 20:41:46.958 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.958 [ 1] 5 Renderer:resetSourceId
2021-01-06 20:41:46.958 [ 1] 9 MediaPlayer:addObserverCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.958 [ 8] 0 MediaPlayer:handleAddObserverCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:8
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.959 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:9
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.959 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.959 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.959 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=1
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:10
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=2
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=CertifiedSender
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.959 [ 9] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 5 CertifiedSender:onConnectionStatusChanged
2021-01-06 20:41:46.959 [ 9] 9 PowerMonitor:assignThreadPowerResource:threadId=0x16de3b000
2021-01-06 20:41:46.959 [ 9] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 9] 9 CertifiedSender:mainloop:reason=waitingForMessage
2021-01-06 20:41:46.959 [ 9] 5 ConditionVariableWrapper:waitInner
2021-01-06 20:41:46.959 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:46.959 [ 1] 5 SQLiteDeviceSettingStorage:open
2021-01-06 20:41:46.959 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.959 [ 1] 5 DoNotDisturbCapabilityAgent:createDoNotDisturbCapabilityAgent
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:11
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 5 DNDSettingProtocol:create:settingName=enabled
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:12
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 5 DNDSettingProtocol:restoreValue:setting=Alexa.DoNotDisturb::enabled
2021-01-06 20:41:46.959 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alexa.DoNotDisturb::enabled
2021-01-06 20:41:46.959 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.959 [ 1] I Setting:handleSetValue:value=false11160318154034397263
2021-01-06 20:41:46.959 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.959 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.959 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=3
2021-01-06 20:41:46.959 [ 1] 5 SharedAVSSettingProtocol:create:settingName=alarmVolumeRamp
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:13
2021-01-06 20:41:46.959 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.959 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.959 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=Alerts::alarmVolumeRamp
2021-01-06 20:41:46.959 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts::alarmVolumeRamp
2021-01-06 20:41:46.959 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.959 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=Alerts::alarmVolumeRamp
2021-01-06 20:41:46.959 [ 1] 9 ThreadPool:obtainWorker:created=1,obtained=1,releasedToPool=0,releasedFromPool=0,outstanding=1
2021-01-06 20:41:46.959 [ a] 9 TaskThread:startThread:moniker= a,duration=20
2021-01-06 20:41:46.960 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=4
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:create:settingName=wakeWordConfirmation
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:14
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.960 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.960 [ a] I Setting:handleSetValue:value="NONE"11160318154034397263
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::wakeWordConfirmation
2021-01-06 20:41:46.960 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::wakeWordConfirmation
2021-01-06 20:41:46.960 [ a] 5 SQLiteDeviceSettingStorage:storeSetting:key=Alerts::alarmVolumeRamp,status=LOCAL_CHANGE_IN_PROGRESS
2021-01-06 20:41:46.960 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer::wakeWordConfirmation
2021-01-06 20:41:46.960 [ 1] 9 ThreadPool:obtainWorker:created=2,obtained=2,releasedToPool=0,releasedFromPool=0,outstanding=2
2021-01-06 20:41:46.960 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=5
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:create:settingName=speechConfirmation
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:15
2021-01-06 20:41:46.960 [ b] 9 TaskThread:startThread:moniker= b,duration=36
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.960 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.960 [ b] I Setting:handleSetValue:value="NONE"11160318154034397263
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::speechConfirmation
2021-01-06 20:41:46.960 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::speechConfirmation
2021-01-06 20:41:46.960 [ b] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer::wakeWordConfirmation,status=LOCAL_CHANGE_IN_PROGRESS
2021-01-06 20:41:46.960 [ a] 5 SettingEventSender:buildEventJson:eventName=AlarmVolumeRampChanged
2021-01-06 20:41:46.960 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer::speechConfirmation
2021-01-06 20:41:46.960 [ 1] 9 ThreadPool:obtainWorker:created=3,obtained=3,releasedToPool=0,releasedFromPool=0,outstanding=3
2021-01-06 20:41:46.960 [ c] 9 TaskThread:startThread:moniker= c,duration=20
2021-01-06 20:41:46.960 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=6
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:create:settingName=timeZone
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:16
2021-01-06 20:41:46.960 [ c] I Setting:handleSetValue:value="NONE"11160318154034397263
2021-01-06 20:41:46.960 [ c] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer::speechConfirmation,status=LOCAL_CHANGE_IN_PROGRESS
2021-01-06 20:41:46.960 [ a] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.960 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.960 [ a] 0 EventBuilder:buildJsonEventString:messageId=90751165-fa9c-4a68-a510-a744e446a090,namespace=Alerts,name=AlarmVolumeRampChanged
2021-01-06 20:41:46.960 [ a] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"Alerts","name":"AlarmVolumeRampChanged","messageId":"90751165-fa9c-4a68-a510-a744e446a090"},"payload":{"alarmVolumeRamp":"NONE"}}}
2021-01-06 20:41:46.960 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.960 [ a] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.960 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System::timeZone
2021-01-06 20:41:46.960 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::timeZone
2021-01-06 20:41:46.960 [ a] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.960 [ a] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.960 [ a] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed
2021-01-06 20:41:46.960 [ b] 5 SettingEventSender:buildEventJson:eventName=WakeWordConfirmationChanged
2021-01-06 20:41:46.960 [ b] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.960 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.960 [ b] 0 EventBuilder:buildJsonEventString:messageId=508c18b4-e54b-4191-82d5-30ac4a8765d3,namespace=SpeechRecognizer,name=WakeWordConfirmationChanged
2021-01-06 20:41:46.960 [ b] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"SpeechRecognizer","name":"WakeWordConfirmationChanged","messageId":"508c18b4-e54b-4191-82d5-30ac4a8765d3"},"payload":{"wakeWordConfirmation":"NONE"}}}
2021-01-06 20:41:46.961 [ b] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.961 [ b] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.961 [ b] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.961 [ b] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed
2021-01-06 20:41:46.961 [ c] 5 SettingEventSender:buildEventJson:eventName=SpeechConfirmationChanged
2021-01-06 20:41:46.961 [ c] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.961 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.961 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System::timeZone
2021-01-06 20:41:46.961 [ c] 0 EventBuilder:buildJsonEventString:messageId=7a114c35-98bb-41bf-9f2f-117e05573c52,namespace=SpeechRecognizer,name=SpeechConfirmationChanged
2021-01-06 20:41:46.961 [ c] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"SpeechRecognizer","name":"SpeechConfirmationChanged","messageId":"7a114c35-98bb-41bf-9f2f-117e05573c52"},"payload":{"speechConfirmation":"NONE"}}}
2021-01-06 20:41:46.961 [ c] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.961 [ c] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.961 [ c] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.961 [ c] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed
2021-01-06 20:41:46.961 [ 1] 9 ThreadPool:obtainWorker:created=4,obtained=4,releasedToPool=0,releasedFromPool=0,outstanding=4
2021-01-06 20:41:46.961 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=7
2021-01-06 20:41:46.961 [ 1] 5 SharedAVSSettingProtocol:create:settingName=networkInfo
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:17
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.961 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.961 [ d] 9 TaskThread:startThread:moniker= d,duration=94
2021-01-06 20:41:46.961 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System::networkInfo
2021-01-06 20:41:46.961 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::networkInfo
2021-01-06 20:41:46.961 [ d] I Setting:handleSetValue:value="America/Vancouver"11160318154034397263
2021-01-06 20:41:46.961 [ d] 5 SQLiteDeviceSettingStorage:storeSetting:key=System::timeZone,status=LOCAL_CHANGE_IN_PROGRESS
2021-01-06 20:41:46.961 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.961 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System::networkInfo
2021-01-06 20:41:46.961 [ 1] 9 ThreadPool:obtainWorker:created=5,obtained=5,releasedToPool=0,releasedFromPool=0,outstanding=5
2021-01-06 20:41:46.961 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=8
2021-01-06 20:41:46.961 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=9
2021-01-06 20:41:46.961 [ 1] 5 LocaleWakeWordsSetting:create:settingName=LocaleWakeWords
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:18
2021-01-06 20:41:46.961 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.961 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System.locales
2021-01-06 20:41:46.961 [ e] 9 TaskThread:startThread:moniker= e,duration=52
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=connectionType
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=ESSID
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=BSSID
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=IPAddress
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=subnetMask
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=MACAddress
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=DHCPServerAddress
2021-01-06 20:41:46.961 [ e] 5 JsonUtils:findNode:reason=missingDirectChild,child=staticIP
2021-01-06 20:41:46.961 [ e] I Setting:handleSetValue:value={}11160318154034397263
2021-01-06 20:41:46.961 [ e] 5 SQLiteDeviceSettingStorage:storeSetting:key=System::networkInfo,status=LOCAL_CHANGE_IN_PROGRESS
2021-01-06 20:41:46.961 [ d] 5 SettingEventSender:buildEventJson:eventName=TimeZoneChanged
2021-01-06 20:41:46.961 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting
2021-01-06 20:41:46.961 [ d] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.961 [ d] 0 EventBuilder:buildJsonEventString:messageId=c81e075c-0183-49aa-90b7-216ec795a6e7,namespace=System,name=TimeZoneChanged
2021-01-06 20:41:46.961 [ d] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"System","name":"TimeZoneChanged","messageId":"c81e075c-0183-49aa-90b7-216ec795a6e7"},"payload":{"timeZone":"America/Vancouver"}}}
2021-01-06 20:41:46.961 [ 1] 2 LocaleWakeWordsSetting:restoreInitialValue:wakeWords=[],locale=["en-US"]
2021-01-06 20:41:46.961 [ 1] 9 ThreadPool:obtainWorker:created=6,obtained=6,releasedToPool=0,releasedFromPool=0,outstanding=6
2021-01-06 20:41:46.961 [ d] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.961 [ d] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.961 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.961 [ d] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.961 [ d] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed
2021-01-06 20:41:46.961 [ f] 9 TaskThread:startThread:moniker= f,duration=37
2021-01-06 20:41:46.962 [ f] 5 LocaleWakeWordsSetting:executeChangeValue:RequestId=1,wwRequest=[],localeRequest=["en-US"]
2021-01-06 20:41:46.962 [ f] I LocaleAssetsManager:changeAssets:Locale=["en-US"],WakeWords=[]
2021-01-06 20:41:46.962 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.962 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.962 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:19
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:20
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.962 [ e] 5 SettingEventSender:buildEventJson:eventName=NetworkInfoChanged
2021-01-06 20:41:46.962 [ f] 5 SQLiteDeviceSettingStorage:storeSettings
2021-01-06 20:41:46.962 [ f] 5 SQLiteDeviceSettingStorage:storeSettings
2021-01-06 20:41:46.962 [ e] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.962 [ e] 0 EventBuilder:buildJsonEventString:messageId=bc65c548-2e13-43f7-8ae5-298885783aaa,namespace=System,name=NetworkInfoChanged
2021-01-06 20:41:46.962 [ e] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"System","name":"NetworkInfoChanged","messageId":"bc65c548-2e13-43f7-8ae5-298885783aaa"},"payload":{"networkInfo":{}}}}
2021-01-06 20:41:46.962 [ e] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.962 [ e] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.962 [ e] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.962 [ e] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed
2021-01-06 20:41:46.962 [ 1] 5 AlertScheduler:setTimerForNextAlertLocked
2021-01-06 20:41:46.962 [ 1] 8 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2021-01-06 20:41:46.962 [ 1] 9 SpeakerManager:getSpeakerSettingsCalled
2021-01-06 20:41:46.962 [ 1] 9 ThreadPool:obtainWorker:created=7,obtained=7,releasedToPool=0,releasedFromPool=0,outstanding=7
2021-01-06 20:41:46.962 [ 10] 9 TaskThread:startThread:moniker= 10,duration=30
2021-01-06 20:41:46.962 [ 10] 9 SpeakerManager:executeGetSpeakerSettingsCalled
2021-01-06 20:41:46.962 [ 10] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.962 [ 10] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.962 [ 8] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2021-01-06 20:41:46.962 [ 10] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2021-01-06 20:41:46.962 [ f] 5 LocaleWakeWordsSetting:notifyObserversOfSuccess:id=1
2021-01-06 20:41:46.962 [ 10] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2021-01-06 20:41:46.962 [ f] 5 SettingEventSender:buildEventJson:eventName=LocalesChanged
2021-01-06 20:41:46.962 [ 1] 5 ContextManager:setState
2021-01-06 20:41:46.962 [ 1] 9 ThreadPool:obtainWorker:created=8,obtained=8,releasedToPool=0,releasedFromPool=0,outstanding=8
2021-01-06 20:41:46.962 [ f] W UUIDGeneration:low entropy on call to generate UUID:current entropy=0
2021-01-06 20:41:46.962 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.962 [ 11] 9 TaskThread:startThread:moniker= 11,duration=23
2021-01-06 20:41:46.962 [ 1] 9 ThreadPool:obtainWorker:created=9,obtained=9,releasedToPool=0,releasedFromPool=0,outstanding=9
2021-01-06 20:41:46.962 [ f] 0 EventBuilder:buildJsonEventString:messageId=6d6ebcd4-e467-4b85-ae8c-d4c54ded1670,namespace=System,name=LocalesChanged
2021-01-06 20:41:46.962 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2021-01-06 20:41:46.962 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x14b0098c0
2021-01-06 20:41:46.962 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.962 [ f] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"System","name":"LocalesChanged","messageId":"6d6ebcd4-e467-4b85-ae8c-d4c54ded1670"},"payload":{"locales":["en-US"]}}}
2021-01-06 20:41:46.962 [ f] 7 SettingEventSender:sendEvent:attempt=0
2021-01-06 20:41:46.962 [ 1] 5 PlaybackRouter:createPlaybackRouterInterface
2021-01-06 20:41:46.962 [ 12] 9 TaskThread:startThread:moniker= 12,duration=21
2021-01-06 20:41:46.962 [ 1] 9 PlaybackRouter:create::called
2021-01-06 20:41:46.962 [ f] E MessageRouter:sendFailed:reason=noActiveTransport
2021-01-06 20:41:46.962 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.962 [ f] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED
2021-01-06 20:41:46.962 [ f] E LocaleWakeWordsSetting:synchronizeLocaleFailed:reason=sendEventFailed
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:21
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.962 [ 12] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.962 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.962 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:22
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.962 [ 1] 5 ExternalMediaPlayer:init
2021-01-06 20:41:46.962 [ 1] 5 AuthorizedSender:create
2021-01-06 20:41:46.962 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.962 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.962 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.962 [ 1] 5 ExternalMediaPlayer:createAdapters
2021-01-06 20:41:46.962 [ 1] C RequiresShutdown:~RequiresShutdownFailed:reason=notShutdown,name=StaticExternalMediaPlayerAdapterHandler
2021-01-06 20:41:46.962 [ 1] 5 AlexaInterfaceCapabilityAgent:createDefaultAlexaInterfaceCapabilityAgent
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:23
2021-01-06 20:41:46.962 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.962 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.963 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.963 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.963 [ 6] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2021-01-06 20:41:46.963 [ 1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2021-01-06 20:41:46.963 [ 1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2021-01-06 20:41:46.963 [ 1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SPEAKER_VOLUME
2021-01-06 20:41:46.963 [ 1] 5 ContextManager:setState
2021-01-06 20:41:46.963 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=1
2021-01-06 20:41:46.963 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=2
2021-01-06 20:41:46.963 [ 1] 9 ExternalMediaPlayer:startup
2021-01-06 20:41:46.963 [ 1] 7 DefaultClient:initialize::metrics disabled
2021-01-06 20:41:46.963 [ 1] 7 DefaultClient:initialize::power resource management disabled
2021-01-06 20:41:46.963 [ 1] 7 DefaultClient:initialize::using default null SystemTimeZone
2021-01-06 20:41:46.963 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled
2021-01-06 20:41:46.963 [ 1] 5 DefaultClient:nullCaptionManager::captions disabled
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:24
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 1] 8 DialogUXStateAggregator:timeout values in milliseconds:m_timeoutForThinkingToIdle=8000,m_shortTimeoutForThinkingToIdle=200,m_timeoutForListeningToIdle=8000
2021-01-06 20:41:46.963 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.963 [ 1] 9 ThreadPool:obtainWorker:created=10,obtained=10,releasedToPool=0,releasedFromPool=0,outstanding=10
2021-01-06 20:41:46.963 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=10
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveSequencer
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=11
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 13] 9 TaskThread:startThread:moniker= 13,duration=41
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveProcessor
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 14] 9 DirectiveProcessor:processingLoop
2021-01-06 20:41:46.963 [ 14] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 5 CapabilitiesDelegate:setMessageSender
2021-01-06 20:41:46.963 [ 15] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 5 AVSGatewayManager:addObserver
2021-01-06 20:41:46.963 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.963 [ 1] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=DISCONNECTED
2021-01-06 20:41:46.963 [ 15] 9 PowerMonitor:assignThreadPowerResource:threadId=0x16e4cb000
2021-01-06 20:41:46.963 [ 14] 9 PowerMonitor:assignThreadPowerResource:threadId=0x16e43f000
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 15] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 15] 5 ConditionVariableWrapper:waitInner
2021-01-06 20:41:46.963 [ 14] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 14] 5 ConditionVariableWrapper:waitInner
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:25
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x14a10f298
2021-01-06 20:41:46.963 [ 1] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=UNINITIALIZED,error=UNINITIALIZED,callback=true
2021-01-06 20:41:46.963 [ 1] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged
2021-01-06 20:41:46.963 [ 1] 9 ThreadPool:obtainWorker:created=11,obtained=11,releasedToPool=0,releasedFromPool=0,outstanding=11
2021-01-06 20:41:46.963 [ 16] 9 TaskThread:startThread:moniker= 16,duration=25
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.963 [ 1] 9 MediaPlayer:addObserverCalled:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.963 [ 17] 0 MediaPlayer:handleAddObserverCalled:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.963 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:26
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 1] 9 ThreadPool:obtainWorker:created=12,obtained=12,releasedToPool=0,releasedFromPool=0,outstanding=12
2021-01-06 20:41:46.963 [ 18] 9 TaskThread:startThread:moniker= 18,duration=18
2021-01-06 20:41:46.963 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-01-06 20:41:46.963 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.963 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer
2021-01-06 20:41:46.963 [ 19] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer
2021-01-06 20:41:46.963 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=3
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:27
2021-01-06 20:41:46.963 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.963 [ 1] 9 MediaPlayer:addObserverCalled:name=SpeakMediaPlayer
2021-01-06 20:41:46.963 [ 19] 0 MediaPlayer:handleAddObserverCalled:name=SpeakMediaPlayer
2021-01-06 20:41:46.963 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.963 [ 1] 9 SpeechSynthesizer:addObserver:observer=0x14a01ba20
2021-01-06 20:41:46.963 [ 1] 9 ThreadPool:obtainWorker:created=13,obtained=13,releasedToPool=0,releasedFromPool=0,outstanding=13
2021-01-06 20:41:46.963 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.963 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.963 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=21
2021-01-06 20:41:46.963 [ 17] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2021-01-06 20:41:46.964 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.964 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=BluetoothMediaPlayer
2021-01-06 20:41:46.964 [ 1b] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=BluetoothMediaPlayer
2021-01-06 20:41:46.964 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.964 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer
2021-01-06 20:41:46.964 [ 1c] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer
2021-01-06 20:41:46.964 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=4
2021-01-06 20:41:46.964 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=5
2021-01-06 20:41:46.964 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_SPEAKER_VOLUME,sizeOfSpeakerSet=6
2021-01-06 20:41:46.964 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.964 [ 1] 5 NotificationRenderer:create
2021-01-06 20:41:46.964 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces
2021-01-06 20:41:46.964 [ 1] 0 ChannelVolumeManager:getSpeakerSettings
2021-01-06 20:41:46.964 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:46.964 [ 13] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=IDLE,validTransition=false
2021-01-06 20:41:46.964 [ 1d] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:46.964 [ 1] 0 SpeakerManager:addChannelVolumeInterfaceIntoSpeakerMap:type=AVS_ALERTS_VOLUME,sizeOfSpeakerSet=2
2021-01-06 20:41:46.964 [ 1] 5 NotificationRenderer:create
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:28
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 9 MediaPlayer:addObserverCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:46.964 [ 1d] 0 MediaPlayer:handleAddObserverCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:29
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 5 NotificationsCapabilityAgent:init
2021-01-06 20:41:46.964 [ 1] 9 ThreadPool:obtainWorker:created=14,obtained=14,releasedToPool=0,releasedFromPool=0,outstanding=14
2021-01-06 20:41:46.964 [ 1] 5 InteractionModel:InteractionModelCapabilityAgent
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:30
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=57
2021-01-06 20:41:46.964 [ 1e] 5 NotificationsCapabilityAgent:executeInit
2021-01-06 20:41:46.964 [ 1] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.964 [ 1e] 5 NotificationRenderer:addObserver
2021-01-06 20:41:46.964 [ 1e] 5 ContextManager:addStateProvider
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:31
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1e] 5 NotificationsCapabilityAgent:notifyObserversOfIndicatorState:indicatorState=0
2021-01-06 20:41:46.964 [ 1] 5 PowerMonitor:getPowerResourceManager
2021-01-06 20:41:46.964 [ 1e] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0,isEnabled=false
2021-01-06 20:41:46.964 [ 1e] 5 ContextManager:setState
2021-01-06 20:41:46.964 [ 1] E PowerResource:create:error=nullPowerManager
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory
2021-01-06 20:41:46.964 [ 1] 5 SDKPrimitivesProvider:isInitialized
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:32
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 1 AudioPlayer:setObserver
2021-01-06 20:41:46.964 [ 1] 9 ThreadPool:obtainWorker:created=15,obtained=15,releasedToPool=0,releasedFromPool=0,outstanding=15
2021-01-06 20:41:46.964 [ 1] 5 ExternalMediaPlayer:setObserver
2021-01-06 20:41:46.964 [ 1] 5 ExternalCapabilitiesBuilder:withTemplateRunTime
2021-01-06 20:41:46.964 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=27
2021-01-06 20:41:46.964 [ 13] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2021-01-06 20:41:46.964 [ 13] 9 ThreadPool:obtainWorker:created=16,obtained=16,releasedToPool=0,releasedFromPool=0,outstanding=16
2021-01-06 20:41:46.964 [ 20] 9 TaskThread:startThread:moniker= 20,duration=32
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:33
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 5 ContextManager:setState
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:34
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:35
2021-01-06 20:41:46.964 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.965 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=12
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:36
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.965 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:46.965 [ 1] 5 DefaultClient:bluetoothCapabilityAgentDisabled:reason=nullBluetoothDeviceManager
2021-01-06 20:41:46.965 [ 1] 5 ApiGateway:create
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor:37
2021-01-06 20:41:46.965 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false
2021-01-06 20:41:46.965 [ 1] 0 DefaultClient:initialize::Diagnostics Not Enabled
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapability
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-01-06 20:41:46.965 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetLocales,handler=0x149787340,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.965 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetTimeZone,handler=0x14977c130,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.965 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ReportState,handler=0x1497888e0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.965 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x13960bdd8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.965 [ 1] 5 ExternalCapabilitiesBuilder:buildCapabilities
2021-01-06 20:41:46.965 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration
2021-01-06 20:41:46.965 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2021-01-06 20:41:46.965 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x149775748
2021-01-06 20:41:46.965 [ 1] 5 NotificationsCapabilityAgent:addObserver
2021-01-06 20:41:46.965 [ 1] 5 DefaultClient:addBluetoothDeviceObserver::bluetooth is disabled, not adding observer
#################################################
NOTIFICATION INDICATOR STATE: OFF
#################################################
2021-01-06 20:41:46.965 [ 1] 5 TemplateRuntime:addObserver
2021-01-06 20:41:46.965 [ 20] 5 TemplateRuntime:addObserverInExecutor
2021-01-06 20:41:46.966 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.974 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.990 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.990 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:46.990 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
||PaMacCore (AUHAL)|| AUHAL component not found.2021-01-06 20:41:46.999 [ 1] C PortAudioMicrophoneWrapper:Failed to open PortAudio default stream:errorCode=-9999
2021-01-06 20:41:46.999 [ 1] C PortAudioMicrophoneWrapper:Failed to initialize PortAudioMicrophoneWrapper
2021-01-06 20:41:46.999 [ 1] C SampleApplication:Failed to create microphone wrapper!
2021-01-06 20:41:46.999 [ 1] 5 DefaultClient:DirectiveSequencerShutdown
2021-01-06 20:41:46.999 [ 1] 9 DirectiveSequencer:doShutdown
2021-01-06 20:41:46.999 [ 1] 5 ConditionVariableWrapper:notifyOne
2021-01-06 20:41:46.999 [ 15] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.999 [ 15] 9 PowerMonitor:removeThreadPowerResource:threadId=0x16e4cb000
2021-01-06 20:41:46.999 [ 1] 9 DirectiveProcessor:queueAllDirectivesForCancellationLocked
2021-01-06 20:41:46.999 [ 1] 5 ConditionVariableWrapper:notifyOne
2021-01-06 20:41:46.999 [ 14] 9 DirectiveProcessor:processCancelingQueueLocked:size=0
2021-01-06 20:41:46.999 [ 14] 9 PowerMonitor:getInstance
2021-01-06 20:41:46.999 [ 14] 9 PowerMonitor:removeThreadPowerResource:threadId=0x16e43f000
2021-01-06 20:41:46.999 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ResetUserInactivity,handler=0x13960bdd8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.999 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ReportState,handler=0x1497888e0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:46.999 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetTimeZone,handler=0x14977c130,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:47.000 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetLocales,handler=0x149787340,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2021-01-06 20:41:47.000 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x13960bdd8
2021-01-06 20:41:47.000 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x1497888e0
2021-01-06 20:41:47.000 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x14977c130
2021-01-06 20:41:47.000 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x149787340
2021-01-06 20:41:47.000 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.000 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:TemplateRuntimeShutdown
2021-01-06 20:41:47.000 [ 1] 5 ExternalMediaPlayer:setObserver
2021-01-06 20:41:47.000 [ 1] 1 AudioPlayer:setObserver
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:AIPShutdown
2021-01-06 20:41:47.000 [ 1] 0 AudioInputProcessor:executeResetState
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:SpeechSynthesizerShutdown
2021-01-06 20:41:47.000 [ 1] 9 SpeechSynthesizer:doShutdown
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 9 MediaPlayer:removeObserverCalled:name=SpeakMediaPlayer
2021-01-06 20:41:47.000 [ 19] 0 MediaPlayer:handleRemoveObserverCalled:name=SpeakMediaPlayer
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:CertifiedSenderShutdown.
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:VisualActivityTrackerShutdown.
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:NotificationsShutdown.
2021-01-06 20:41:47.000 [ 1] 5 NotificationsCapabilityAgent:doShutdown
2021-01-06 20:41:47.000 [ 1e] 5 NotificationsCapabilityAgent:executeShutdown:currentState=IDLE
2021-01-06 20:41:47.000 [ 1e] 5 NotificationsCapabilityAgent:executeSetState:previousState=IDLE,newState=SHUTDOWN
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:NotificationsRendererShutdown.
2021-01-06 20:41:47.000 [ 1] 5 NotificationRenderer:doShutdown
2021-01-06 20:41:47.000 [ 1] 9 MediaPlayer:removeObserverCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:47.000 [ 1d] 0 MediaPlayer:handleRemoveObserverCalled:name=NotificationsMediaPlayer
2021-01-06 20:41:47.000 [ 1] 1 FocusManager:releaseChannel:channelName=Earcon
2021-01-06 20:41:47.000 [ 1] 9 ThreadPool:obtainWorker:created=17,obtained=17,releasedToPool=4,releasedFromPool=0,outstanding=13
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:UserInactivityMonitorShutdown.
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:CallApiGatewayCapabilityAgentShutdown.
2021-01-06 20:41:47.000 [ 20] 9 TaskThread:startThread:moniker= 20,duration=21
2021-01-06 20:41:47.000 [ 20] 5 FocusManager:releaseChannelHelper:channelToRelease=Earcon
2021-01-06 20:41:47.000 [ 1] 5 DefaultClient:EqualizerCapabilityAgentShutdown
2021-01-06 20:41:47.000 [ 20] 0 Channel:releaseActivityFailed::Observer not found
2021-01-06 20:41:47.000 [ 20] E FocusManager:releaseChannelHelper:reason=releaseActivityFailed,channel=0x1497812c8,interface=
2021-01-06 20:41:47.000 [ 1] 5 CapabilitiesDelegate:removeCapabilitiesObserver:observer=0x14a10f298
2021-01-06 20:41:47.000 [ 1] 5 InteractionModel:~InteractionModelCapabilityAgent
2021-01-06 20:41:47.000 [ 1] C SampleApplication:Failed to initialize SampleApplication
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 5 ProgressTimer:stop
2021-01-06 20:41:47.000 [ 1] 1 AudioPlayer:executeStop:playNextItem=false,m_currentState=IDLE,sourceId=0
2021-01-06 20:41:47.000 [ 1] 9 PooledMediaPlayerFactory:~PooledMediaPlayerFactory
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 9 AlertsCapabilityAgent:releaseChannel
2021-01-06 20:41:47.000 [ 1] 5 ContextManager:removeStateProvider
2021-01-06 20:41:47.000 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.000 [ 1] 5 AVSConnectionManager:disable
2021-01-06 20:41:47.000 [ 1] I MessageRouter:disable
2021-01-06 20:41:47.000 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0x0
2021-01-06 20:41:47.000 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:47.000 [ 1] 9 DirectiveProcessor:queueAllDirectivesForCancellationLocked
2021-01-06 20:41:47.001 [ 1] 5 ConditionVariableWrapper:notifyOne
2021-01-06 20:41:47.003 [ 1] I MessageRouter:disable
2021-01-06 20:41:47.003 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0x0
2021-01-06 20:41:47.003 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2021-01-06 20:41:47.003 [ 1] 5 ConditionVariableWrapper:notifyOne
2021-01-06 20:41:47.003 [ 1] 5 ConditionVariableWrapper:notifyOne
2021-01-06 20:41:47.003 [ 9] 9 CertifiedSender:CertifiedSender worker thread done. Exiting mainloop.
2021-01-06 20:41:47.003 [ 9] 9 PowerMonitor:getInstance
2021-01-06 20:41:47.003 [ 9] 9 PowerMonitor:removeThreadPowerResource:threadId=0x16de3b000
2021-01-06 20:41:47.003 [ 1] 5 ProgressTimer:stop
2021-01-06 20:41:47.003 [ 1] 5 AVSGatewayManager:~AVSGatewayManager
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=SpeakMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=NotificationsMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=BluetoothMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=RingtoneMediaPlayer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=AlertsMediaPlayer
2021-01-06 20:41:47.003 [ 1] C RequiresShutdown:~RequiresShutdownFailed:reason=notShutdown,name=Renderer
2021-01-06 20:41:47.003 [ 1] 9 MediaPlayer:doShutdown:name=SystemSoundMediaPlayer
2021-01-06 20:41:47.003 [ 1] 5 CapabilitiesDelegate:doShutdown
2021-01-06 20:41:47.003 [ 1] 5 CapabilitiesDelegate:resetCurrentDiscoveryEventSender
2021-01-06 20:41:47.003 [ 1] 9 PowerMonitor:getInstance
2021-01-06 20:41:47.003 [ 1] 9 PowerMonitor:deactivate
2021-01-06 20:41:47.003 [ 1] 5 SDKPrimitivesProvider:getInstance
2021-01-06 20:41:47.003 [ 1] 5 SDKPrimitivesProvider:terminate
2021-01-06 20:41:47.003 [ 1] 5 SDKPrimitivesProvider:reset
2021-01-06 20:41:47.003 [ 1] 5 SQLiteCapabilitiesDelegateStorage:close
2021-01-06 20:41:47.003 [ 1] 5 SQLiteCapabilitiesDelegateStorage:closeLocked
2021-01-06 20:41:47.003 [ 1] 5 CBLAuthDelegate:stop
2021-01-06 20:41:47.004 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:47.054 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=419
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found.
2021-01-06 20:41:47.120 [ 5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition
2021-01-06 20:41:47.120 [ 4] 9 InternetConnectionMonitor:testConnection:contentLength=419
2021-01-06 20:41:47.120 [ 4] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition
2021-01-06 20:41:47.225 [ 5] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=419,contentLength=419,currentContentReceived=419,remaining=0,totalBytesWritten=419
2021-01-06 20:41:47.225 [ 5] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=http://spectrum.s3.amazonaws.com/kindle-wifi/wifistub.html
2021-01-06 20:41:47.225 [ 5] 9 LibCurlHttpContentFetcher:transferThread::end
2021-01-06 20:41:47.225 [ 5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition
2021-01-06 20:41:47.325 [ 2] 5 HttpPost:doPostSucceeded:code=200
2021-01-06 20:41:47.330 [ 4] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes
2021-01-06 20:41:47.330 [ 4] 9 InternetConnectionMonitor:testConnection::Finished reading
2021-01-06 20:41:47.330 [ 4] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
2021-01-06 20:41:47.330 [ 4] 5 InternetConnectionMonitor:notifyObserversLocked
2021-01-06 20:41:47.330 [ 4] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher
2021-01-06 20:41:47.331 [ 2] 5 CBLAuthDelegate:receiveCodePairResponse:code=200
2021-01-06 20:41:47.331 [ 2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS
2021-01-06 20:41:47.331 [ 2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
##################################
NOT YET AUTHORIZED
##################################
################################################################################################
To authorize, browse to: 'https://amazon.com/us/code' and enter the code: BPCPKY
################################################################################################
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 9 SettingEventSender:cancel
2021-01-06 20:41:47.332 [ 1] 5 ConditionVariableWrapper:notifyAll
2021-01-06 20:41:47.332 [ 1] 5 SQLiteDeviceSettingStorage:close
2021-01-06 20:41:47.333 [ 1] 5 InternetConnectionMonitor:stopMonitoring
2021-01-06 20:41:47.348 [ 1] 5 SQLiteCBLAuthDelegateStorage:~SQLiteCBLAuthDelegateStorage
2021-01-06 20:41:47.348 [ 1] 5 SQLiteCBLAuthDelegateStorage:close
2021-01-06 20:41:47.348 [ 1] 9 MediaPlayer:~MediaPlayer:name=SystemSoundMediaPlayer
2021-01-06 20:41:47.348 [ 1] 9 MediaPlayer:resetPipeline:name=SystemSoundMediaPlayer
2021-01-06 20:41:47.348 [ 1] 9 MediaPlayer:~MediaPlayer:name=AlertsMediaPlayer
2021-01-06 20:41:47.348 [ 1] 9 MediaPlayer:resetPipeline:name=AlertsMediaPlayer
2021-01-06 20:41:47.348 [ 1] 9 MediaPlayer:~MediaPlayer:name=RingtoneMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:resetPipeline:name=RingtoneMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:~MediaPlayer:name=BluetoothMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:resetPipeline:name=BluetoothMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:~MediaPlayer:name=NotificationsMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:resetPipeline:name=NotificationsMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer
2021-01-06 20:41:47.349 [ 1] 9 MediaPlayer:~MediaPlayer:name=SpeakMediaPlayer
2021-01-06 20:41:47.350 [ 1] 9 MediaPlayer:resetPipeline:name=SpeakMediaPlayer
Failed to create SampleApplication!
Provide the steps to reproduce the issue, if applicable:
Tell us about your environment:
What version of the AVS Device SDK are you using?
Tell us what hardware you're using:
M1 MacBook Pro
Tell us about your OS (Type & version):
The text was updated successfully, but these errors were encountered: