VaruPan
commented
3 years ago Hi @flashbif,
Thanks a lot for reaching out to Alexa team. I am wondering if this is microphone issue:
2020-12-10 00:24:07.352 [ 1] C PortAudioMicrophoneWrapper:Failed to open PortAudio default stream:errorCode=-9985
2020-12-10 00:24:07.352 [ 1] C PortAudioMicrophoneWrapper:Failed to initialize PortAudioMicrophoneWrapper
2020-12-10 00:24:07.353 [ 1] C SampleApplication:Failed to create microphone wrapper!From what you have mentioned, it looks like is a problem related to avs-device-sdk Sample App. Please file an issue with device sdk team: https://github.com/alexa/avs-device-sdk/issues
Thanks!
flashbif
IMPORTANT: Before you create an issue, please take a look at our Issue Reporting Guide.
Briefly summarize your issue:
Trying to complete STEP 6 of the smart screen sdk.
What is the expected behavior?
It should let me enter the weatherization code then let me test the alexa app
What behavior are you observing?
2020-12-10 00:24:07.102 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.103 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.103 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.103 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.103 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.103 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.103 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.103 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.103 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.104 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.104 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.104 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.104 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.104 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.104 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.104 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.104 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.105 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.105 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.105 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.105 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.105 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.105 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.105 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.105 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.106 [ 1] 5 PlaybackRouter:createPlaybackRouterInterface 2020-12-10 00:24:07.106 [ 1] 9 PlaybackRouter:create::called 2020-12-10 00:24:07.107 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=1 2020-12-10 00:24:07.108 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.108 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:9 2020-12-10 00:24:07.108 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.108 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=2 2020-12-10 00:24:07.109 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.109 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=CertifiedSender 2020-12-10 00:24:07.109 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.110 [ 8] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.110 [ 8] 9 PowerMonitor:assignThreadPowerResource:threadId=2845803008 2020-12-10 00:24:07.110 [ 8] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.110 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.110 [ 8] 9 CertifiedSender:mainloop:reason=waitingForMessage 2020-12-10 00:24:07.111 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.111 [ 8] 5 ConditionVariableWrapper:waitInner 2020-12-10 00:24:07.111 [ 1] 5 CertifiedSender:onConnectionStatusChanged 2020-12-10 00:24:07.111 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.113 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled 2020-12-10 00:24:07.113 [ 1] 5 StubApplicationAudioPipelineFactory:addCaptionManager::captions disabled 2020-12-10 00:24:07.114 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.114 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:10 2020-12-10 00:24:07.114 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.115 [ 1] 5 ExternalMediaPlayer:init 2020-12-10 00:24:07.115 [ 1] 5 AuthorizedSender:create 2020-12-10 00:24:07.115 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.116 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.117 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.118 [ 1] 5 ExternalMediaPlayer:createAdapters 2020-12-10 00:24:07.118 [ 1] E RequiresShutdown:~RequiresShutdownFailed:reason=notShutdown,name=StaticExternalMediaPlayerAdapterHandler 2020-12-10 00:24:07.120 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled 2020-12-10 00:24:07.122 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.122 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2020-12-10 00:24:07.123 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:11 2020-12-10 00:24:07.123 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.124 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.125 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.127 [ 1] 5 AlexaInterfaceCapabilityAgent:createDefaultAlexaInterfaceCapabilityAgent 2020-12-10 00:24:07.127 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.127 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:12 2020-12-10 00:24:07.127 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.127 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.129 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.129 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer 2020-12-10 00:24:07.129 [ 6] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer 2020-12-10 00:24:07.130 [ 1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true 2020-12-10 00:24:07.130 [ 1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false 2020-12-10 00:24:07.130 [ 1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SPEAKER_VOLUME 2020-12-10 00:24:07.130 [ 1] 5 ContextManager:setState 2020-12-10 00:24:07.130 [ 1] 9 ThreadPool:obtainWorker:created=1,obtained=1,releasedToPool=0,releasedFromPool=0,outstanding=1 2020-12-10 00:24:07.131 [ 9] 9 TaskThread:startThread:moniker= 9,duration=235 2020-12-10 00:24:07.132 [ 1] 9 ExternalMediaPlayer:startup 2020-12-10 00:24:07.133 [ 1] 7 DefaultClient:initialize::metrics disabled 2020-12-10 00:24:07.134 [ 1] 7 DefaultClient:initialize::power resource management disabled 2020-12-10 00:24:07.135 [ 1] 5 CaptionsComponent:createCaptionManagerInterface::captions disabled 2020-12-10 00:24:07.135 [ 1] 5 DefaultClient:nullCaptionManager::captions disabled 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.137 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.137 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.137 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:13 2020-12-10 00:24:07.138 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.138 [ 1] 8 DialogUXStateAggregator:timeout values in milliseconds:m_timeoutForThinkingToIdle=8000,m_shortTimeoutForThinkingToIdle=200,m_timeoutForListeningToIdle=8000 2020-12-10 00:24:07.139 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.139 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.140 [ 1] 9 ThreadPool:obtainWorker:created=2,obtained=2,releasedToPool=0,releasedFromPool=0,outstanding=2 2020-12-10 00:24:07.140 [ a] 9 TaskThread:startThread:moniker= a,duration=368 2020-12-10 00:24:07.140 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=3 2020-12-10 00:24:07.140 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.141 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveSequencer 2020-12-10 00:24:07.141 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.141 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=4 2020-12-10 00:24:07.141 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.141 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=DirectiveProcessor 2020-12-10 00:24:07.141 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.142 [ 1] 5 CapabilitiesDelegate:setMessageSender 2020-12-10 00:24:07.142 [ 1] 5 AVSGatewayManager:addObserver 2020-12-10 00:24:07.143 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.143 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.143 [ 1] 5 CapabilitiesDelegate:onConnectionStatusChanged:connectionStatus=DISCONNECTED 2020-12-10 00:24:07.143 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.143 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:14 2020-12-10 00:24:07.144 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.144 [ 1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x1069fa4 2020-12-10 00:24:07.144 [ 1] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=UNINITIALIZED,error=UNINITIALIZED,callback=true 2020-12-10 00:24:07.144 [ 1] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged 2020-12-10 00:24:07.145 [ 1] 9 ThreadPool:obtainWorker:created=3,obtained=3,releasedToPool=0,releasedFromPool=0,outstanding=3 2020-12-10 00:24:07.145 [ b] 9 TaskThread:startThread:moniker= b,duration=358 2020-12-10 00:24:07.146 [ 1] 5 SQLiteDeviceSettingStorage:open 2020-12-10 00:24:07.146 [ c] 9 DirectiveProcessor:processingLoop 2020-12-10 00:24:07.146 [ c] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.146 [ c] 9 PowerMonitor:assignThreadPowerResource:threadId=2829017600 2020-12-10 00:24:07.146 [ c] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.146 [ c] 5 ConditionVariableWrapper:waitInner 2020-12-10 00:24:07.147 [ d] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.147 [ d] 9 PowerMonitor:assignThreadPowerResource:threadId=2820624896 2020-12-10 00:24:07.147 [ d] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.147 [ d] 5 ConditionVariableWrapper:waitInner 2020-12-10 00:24:07.147 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.147 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:15 2020-12-10 00:24:07.147 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.148 [ 1] 5 DNDSettingProtocol:create:settingName=enabled 2020-12-10 00:24:07.148 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.148 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:16 2020-12-10 00:24:07.148 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.148 [ 1] 5 DNDSettingProtocol:restoreValue:setting=Alexa.DoNotDisturb\:\:enabled 2020-12-10 00:24:07.148 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alexa.DoNotDisturb\:\:enabled 2020-12-10 00:24:07.149 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.149 [ 1] I Setting:handleSetValue:value=false3990065800 2020-12-10 00:24:07.149 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.150 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.150 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=5 2020-12-10 00:24:07.151 [ 1] 5 SharedAVSSettingProtocol:create:settingName=alarmVolumeRamp 2020-12-10 00:24:07.151 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.151 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:17 2020-12-10 00:24:07.151 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.152 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.152 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.152 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=Alerts\:\:alarmVolumeRamp 2020-12-10 00:24:07.152 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts\:\:alarmVolumeRamp 2020-12-10 00:24:07.153 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.153 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=Alerts\:\:alarmVolumeRamp 2020-12-10 00:24:07.154 [ 1] 9 ThreadPool:obtainWorker:created=4,obtained=4,releasedToPool=0,releasedFromPool=0,outstanding=4 2020-12-10 00:24:07.155 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=6 2020-12-10 00:24:07.155 [ 1] 5 SharedAVSSettingProtocol:create:settingName=wakeWordConfirmation 2020-12-10 00:24:07.155 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.155 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:18 2020-12-10 00:24:07.155 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.155 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.155 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.155 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer\:\:wakeWordConfirmation 2020-12-10 00:24:07.155 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer\:\:wakeWordConfirmation 2020-12-10 00:24:07.156 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.156 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer\:\:wakeWordConfirmation 2020-12-10 00:24:07.156 [ 1] 9 ThreadPool:obtainWorker:created=5,obtained=5,releasedToPool=0,releasedFromPool=0,outstanding=5 2020-12-10 00:24:07.156 [ f] 9 TaskThread:startThread:moniker= f,duration=339 2020-12-10 00:24:07.156 [ e] 9 TaskThread:startThread:moniker= e,duration=2370 2020-12-10 00:24:07.157 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=7 2020-12-10 00:24:07.157 [ 1] 5 SharedAVSSettingProtocol:create:settingName=speechConfirmation 2020-12-10 00:24:07.157 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.157 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:19 2020-12-10 00:24:07.157 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.157 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.157 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.157 [ e] I Setting:handleSetValue:value="NONE"3990065800 2020-12-10 00:24:07.157 [ f] I Setting:handleSetValue:value="NONE"3990065800 2020-12-10 00:24:07.158 [ e] 5 SQLiteDeviceSettingStorage:storeSetting:key=Alerts\:\:alarmVolumeRamp,status=LOCAL_CHANGE_IN_PROGRESS 2020-12-10 00:24:07.158 [ f] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer\:\:wakeWordConfirmation,status=LOCAL_CHANGE_IN_PROGRESS 2020-12-10 00:24:07.158 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer\:\:speechConfirmation 2020-12-10 00:24:07.158 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer\:\:speechConfirmation 2020-12-10 00:24:07.183 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2020-12-10 00:24:07.183 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.183 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.183 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.184 [ 5] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=419 2020-12-10 00:24:07.185 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.185 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.185 [ 5] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2020-12-10 00:24:07.188 [ 5] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2020-12-10 00:24:07.188 [ 5] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found. 2020-12-10 00:24:07.188 [ 5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition 2020-12-10 00:24:07.189 [ 4] 9 InternetConnectionMonitor:testConnection:contentLength=419 2020-12-10 00:24:07.189 [ 4] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition 2020-12-10 00:24:07.202 [ e] 5 SettingEventSender:buildEventJson:eventName=AlarmVolumeRampChanged 2020-12-10 00:24:07.204 [ e] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.204 [ e] 0 EventBuilder:buildJsonEventString:messageId=82f7a52f-6aef-4c1e-81e1-a78a3ab548a8,namespace=Alerts,name=AlarmVolumeRampChanged 2020-12-10 00:24:07.205 [ e] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlarmVolumeRampChanged"\,"messageId"\:"82f7a52f-6aef-4c1e-81e1-a78a3ab548a8"}\,"payload"\:{"alarmVolumeRamp"\:"NONE"}}} 2020-12-10 00:24:07.206 [ e] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.206 [ e] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.207 [ e] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.207 [ e] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2020-12-10 00:24:07.234 [ f] 5 SettingEventSender:buildEventJson:eventName=WakeWordConfirmationChanged 2020-12-10 00:24:07.234 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.234 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=SpeechRecognizer\:\:speechConfirmation 2020-12-10 00:24:07.234 [ f] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.234 [ 1] 9 ThreadPool:obtainWorker:created=6,obtained=6,releasedToPool=0,releasedFromPool=0,outstanding=6 2020-12-10 00:24:07.234 [ f] 0 EventBuilder:buildJsonEventString:messageId=394d570b-e31d-44cd-9f13-7f5d70409d8f,namespace=SpeechRecognizer,name=WakeWordConfirmationChanged 2020-12-10 00:24:07.235 [ f] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"WakeWordConfirmationChanged"\,"messageId"\:"394d570b-e31d-44cd-9f13-7f5d70409d8f"}\,"payload"\:{"wakeWordConfirmation"\:"NONE"}}} 2020-12-10 00:24:07.235 [ f] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.235 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=8 2020-12-10 00:24:07.235 [ 1] 5 SharedAVSSettingProtocol:create:settingName=timeZone 2020-12-10 00:24:07.235 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.235 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:20 2020-12-10 00:24:07.235 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.235 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.235 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.235 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System\:\:timeZone 2020-12-10 00:24:07.235 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System\:\:timeZone 2020-12-10 00:24:07.236 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.236 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System\:\:timeZone 2020-12-10 00:24:07.236 [ 1] 9 ThreadPool:obtainWorker:created=7,obtained=7,releasedToPool=0,releasedFromPool=0,outstanding=7 2020-12-10 00:24:07.236 [ 11] 9 TaskThread:startThread:moniker= 11,duration=261 2020-12-10 00:24:07.236 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=9 2020-12-10 00:24:07.237 [ 1] 5 SharedAVSSettingProtocol:create:settingName=networkInfo 2020-12-10 00:24:07.237 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.237 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:21 2020-12-10 00:24:07.237 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.237 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.237 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.237 [ 1] 5 SharedAVSSettingProtocol:restoreValue:setting=System\:\:networkInfo 2020-12-10 00:24:07.238 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System\:\:networkInfo 2020-12-10 00:24:07.238 [ 11] I Setting:handleSetValue:value="America/Vancouver"3990065800 2020-12-10 00:24:07.238 [ 11] 5 SQLiteDeviceSettingStorage:storeSetting:key=System\:\:timeZone,status=LOCAL_CHANGE_IN_PROGRESS 2020-12-10 00:24:07.238 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.238 [ 1] 5 SharedAVSSettingProtocol:localChange:setting=System\:\:networkInfo 2020-12-10 00:24:07.238 [ 1] 9 ThreadPool:obtainWorker:created=8,obtained=8,releasedToPool=0,releasedFromPool=0,outstanding=8 2020-12-10 00:24:07.239 [ 12] 9 TaskThread:startThread:moniker= 12,duration=416 2020-12-10 00:24:07.239 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=10 2020-12-10 00:24:07.239 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=11 2020-12-10 00:24:07.239 [ 1] 5 LocaleWakeWordsSetting:create:settingName=LocaleWakeWords 2020-12-10 00:24:07.239 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=connectionType 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=ESSID 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=BSSID 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=IPAddress 2020-12-10 00:24:07.240 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.235 [ 10] 9 TaskThread:startThread:moniker= 10,duration=566 2020-12-10 00:24:07.240 [ 10] I Setting:handleSetValue:value="NONE"3990065800 2020-12-10 00:24:07.240 [ 10] 5 SQLiteDeviceSettingStorage:storeSetting:key=SpeechRecognizer\:\:speechConfirmation,status=LOCAL_CHANGE_IN_PROGRESS 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=subnetMask 2020-12-10 00:24:07.235 [ f] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.240 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:22 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=MACAddress 2020-12-10 00:24:07.240 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=DHCPServerAddress 2020-12-10 00:24:07.240 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=staticIP 2020-12-10 00:24:07.240 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System.locales 2020-12-10 00:24:07.240 [ f] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.241 [ 12] I Setting:handleSetValue:value={}3990065800 2020-12-10 00:24:07.241 [ f] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2020-12-10 00:24:07.241 [ 12] 5 SQLiteDeviceSettingStorage:storeSetting:key=System\:\:networkInfo,status=LOCAL_CHANGE_IN_PROGRESS 2020-12-10 00:24:07.261 [ 11] 5 SettingEventSender:buildEventJson:eventName=TimeZoneChanged 2020-12-10 00:24:07.262 [ 11] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.263 [ 11] 0 EventBuilder:buildJsonEventString:messageId=216860b3-d823-4c45-8302-7963b46f9f8f,namespace=System,name=TimeZoneChanged 2020-12-10 00:24:07.263 [ 11] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"TimeZoneChanged"\,"messageId"\:"216860b3-d823-4c45-8302-7963b46f9f8f"}\,"payload"\:{"timeZone"\:"America/Vancouver"}}} 2020-12-10 00:24:07.263 [ 11] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.264 [ 11] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.264 [ 11] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.264 [ 11] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2020-12-10 00:24:07.282 [ 10] 5 SettingEventSender:buildEventJson:eventName=SpeechConfirmationChanged 2020-12-10 00:24:07.282 [ 1] 5 SQLiteDeviceSettingStorage:loadSetting 2020-12-10 00:24:07.282 [ 10] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.282 [ 10] 0 EventBuilder:buildJsonEventString:messageId=bedb4534-94cd-48e9-82fb-9e815413ef4c,namespace=SpeechRecognizer,name=SpeechConfirmationChanged 2020-12-10 00:24:07.282 [ 10] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"SpeechConfirmationChanged"\,"messageId"\:"bedb4534-94cd-48e9-82fb-9e815413ef4c"}\,"payload"\:{"speechConfirmation"\:"NONE"}}} 2020-12-10 00:24:07.283 [ 1] 2 LocaleWakeWordsSetting:restoreInitialValue:wakeWords=[],locale=["en-US"] 2020-12-10 00:24:07.283 [ 10] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.283 [ 10] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.283 [ 1] 9 ThreadPool:obtainWorker:created=9,obtained=9,releasedToPool=0,releasedFromPool=0,outstanding=9 2020-12-10 00:24:07.283 [ 10] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.283 [ 10] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2020-12-10 00:24:07.283 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.284 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.284 [ 13] 9 TaskThread:startThread:moniker= 13,duration=664 2020-12-10 00:24:07.284 [ 13] 5 LocaleWakeWordsSetting:executeChangeValue:RequestId=1,wwRequest=[],localeRequest=["en-US"] 2020-12-10 00:24:07.284 [ 13] I LocaleAssetsManager:changeAssets:Locale=["en-US"],WakeWords=[] 2020-12-10 00:24:07.285 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.285 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.285 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.286 [ 1] 9 MediaPlayer:addObserverCalled:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.287 [ 14] 0 MediaPlayer:handleAddObserverCalled:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.288 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.288 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.288 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.288 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.288 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:23 2020-12-10 00:24:07.288 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.289 [ a] 9 ThreadPool:obtainWorker:created=10,obtained=10,releasedToPool=0,releasedFromPool=0,outstanding=10 2020-12-10 00:24:07.289 [ 5] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=419,contentLength=419,currentContentReceived=419,remaining=0,totalBytesWritten=419 2020-12-10 00:24:07.289 [ 5] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=http\://spectrum.s3.amazonaws.com/kindle-wifi/wifistub.html 2020-12-10 00:24:07.290 [ 5] 9 LibCurlHttpContentFetcher:transferThread::end 2020-12-10 00:24:07.290 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2020-12-10 00:24:07.290 [ 5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition 2020-12-10 00:24:07.291 [ 15] 9 TaskThread:startThread:moniker= 15,duration=1689 2020-12-10 00:24:07.291 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.291 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.291 [ 16] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.293 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.293 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:24 2020-12-10 00:24:07.293 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.294 [ 1] 9 MediaPlayer:addObserverCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.294 [ 16] 0 MediaPlayer:handleAddObserverCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.294 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.295 [ 1] 9 SpeechSynthesizer:addObserver:observer=0xf8282c 2020-12-10 00:24:07.295 [ a] 9 ThreadPool:obtainWorker:created=11,obtained=11,releasedToPool=0,releasedFromPool=0,outstanding=11 2020-12-10 00:24:07.296 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.296 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.296 [ 14] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.296 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.297 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=BluetoothMediaPlayer 2020-12-10 00:24:07.297 [ 18] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=BluetoothMediaPlayer 2020-12-10 00:24:07.297 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.297 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer 2020-12-10 00:24:07.297 [ 17] 9 TaskThread:startThread:moniker= 17,duration=2009 2020-12-10 00:24:07.297 [ 19] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer 2020-12-10 00:24:07.298 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.298 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.298 [ 1a] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.298 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.298 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:25 2020-12-10 00:24:07.299 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.299 [ 1] 5 Renderer:resetSourceId 2020-12-10 00:24:07.299 [ 1] 9 MediaPlayer:addObserverCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.299 [ 1a] 0 MediaPlayer:handleAddObserverCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.300 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.300 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.300 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:26 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:27 2020-12-10 00:24:07.301 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.304 [ 1] 5 AlertScheduler:setTimerForNextAlertLocked 2020-12-10 00:24:07.304 [ 1] 8 AlertScheduler:executeScheduleNextAlertForRendering::no work to do. 2020-12-10 00:24:07.304 [ 1] 9 SpeakerManager:getSpeakerSettingsCalled 2020-12-10 00:24:07.304 [ 1] 9 ThreadPool:obtainWorker:created=12,obtained=12,releasedToPool=0,releasedFromPool=0,outstanding=12 2020-12-10 00:24:07.305 [ 1b] 9 TaskThread:startThread:moniker= 1b,duration=392 2020-12-10 00:24:07.305 [ 1b] 9 SpeakerManager:executeGetSpeakerSettingsCalled 2020-12-10 00:24:07.305 [ 1b] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.305 [ 1b] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.305 [ 1a] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer 2020-12-10 00:24:07.305 [ 1b] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true 2020-12-10 00:24:07.306 [ 1b] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false 2020-12-10 00:24:07.306 [ 1] 5 ContextManager:setState 2020-12-10 00:24:07.307 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.307 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.307 [ 1] 9 ThreadPool:obtainWorker:created=13,obtained=13,releasedToPool=0,releasedFromPool=0,outstanding=13 2020-12-10 00:24:07.308 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled 2020-12-10 00:24:07.308 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1077184 2020-12-10 00:24:07.309 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.309 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.309 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=1872 2020-12-10 00:24:07.309 [ 1c] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.310 [ 1] 5 NotificationRenderer:create 2020-12-10 00:24:07.310 [ 1] 5 StubApplicationAudioPipelineFactory:createApplicationMediaInterfaces 2020-12-10 00:24:07.310 [ 1] 0 ChannelVolumeManager:getSpeakerSettings 2020-12-10 00:24:07.310 [ a] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=IDLE,validTransition=false 2020-12-10 00:24:07.310 [ 1] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.310 [ 1d] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.311 [ 1] 5 NotificationRenderer:create 2020-12-10 00:24:07.311 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.311 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:28 2020-12-10 00:24:07.311 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.311 [ 1] 9 MediaPlayer:addObserverCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.311 [ 1d] 0 MediaPlayer:handleAddObserverCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.312 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.312 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:29 2020-12-10 00:24:07.312 [ 12] 5 SettingEventSender:buildEventJson:eventName=NetworkInfoChanged 2020-12-10 00:24:07.313 [ 12] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.313 [ 12] 0 EventBuilder:buildJsonEventString:messageId=4065b3ad-dbe6-4295-8c88-61942b96665e,namespace=System,name=NetworkInfoChanged 2020-12-10 00:24:07.313 [ 12] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"NetworkInfoChanged"\,"messageId"\:"4065b3ad-dbe6-4295-8c88-61942b96665e"}\,"payload"\:{"networkInfo"\:{}}}} 2020-12-10 00:24:07.313 [ 12] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.313 [ 12] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.313 [ 12] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.313 [ 12] E SharedAVSSettingProtocol:localChangeFailed:reason=sendEventFailed 2020-12-10 00:24:07.312 [ 13] 5 SQLiteDeviceSettingStorage:storeSettings 2020-12-10 00:24:07.312 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.314 [ 13] 5 SQLiteDeviceSettingStorage:storeSettings 2020-12-10 00:24:07.314 [ 1] 5 NotificationsCapabilityAgent:init 2020-12-10 00:24:07.314 [ 1] 5 NotificationRenderer:addObserver 2020-12-10 00:24:07.314 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.316 [ 1] 9 ThreadPool:obtainWorker:created=14,obtained=14,releasedToPool=0,releasedFromPool=0,outstanding=14 2020-12-10 00:24:07.316 [ 1] 5 InteractionModel:InteractionModelCapabilityAgent 2020-12-10 00:24:07.317 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.317 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:30 2020-12-10 00:24:07.317 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.317 [ 1] 5 ContextManager:addStateProvider 2020-12-10 00:24:07.318 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.318 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:31 2020-12-10 00:24:07.318 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.318 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.318 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.318 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.318 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.318 [ 1] 5 PowerMonitor:getPowerResourceManager 2020-12-10 00:24:07.318 [ 1] E PowerResource:create:error=nullPowerManager 2020-12-10 00:24:07.319 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.319 [ 1] 5 SDKPrimitivesProvider:getTimerDelegateFactory 2020-12-10 00:24:07.319 [ 1] 5 SDKPrimitivesProvider:isInitialized 2020-12-10 00:24:07.319 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.319 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:32 2020-12-10 00:24:07.319 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.320 [ 1] 1 AudioPlayer:setObserver 2020-12-10 00:24:07.320 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=4488 2020-12-10 00:24:07.321 [ 1e] 5 NotificationsCapabilityAgent:executeInit 2020-12-10 00:24:07.321 [ 1] 9 ThreadPool:obtainWorker:created=15,obtained=15,releasedToPool=0,releasedFromPool=0,outstanding=15 2020-12-10 00:24:07.321 [ 1] 5 ExternalMediaPlayer:setObserver 2020-12-10 00:24:07.321 [ 1e] 5 NotificationsCapabilityAgent:notifyObserversOfIndicatorState:indicatorState=0 2020-12-10 00:24:07.322 [ 1] 5 ExternalCapabilitiesBuilder:withTemplateRunTime 2020-12-10 00:24:07.322 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=1442 2020-12-10 00:24:07.322 [ a] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE 2020-12-10 00:24:07.322 [ 1e] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0,isEnabled=false 2020-12-10 00:24:07.323 [ a] 9 ThreadPool:obtainWorker:created=16,obtained=16,releasedToPool=0,releasedFromPool=0,outstanding=16 2020-12-10 00:24:07.323 [ 1e] 5 ContextManager:setState 2020-12-10 00:24:07.323 [ 20] 9 TaskThread:startThread:moniker= 20,duration=783 2020-12-10 00:24:07.324 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.324 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:33 2020-12-10 00:24:07.324 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.325 [ 1] 5 ContextManager:setState 2020-12-10 00:24:07.327 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.328 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:34 2020-12-10 00:24:07.328 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.328 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.328 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:35 2020-12-10 00:24:07.328 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.330 [ 1] 9 ConditionVariableWrapper:ConditionVariableWrapper:id=12 2020-12-10 00:24:07.331 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.331 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:36 2020-12-10 00:24:07.331 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.332 [ 13] 5 LocaleWakeWordsSetting:notifyObserversOfSuccess:id=1 2020-12-10 00:24:07.332 [ 2] 5 HttpPost:doPostSucceeded:code=200 2020-12-10 00:24:07.332 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:engineType=1,status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.332 [ 1] 9 AbstractAVSConnectionManager:addConnectionStatusObserver:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.332 [ 13] 5 SettingEventSender:buildEventJson:eventName=LocalesChanged 2020-12-10 00:24:07.333 [ 13] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32 2020-12-10 00:24:07.333 [ 2] 5 CBLAuthDelegate:receiveCodePairResponse:code=200 2020-12-10 00:24:07.334 [ 13] 0 EventBuilder:buildJsonEventString:messageId=e7f98163-cffa-465a-ae76-30c48850317e,namespace=System,name=LocalesChanged 2020-12-10 00:24:07.334 [ 1] 5 DefaultClient:bluetoothCapabilityAgentDisabled:reason=nullBluetoothDeviceManager 2020-12-10 00:24:07.334 [ 13] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"LocalesChanged"\,"messageId"\:"e7f98163-cffa-465a-ae76-30c48850317e"}\,"payload"\:{"locales"\:["en-US"]}}} 2020-12-10 00:24:07.334 [ 2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS 2020-12-10 00:24:07.334 [ 1] 5 ApiGateway:create 2020-12-10 00:24:07.334 [ 13] 7 SettingEventSender:sendEvent:attempt=0 2020-12-10 00:24:07.334 [ 13] E MessageRouter:sendFailed:reason=noActiveTransport 2020-12-10 00:24:07.334 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.335 [ 1] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:37 2020-12-10 00:24:07.335 [ 1] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2020-12-10 00:24:07.335 [ 13] 7 SettingEventSender:sendEvent:status=NOT_CONNECTED 2020-12-10 00:24:07.335 [ 2] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS 2020-12-10 00:24:07.335 [ 13] E LocaleWakeWordsSetting:synchronizeLocaleFailed:reason=sendEventFailed 2020-12-10 00:24:07.335 [ 1] 0 DefaultClient:initialize::Diagnostics Not Enabled 2020-12-10 00:24:07.335 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.336 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.337 [ 1] 5 DefaultEndpointBuilder:withCapability 2020-12-10 00:24:07.337 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration ##################################
NOT YET AUTHORIZED
##################################
################################################################################################
To authorize, browse to: 'https://amazon.com/us/code' and enter the code: FVZXNV
################################################################################################
2020-12-10 00:24:07.338 [ 2] 5 CBLAuthDelegate:handleRequestingToken 2020-12-10 00:24:07.338 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetLocales,handler=0xf66da8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.338 [ 2] 5 CBLAuthDelegate:requestToken #################################################
Checking for authorization (1)...
#################################################
2020-12-10 00:24:07.339 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetTimeZone,handler=0x10a6af0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.339 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ReportState,handler=0xf49e60,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.340 [ 1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0xf1fb04,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.340 [ 1] 5 ExternalCapabilitiesBuilder:buildCapabilities 2020-12-10 00:24:07.340 [ 1] 5 DefaultEndpointBuilder:withCapabilityConfiguration 2020-12-10 00:24:07.347 [ 1] 9 SpeakerManager:addSpeakerManagerObserverCalled 2020-12-10 00:24:07.347 [ 1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0xf679e4 2020-12-10 00:24:07.347 [ 1] 5 NotificationsCapabilityAgent:addObserver 2020-12-10 00:24:07.348 [ 1] 5 DefaultClient:addBluetoothDeviceObserver::bluetooth is disabled, not adding observer #################################################
NOTIFICATION INDICATOR STATE: OFF
#################################################
2020-12-10 00:24:07.350 [ 1] 5 TemplateRuntime:addObserver 2020-12-10 00:24:07.350 [ 20] 5 TemplateRuntime:addObserverInExecutor 2020-12-10 00:24:07.352 [ 1] C PortAudioMicrophoneWrapper:Failed to open PortAudio default stream:errorCode=-9985 2020-12-10 00:24:07.352 [ 1] C PortAudioMicrophoneWrapper:Failed to initialize PortAudioMicrophoneWrapper 2020-12-10 00:24:07.353 [ 1] C SampleApplication:Failed to create microphone wrapper! 2020-12-10 00:24:07.353 [ 1] 5 DefaultClient:DirectiveSequencerShutdown 2020-12-10 00:24:07.353 [ 1] 9 DirectiveSequencer:doShutdown 2020-12-10 00:24:07.353 [ 1] 5 ConditionVariableWrapper:notifyOne 2020-12-10 00:24:07.353 [ d] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.353 [ d] 9 PowerMonitor:removeThreadPowerResource:threadId=2820624896 2020-12-10 00:24:07.353 [ 1] 9 DirectiveProcessor:queueAllDirectivesForCancellationLocked 2020-12-10 00:24:07.354 [ 1] 5 ConditionVariableWrapper:notifyOne 2020-12-10 00:24:07.354 [ c] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2020-12-10 00:24:07.354 [ c] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.354 [ c] 9 PowerMonitor:removeThreadPowerResource:threadId=2829017600 2020-12-10 00:24:07.354 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ReportState,handler=0xf49e60,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetLocales,handler=0xf66da8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=ResetUserInactivity,handler=0xf1fb04,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:removeDirectiveHandlers:action=removed,namespace=System,name=SetTimeZone,handler=0x10a6af0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0xf49e60 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0xf66da8 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0xf1fb04 2020-12-10 00:24:07.355 [ 1] 9 DirectiveRouter:onDeregisteredCalled:handler=0x10a6af0 2020-12-10 00:24:07.356 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.356 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.356 [ 1] 5 DefaultClient:TemplateRuntimeShutdown 2020-12-10 00:24:07.358 [ 1] 1 AudioPlayer:setObserver 2020-12-10 00:24:07.359 [ 1] 5 ExternalMediaPlayer:setObserver 2020-12-10 00:24:07.359 [ 1] 5 DefaultClient:AIPShutdown 2020-12-10 00:24:07.359 [ 1] 0 AudioInputProcessor:executeResetState 2020-12-10 00:24:07.360 [ 1] 5 DefaultClient:SpeechSynthesizerShutdown 2020-12-10 00:24:07.360 [ 1] 9 SpeechSynthesizer:doShutdown 2020-12-10 00:24:07.360 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.360 [ 1] 9 MediaPlayer:removeObserverCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.360 [ 16] 0 MediaPlayer:handleRemoveObserverCalled:name=SpeakMediaPlayer 2020-12-10 00:24:07.361 [ 1] 5 DefaultClient:AlertsShutdown 2020-12-10 00:24:07.361 [ 1] 9 AlertsCapabilityAgent:releaseChannel 2020-12-10 00:24:07.362 [ 1] 5 DefaultClient:CertifiedSenderShutdown. 2020-12-10 00:24:07.362 [ 1] 5 DefaultClient:VisualActivityTrackerShutdown. 2020-12-10 00:24:07.362 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.362 [ 1] 5 DefaultClient:NotificationsShutdown. 2020-12-10 00:24:07.362 [ 1] 5 NotificationsCapabilityAgent:doShutdown 2020-12-10 00:24:07.362 [ 1e] 5 NotificationsCapabilityAgent:executeShutdown:currentState=IDLE 2020-12-10 00:24:07.362 [ 1e] 5 NotificationsCapabilityAgent:executeSetState:previousState=IDLE,newState=SHUTDOWN 2020-12-10 00:24:07.362 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.363 [ 1] 5 DefaultClient:NotificationsRendererShutdown. 2020-12-10 00:24:07.363 [ 1] 5 NotificationRenderer:doShutdown 2020-12-10 00:24:07.363 [ 1] 9 MediaPlayer:removeObserverCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.363 [ 1d] 0 MediaPlayer:handleRemoveObserverCalled:name=NotificationsMediaPlayer 2020-12-10 00:24:07.364 [ 1] 1 FocusManager:releaseChannel:channelName=Earcon 2020-12-10 00:24:07.365 [ 1] 9 ThreadPool:obtainWorker:created=17,obtained=17,releasedToPool=5,releasedFromPool=0,outstanding=12 2020-12-10 00:24:07.365 [ 20] 9 TaskThread:startThread:moniker= 20,duration=437 2020-12-10 00:24:07.365 [ 1] 5 DefaultClient:UserInactivityMonitorShutdown. 2020-12-10 00:24:07.365 [ 1] 5 DefaultClient:CallApiGatewayCapabilityAgentShutdown. 2020-12-10 00:24:07.366 [ 20] 5 FocusManager:releaseChannelHelper:channelToRelease=Earcon 2020-12-10 00:24:07.366 [ 1] 5 DefaultClient:DNDCapabilityAgentShutdown 2020-12-10 00:24:07.366 [ 20] 0 Channel:releaseActivityFailed::Observer not found 2020-12-10 00:24:07.366 [ 20] E FocusManager:releaseChannelHelper:reason=releaseActivityFailed,channel=0xe87448,interface= 2020-12-10 00:24:07.366 [ 1] 5 DefaultClient:EqualizerCapabilityAgentShutdown 2020-12-10 00:24:07.367 [ 1] 5 DefaultClient:CloseSettingStorage 2020-12-10 00:24:07.367 [ 1] 5 SQLiteDeviceSettingStorage:close 2020-12-10 00:24:07.369 [ 1] 5 CapabilitiesDelegate:removeCapabilitiesObserver:observer=0x1069fa4 2020-12-10 00:24:07.371 [ 1] 5 InteractionModel:~InteractionModelCapabilityAgent 2020-12-10 00:24:07.374 [ 1] C SampleApplication:Failed to initialize SampleApplication 2020-12-10 00:24:07.374 [ 1] 5 ProgressTimer:stop 2020-12-10 00:24:07.375 [ 1] 1 AudioPlayer:executeStop:playNextItem=false,m_currentActivity=IDLE,sourceId=0 2020-12-10 00:24:07.375 [ 1] 9 PooledMediaPlayerFactory:~PooledMediaPlayerFactory 2020-12-10 00:24:07.376 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.376 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.376 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.377 [ 1] 5 ContextManager:removeStateProvider 2020-12-10 00:24:07.377 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.377 [ 1] 5 AVSConnectionManager:disable 2020-12-10 00:24:07.377 [ 1] I MessageRouter:disable 2020-12-10 00:24:07.377 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0 2020-12-10 00:24:07.377 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.378 [ 1] 9 DirectiveProcessor:queueAllDirectivesForCancellationLocked 2020-12-10 00:24:07.378 [ 1] 5 ConditionVariableWrapper:notifyOne 2020-12-10 00:24:07.379 [ 1] I MessageRouter:disable 2020-12-10 00:24:07.380 [ 1] I MessageRouter:disconnectAllTransportsLocked:reason=ACL_CLIENT_REQUEST,sizeOf m_transports=0,m_activeTransport=0 2020-12-10 00:24:07.380 [ 1] I MessageRouter:setConnectionStatusLocked:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST 2020-12-10 00:24:07.380 [ 1] 5 AVSGatewayManager:~AVSGatewayManager 2020-12-10 00:24:07.384 [ 1] 5 ConditionVariableWrapper:notifyOne 2020-12-10 00:24:07.384 [ 1] 5 ConditionVariableWrapper:notifyOne 2020-12-10 00:24:07.384 [ 8] 9 CertifiedSender:CertifiedSender worker thread done. Exiting mainloop. 2020-12-10 00:24:07.384 [ 8] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.384 [ 8] 9 PowerMonitor:removeThreadPowerResource:threadId=2845803008 2020-12-10 00:24:07.385 [ 1] 5 ProgressTimer:stop 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=SpeakMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=AudioMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=NotificationsMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=BluetoothMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=RingtoneMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=AlertsMediaPlayer 2020-12-10 00:24:07.386 [ 1] 9 MediaPlayer:doShutdown:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.386 [ 1] 5 CapabilitiesDelegate:doShutdown 2020-12-10 00:24:07.387 [ 1] 5 CapabilitiesDelegate:resetCurrentDiscoveryEventSender 2020-12-10 00:24:07.387 [ 1] 9 PowerMonitor:getInstance 2020-12-10 00:24:07.387 [ 1] 9 PowerMonitor:deactivate 2020-12-10 00:24:07.387 [ 1] 5 SDKPrimitivesProvider:getInstance 2020-12-10 00:24:07.387 [ 1] 5 SDKPrimitivesProvider:terminate 2020-12-10 00:24:07.387 [ 1] 5 SDKPrimitivesProvider:reset 2020-12-10 00:24:07.387 [ 1] 9 MediaPlayer:~MediaPlayer:name=SpeakMediaPlayer 2020-12-10 00:24:07.388 [ 1] 9 MediaPlayer:resetPipeline:name=SpeakMediaPlayer 2020-12-10 00:24:07.389 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer 2020-12-10 00:24:07.390 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer 2020-12-10 00:24:07.390 [ 1] 9 MediaPlayer:~MediaPlayer:name=AudioMediaPlayer 2020-12-10 00:24:07.390 [ 4] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes 2020-12-10 00:24:07.390 [ 4] 9 InternetConnectionMonitor:testConnection::Finished reading 2020-12-10 00:24:07.390 [ 4] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true 2020-12-10 00:24:07.390 [ 4] 5 InternetConnectionMonitor:notifyObserversLocked 2020-12-10 00:24:07.391 [ 4] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher 2020-12-10 00:24:07.392 [ 1] 9 MediaPlayer:resetPipeline:name=AudioMediaPlayer 2020-12-10 00:24:07.392 [ 1] 9 MediaPlayer:~MediaPlayer:name=NotificationsMediaPlayer 2020-12-10 00:24:07.393 [ 1] 9 MediaPlayer:resetPipeline:name=NotificationsMediaPlayer 2020-12-10 00:24:07.393 [ 1] 9 MediaPlayer:~MediaPlayer:name=BluetoothMediaPlayer 2020-12-10 00:24:07.394 [ 1] 9 MediaPlayer:resetPipeline:name=BluetoothMediaPlayer 2020-12-10 00:24:07.394 [ 1] 9 MediaPlayer:~MediaPlayer:name=RingtoneMediaPlayer 2020-12-10 00:24:07.395 [ 1] 9 MediaPlayer:resetPipeline:name=RingtoneMediaPlayer 2020-12-10 00:24:07.395 [ 1] 9 MediaPlayer:~MediaPlayer:name=AlertsMediaPlayer 2020-12-10 00:24:07.396 [ 1] 9 MediaPlayer:resetPipeline:name=AlertsMediaPlayer 2020-12-10 00:24:07.396 [ 1] 9 MediaPlayer:~MediaPlayer:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.397 [ 1] 9 MediaPlayer:resetPipeline:name=SystemSoundMediaPlayer 2020-12-10 00:24:07.397 [ 1] 5 SQLiteCapabilitiesDelegateStorage:close 2020-12-10 00:24:07.397 [ 1] 5 CBLAuthDelegate:stop 2020-12-10 00:24:07.581 [ 2] 5 HttpPost:doPostSucceeded:code=400 2020-12-10 00:24:07.582 [ 2] 5 CBLAuthDelegate:receiveTokenResponse:code=400 2020-12-10 00:24:07.582 [ 2] 5 CBLAuthDelegate:mapHTTPStatusToError:code=400,error=INVALID_REQUEST 2020-12-10 00:24:07.582 [ 2] 5 CBLAuthDelegate:errorInLwaResponseBody:error=authorization_pending,errorCode=AUTHORIZATION_PENDING 2020-12-10 00:24:07.582 [ 2] 5 CBLAuthDelegate:setAuthError:authError=AUTHORIZATION_PENDING 2020-12-10 00:24:07.582 [ 2] 0 CBLAuthDelegate:receiveTokenResponseFailed:result=AUTHORIZATION_PENDING 2020-12-10 00:24:07.583 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.584 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.584 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.584 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.584 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.584 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.584 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.584 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.585 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.585 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.585 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.585 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.585 [ 1] 5 SQLiteDeviceSettingStorage:close 2020-12-10 00:24:07.585 [ 1] 9 SettingEventSender:cancel 2020-12-10 00:24:07.585 [ 1] 5 ConditionVariableWrapper:notifyAll 2020-12-10 00:24:07.585 [ 1] 5 InternetConnectionMonitor:stopMonitoring 2020-12-10 00:24:07.588 [ 1] 5 SQLiteCBLAuthDelegateStorage:~SQLiteCBLAuthDelegateStorage 2020-12-10 00:24:07.588 [ 1] 5 SQLiteCBLAuthDelegateStorage:close Failed to create SampleApplication!
Provide the steps to reproduce the issue, if applicable:
cd $HOME/sdk_folder/sdk-build PA_ALSA_PLUGHW=1 ./SampleApp/src/SampleApp ./Integration/AlexaClientSDKConfig.json DEBUG9
Tell us about your environment:
What version of the AVS Device SDK are you using?
1.21.0
Tell us what hardware you're using:
Tell us about your OS (Type & version):
Raspian Buster
Have you tried the same use case with AVS Device SDK SampleApp?
-followed case 1841 and 1090. i don't know how to run patest1. ~/.asoundrc = pcm.!default { type asym playback.pcm { type plug slave.pcm "hw:2,0" } capture.pcm { type plug slave.pcm "hw:2,0" } } arecord and aplay work for both microphones