ass-sdk: Say "Alexa, give me a music", Alexa can not play music and speak “have trouble ...”

[liuyiamlogic]

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

Briefly summarize your issue:

When I'm asking alexa to "give me a music", Alexa can not play music and speak “have trouble ...”; But when I'm asking alexa to "give me a story", It works ok.

What is the expected behavior?

Music can be played normally。

What behavior are you observing?

Music can be played normally。

Provide the steps to reproduce the issue, if applicable:

Say，“alexa，give me a music ”

Tell us about your environment:

What version of the AVS Device SDK are you using?

1.25.0

Tell us what hardware you're using:

• [ ] Desktop / Laptop
• [ ] Raspberry Pi
• [x] Other - tell us more: arm linux device.

Tell us about your OS (Type & version):

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

Have you tried the same use case with AVS Device SDK SampleApp?

• [x] The issue is not applicable to the AVS Device SDK SampleApp.
• [ ] The issue is not reproducible on the AVS Device SDK SampleApp.

[aravnata]

Could you please share what version of the Alexa Smart Screen SDK you are using?

Also, could you please try playing music with the AVS Device SDK SampleApp and let us know if that works?

[liuyiamlogic]

Hi, AVS also has this problem. Here is the ass logs that I say "playing music"：

Amazon Keyword startIndex 613120 endIndex 622400

2022-06-01 00:00:43.065 [ 24] 9 KeywordObserver:computeStartOfSpeechTimestamp:timeSinceStartOfWW=784 2022-06-01 00:00:43.066 [ 24] 5 SmartScreenClient:notifyOfWakeWord:keyword=ALEXA,connected=true 2022-06-01 00:00:43.066 [ 24] 5 AudioInputProcessor:recognize 2022-06-01 00:00:43.066 [ 24] 9 ThreadPool:obtainWorker:created=16,obtained=92,releasedToPool=91,releasedFromPool=0,outstanding=1 2022-06-01 00:00:43.066 [ 2] 9 TaskThread:startThread:moniker= 2,duration=305 2022-06-01 00:00:43.067 [ 2] 0 AudioInputProcessor:notEncodingAudio 2022-06-01 00:00:43.067 [ 2] 0 AudioInputProcessor:Create audio reader:format=LPCM,offset=605120,encodingOffset=0 2022-06-01 00:00:43.067 [ 2] I AudioInputProcessor:Create audio attachment reader success:resolveKey=DEFAULT_RESOLVE_KEY,format=AUDIO_L16_RATE_16000_CHANNELS_1 2022-06-01 00:00:43.067 [ 2] W MetricEventBuilder:addDataPointFailed:reason=invalidDataPoint 2022-06-01 00:00:43.069 [ 2] I UUIDGeneration:low entropy on call to generate UUID:current entropy=0 2022-06-01 00:00:43.070 [ 2] 5 AudioInputProcessor:setState:currentDialogRequestId=c890ca2d-4b05-47b0-a187-3c352223a019 2022-06-01 00:00:43.071 [ 2] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING 2022-06-01 00:00:43.071 [ 2] 0 DialogUXStateAggregator:onStateChanged:AudioInputProcessorState=RECOGNIZING 2022-06-01 00:00:43.071 [ 2] 9 ThreadPool:obtainWorker:created=16,obtained=93,releasedToPool=91,releasedFromPool=0,outstanding=2 2022-06-01 00:00:43.071 [ 18] 9 TaskThread:startThread:moniker= 18,duration=424 2022-06-01 00:00:43.072 [ 18] 0 DialogUXStateAggregator:onStateChangedLambda:AudioInputProcessorState=RECOGNIZING 2022-06-01 00:00:43.072 [ 18] 0 DialogUXStateAggregator:executeSetState:from=IDLE,to=LISTENING,validTransition=true 2022-06-01 00:00:43.072 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=94,releasedToPool=91,releasedFromPool=0,outstanding=3 2022-06-01 00:00:43.072 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=423 2022-06-01 00:00:43.073 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=95,releasedToPool=91,releasedFromPool=0,outstanding=4 2022-06-01 00:00:43.073 [ 18] 5 TemplateRuntime:onDialogUXStateChanged:state=LISTENING 2022-06-01 00:00:43.073 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=96,releasedToPool=91,releasedFromPool=0,outstanding=5 2022-06-01 00:00:43.073 [ 18] 5 AlexaPresentation:onDialogUXStateChanged:state=LISTENING 2022-06-01 00:00:43.074 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=97,releasedToPool=91,releasedFromPool=0,outstanding=6 2022-06-01 00:00:43.074 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=98,releasedToPool=91,releasedFromPool=0,outstanding=7 2022-06-01 00:00:43.074 [ e] 9 TaskThread:startThread:moniker= e,duration=1425 2022-06-01 00:00:43.074 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=1284 2022-06-01 00:00:43.075 [ 19] 9 TaskThread:startThread:moniker= 19,duration=1157 2022-06-01 00:00:43.075 [ 12] 9 TaskThread:startThread:moniker= 12,duration=1171 2022-06-01 00:00:43.075 [ 12] 9 ThreadPool:obtainWorker:created=16,obtained=99,releasedToPool=91,releasedFromPool=0,outstanding=8 ######################################

ALEXA STATE: LISTENING

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

2022-06-01 00:00:43.076 [ 1c] 3 GUIManager:operator():Interrupted activity while speaking or listening=INTERRUPT 2022-06-01 00:00:43.076 [ 22] 9 TaskThread:startThread:moniker= 22,duration=555 2022-06-01 00:00:43.076 [ 22] 9 WebSocketServer:writeMessageBegin 2022-06-01 00:00:43.076 [ 22] 9 WebSocket:AccessLog 2022-06-01 00:00:43.076 [ 22] 9 WebSocket:AccessLog 2022-06-01 00:00:43.077 [ 22] 9 WebSocketServer:writeMessageComplete 2022-06-01 00:00:43.077 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:43.077 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:43.077 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:43.077 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:43.078 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:43.076 [ 1c] 1 FocusManager:releaseChannel:channelName=Dialog 2022-06-01 00:00:43.084 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=100,releasedToPool=91,releasedFromPool=0,outstanding=9 2022-06-01 00:00:43.084 [ f] 9 TaskThread:startThread:moniker= f,duration=407 2022-06-01 00:00:43.084 [ f] 5 FocusManager:releaseChannelHelper:channelToRelease=Dialog 2022-06-01 00:00:43.417 [ f] 0 Channel:releaseActivityFailed::Observer not found 2022-06-01 00:00:43.417 [ f] E FocusManager:releaseChannelHelper:reason=releaseActivityFailed,channel=0x20e668,interface= 2022-06-01 00:00:43.084 [ 19] 5 AlexaPresentation:executeClearExecuteCommands 2022-06-01 00:00:43.076 [ 2] 9 ConnectionRetryTrigger:onStateChanged:state=RECOGNIZING 2022-06-01 00:00:43.420 [ 2] 9 AVSConnectionManager:onWakeConnectionRetry:this=0x25ca00 2022-06-01 00:00:43.420 [ 2] I MessageRouter:onWakeConnectionRetry 2022-06-01 00:00:43.420 [ 2] I MessageRouter:onWakeConnectionRetry:m_activeTransport=0x24cc80 2022-06-01 00:00:43.420 [ 2] I HTTP2Transport:onWakeConnectionRetry:this=0x24cc80 2022-06-01 00:00:43.421 [ 2] 5 ContextManager:getContextWithoutReportableStateProperties 2022-06-01 00:00:43.421 [ 2] 5 ContextManager:getContextInternal 2022-06-01 00:00:43.421 [ 2] 9 ThreadPool:obtainWorker:created=16,obtained=101,releasedToPool=91,releasedFromPool=0,outstanding=10 2022-06-01 00:00:43.422 [ 5] 9 TaskThread:startThread:moniker= 5,duration=563 2022-06-01 00:00:43.423 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=102,releasedToPool=91,releasedFromPool=0,outstanding=11 2022-06-01 00:00:43.424 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=103,releasedToPool=91,releasedFromPool=0,outstanding=12 2022-06-01 00:00:43.424 [ 5] 5 VisualActivityTracker:provideState 2022-06-01 00:00:43.425 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=104,releasedToPool=91,releasedFromPool=0,outstanding=13 2022-06-01 00:00:43.425 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=105,releasedToPool=91,releasedFromPool=0,outstanding=14 2022-06-01 00:00:43.425 [ 5] 5 AudioActivityTracker:provideState 2022-06-01 00:00:43.426 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=106,releasedToPool=91,releasedFromPool=0,outstanding=15 2022-06-01 00:00:43.426 [ 3] 9 TaskThread:startThread:moniker= 3,duration=2909 2022-06-01 00:00:43.426 [ 21] 9 TaskThread:startThread:moniker= 21,duration=2759 2022-06-01 00:00:43.427 [ 21] 5 ContextManager:setState 2022-06-01 00:00:43.427 [ 11] 9 TaskThread:startThread:moniker= 11,duration=2452 2022-06-01 00:00:43.427 [ 11] 5 VisualActivityTracker:executeProvideState 2022-06-01 00:00:43.427 [ 11] 5 ContextManager:setState 2022-06-01 00:00:43.428 [ 25] 9 TaskThread:startThread:moniker= 25,duration=2681 2022-06-01 00:00:43.428 [ 25] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3 2022-06-01 00:00:43.428 [ 25] 0 ExternalMediaPlayer:executeProvideState:provideSessionState={"agent"\:""\,"spiVersion"\:"1.0"\,"playerInFocus"\:""\,"players"\:[]} 2022-06-01 00:00:43.428 [ 25] 5 ContextManager:setState 2022-06-01 00:00:43.429 [ 19] 3 AlexaPresentation:executeProvideState:token=3 2022-06-01 00:00:43.429 [ 19] 5 ContextManager:setState 2022-06-01 00:00:43.430 [ 23] 9 TaskThread:startThread:moniker= 23,duration=4217 2022-06-01 00:00:43.430 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=6 2022-06-01 00:00:43.431 [ 23] 5 AudioActivityTracker:executeProvideState 2022-06-01 00:00:43.431 [ 23] 5 ContextManager:setState 2022-06-01 00:00:43.432 [ 25] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3 2022-06-01 00:00:43.432 [ 25] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers 2022-06-01 00:00:43.433 [ 25] 0 ExternalMediaPlayer:executeProvideState:providePlaybackState={"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"players"\:[]} 2022-06-01 00:00:43.433 [ 25] 5 ContextManager:setState 2022-06-01 00:00:43.423 [ 2] 0 AudioInputProcessor:executeRecognize:WW_DURATION(ms)=1080 2022-06-01 00:00:43.434 [ 5] I ContextManager:updateCapabilityState Amazon Keyword startIndex 613440 endIndex 622720 2022-06-01 00:00:43.434 [ 2] 5 UserInactivityMonitor:onUserActive 2022-06-01 00:00:43.434 [ 24] 9 KeywordObserver:computeStartOfSpeechTimestamp:timeSinceStartOfWW=780 2022-06-01 00:00:43.770 [ 24] 5 SmartScreenClient:notifyOfWakeWord:keyword=ALEXA,connected=true 2022-06-01 00:00:43.770 [ 24] 5 AudioInputProcessor:recognize 2022-06-01 00:00:43.435 [ 2] 5 UserInactivityMonitor:onUserActive::Timer stopped 2022-06-01 00:00:43.773 [ 2] 5 UserInactivityMonitor:onUserActive::Timer started 2022-06-01 00:00:43.774 [ 2] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=LISTENING 2022-06-01 00:00:43.774 [ 2] E AudioInputProcessor:executeRecognizeFailed:reason=New audio provider can not override 2022-06-01 00:00:43.436 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.775 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.775 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.775 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.775 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.775 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.776 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.776 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.776 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.776 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.776 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.777 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=5 2022-06-01 00:00:43.777 [ 5] I ContextManager:updateCapabilityState 2022-06-01 00:00:43.777 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.777 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.778 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.778 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.778 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.779 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.779 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.780 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.780 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.781 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.781 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.781 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=4 2022-06-01 00:00:43.781 [ 5] I ContextManager:updateCapabilityState 2022-06-01 00:00:43.782 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.782 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.782 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.782 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.783 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.783 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.784 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.784 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.784 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.784 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.784 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.785 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=3 2022-06-01 00:00:43.785 [ 5] I ContextManager:updateCapabilityState 2022-06-01 00:00:43.786 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.786 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.787 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:43.787 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.119 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=2 2022-06-01 00:00:44.120 [ 5] I ContextManager:updateCapabilityState 2022-06-01 00:00:44.120 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.121 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.122 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=1 2022-06-01 00:00:44.122 [ 5] I ContextManager:updateCapabilityState 2022-06-01 00:00:44.122 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.123 [ 5] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=3 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:Alexa.Presentation.APL,name:RenderedDocumentState} 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.124 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:VisualActivityTracker,name:ActivityState} 2022-06-01 00:00:44.125 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.125 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.125 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:AudioActivityTracker,name:ActivityState} 2022-06-01 00:00:44.458 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.458 [ 5] 5 ContextManager:getContextAvailableCallbackIfReadyLocked 2022-06-01 00:00:44.460 [ 5] 5 AVSContext:toJson 2022-06-01 00:00:44.460 [ 2] 0 AudioInputProcessor:executeOnContextAvailable 2022-06-01 00:00:44.460 [ 2] 1 FocusManager:acquireChannel:channelName=Dialog,interface=SpeechRecognizer 2022-06-01 00:00:44.460 [ 2] 9 ThreadPool:obtainWorker:created=16,obtained=107,releasedToPool=103,releasedFromPool=0,outstanding=4 2022-06-01 00:00:44.461 [ 2] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=6c700c7a-2308-4cd7-8c51-5a9cdbe5143a,newValue=c890ca2d-4b05-47b0-a187-3c352223a019 2022-06-01 00:00:44.461 [ 2] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=6c700c7a-2308-4cd7-8c51-5a9cdbe5143a 2022-06-01 00:00:44.463 [ 2] I UUIDGeneration:low entropy on call to generate UUID:current entropy=0 2022-06-01 00:00:44.464 [ 2] 0 EventBuilder:buildJsonEventString:messageId=3d166922-f5bf-4897-9250-acc0ed7c347d,namespace=SpeechRecognizer,name=Recognize 2022-06-01 00:00:44.464 [ 2] 0 EventBuilder:buildJsonEventString:event={"context"\:{"properties"\:[{"namespace"\:"Alerts"\,"name"\:"AlertsState"\,"value"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}\,"timeOfSample"\:"2022-06-01T00\:00\:05.275Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"Alexa.Display.Window"\,"name"\:"WindowState"\,"value"\:{"defaultWindowId"\:"smartScreenLandscape"\,"instances"\:[{"id"\:"smartScreenLandscape"\,"templateId"\:"smartScreenLandscape"\,"token"\:null\,"configuration"\:{"interactionMode"\:"smart_screen"\,"sizeConfigurationId"\:"fullscreen"}}]}\,"timeOfSample"\:"2022-06-01T00\:00\:43.434Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"Alexa.PlaybackStateReporter"\,"name"\:"playbackState"\,"value"\:{"state"\:"IDLE"\,"supportedOperations"\:[]\,"shuffle"\:"NOT_SHUFFLED"\,"repeat"\:"NOT_REPEATED"\,"favorite"\:"NOT_RATED"\,"positionMilliseconds"\:0\,"players"\:[]}\,"timeOfSample"\:"2022-06-01T00\:00\:44.122Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"\,"value"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}\,"timeOfSample"\:"2022-06-01T00\:00\:11.688Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"ExternalMediaPlayer"\,"name"\:"ExternalMediaPlayerState"\,"value"\:{"agent"\:""\,"spiVersion"\:"1.0"\,"playerInFocus"\:""\,"players"\:[]}\,"timeOfSample"\:"2022-06-01T00\:00\:43.785Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"Notifications"\,"name"\:"IndicatorState"\,"value"\:{"isEnabled"\:false\,"isVisualIndicatorPersisted"\:false}\,"timeOfSample"\:"2022-06-01T00\:00\:05.663Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"Speaker"\,"name"\:"VolumeState"\,"value"\:{"volume"\:10\,"muted"\:false}\,"timeOfSample"\:"2022-06-01T00\:00\:35.910Z"\,"uncertaintyInMilliseconds"\:0}\,{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"\,"value"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}\,"timeOfSample"\:"2022-06-01T00\:00\:11.685Z"\,"uncertaintyInMilliseconds"\:0}]}\,"event"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"Recognize"\,"messageId"\:"3d166922-f5bf-4897-9250-acc0ed7c347d"\,"dialogRequestId"\:"c890ca2d-4b05-47b0-a187-3c352223a019"}\,"payload"\:{"profile"\:"NEAR_FIELD"\,"initiator"\:{"type"\:"WAKEWORD"\,"payload"\:{"wakeWordIndices"\:{"startIndexInSamples"\:8000\,"endIndexInSamples"\:17280}\,"wakeWord"\:"ALEXA"}}\,"startOfSpeechTimestamp"\:"456739214000"\,"format"\:"AUDIO_L16_RATE_16000_CHANNELS_1"}}} 2022-06-01 00:00:44.465 [ 18] 9 TaskThread:startThread:moniker= 18,duration=4471 2022-06-01 00:00:44.465 [ 18] 5 FocusManager:acquireChannelHelper:incomingChannel=Dialog,incomingInterface=SpeechRecognizer 2022-06-01 00:00:44.465 [ 18] 5 Channel:setPrimaryActivity:Interface=SpeechRecognizer 2022-06-01 00:00:44.465 [ 18] 5 Channel:setFocus:name=Dialog,newfocusState=FOREGROUND,prevfocusState=NONE,newMixingBehavior=PRIMARY,forceUpdate=false 2022-06-01 00:00:44.797 [ 18] 0 Channel:addToChannelUpdatesLocked:interface=SpeechRecognizer,focusState=FOREGROUND 2022-06-01 00:00:44.797 [ 18] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND,MixingBehavior=PRIMARY 2022-06-01 00:00:44.798 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=108,releasedToPool=103,releasedFromPool=0,outstanding=5 2022-06-01 00:00:44.798 [ 18] 1 FocusManager:notifyActivityTracker:name=Dialog,interfaceName=SpeechRecognizer,focusState=FOREGROUND 2022-06-01 00:00:44.798 [ 18] 5 AudioActivityTracker:notifyOfActivityUpdates 2022-06-01 00:00:44.798 [ 18] 9 ThreadPool:obtainWorker:created=16,obtained=109,releasedToPool=103,releasedFromPool=0,outstanding=6 2022-06-01 00:00:44.799 [ 2] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND 2022-06-01 00:00:44.799 [ 2] 0 AudioInputProcessor:sendRequestNow 2022-06-01 00:00:44.799 [ 2] 7 AVSConnectionManager:sendMessage:this=0x25ca00 2022-06-01 00:00:44.799 [ 2] 7 HTTP2Transport:onRequestEnqueued:this=0x24cc80 2022-06-01 00:00:44.799 [ 2] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:44.799 [ e] 9 TaskThread:startThread:moniker= e,duration=1784 2022-06-01 00:00:44.800 [ e] 0 GUIManager:ChannelFocusChanged:channelName=Dialog,newFocus=FOREGROUND 2022-06-01 00:00:44.800 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=1586 2022-06-01 00:00:44.800 [ 1a] 5 AudioActivityTracker:executeNotifyOfActivityUpdates 2022-06-01 00:00:44.800 [ 1f] 7 MessageRequestHandler:create:context=0x24ccd0,messageRequest=0xacc039ec 2022-06-01 00:00:44.800 [ 1f] 5 ExchangeHandler:init:context=0x24ccd0 2022-06-01 00:00:44.800 [ 1f] 7 MessageRequestHandler:init:context=0x24ccd0,messageRequest=0xacc039ec 2022-06-01 00:00:44.801 [ 1f] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie\=,source=0x1a67b4 2022-06-01 00:00:44.801 [ 1f] 9 MimeResponseSink:init:handler=0x1a67b8 2022-06-01 00:00:44.801 [ 1f] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder 2022-06-01 00:00:44.801 [ 1f] 7 HTTP2Transport:onMessageRequestSent:this=0x24cc80,countOfUnfinishedMessageHandlers=1 2022-06-01 00:00:44.801 [ 1f] 7 HTTP2Transport:createAndSendRequest:this=0x24cc80,type=POST 2022-06-01 00:00:44.802 [ 1f] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines 2022-06-01 00:00:44.802 [ 1f] 9 MessageRequestHandler:getRequestHeaderLines 2022-06-01 00:00:44.803 [ 1f] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:44.803 [ 1f] 0 MessageRequestHandler:EventSent 2022-06-01 00:00:44.803 [ 1f] 9 ConditionVariableWrapper:waitForInner:duration=299999748000 2022-06-01 00:00:44.814 [ 1e] 9 LibcurlHTTP2Connection:insertActiveStream:this=0x20d008,handle=0x74390,streamId=AVSEvent-11 2022-06-01 00:00:44.815 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS 2022-06-01 00:00:44.816 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA 2022-06-01 00:00:44.816 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65429 2022-06-01 00:00:44.816 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:44.817 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=63200 2022-06-01 00:00:44.817 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:44.817 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState[ 459.631935@2]s aml_wifi_get_platform_verid(947) version name: gva [ 459.637165@2]s aml_wifi_get_platform_verid(947) version name: gva =SENDING_END_BOUNDARY 2022-06-01 00:00:45.149 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS 2022-06-01 00:00:45.149 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:45.150 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.150 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_CRLF_AFTER_BOUNDARY 2022-06-01 00:00:45.150 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_CRLF_AFTER_BOUNDARY,newState=SENDING_PART_HEADERS 2022-06-01 00:00:45.150 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA 2022-06-01 00:00:45.150 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=63088 2022-06-01 00:00:45.150 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.150 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=63088 2022-06-01 00:00:45.406 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.407 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.407 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.407 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.408 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=52752 2022-06-01 00:00:45.408 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=12784 2022-06-01 00:00:45.408 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.408 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 [ 460.084228@2]s aml_wifi_get_platform_verid(947) version name: gva 2022-06-01 00:00:45.628 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.628 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING[ 460.102995@2]s aml_wifi_get_platform_verid(947) version name: gva _PART_DATA 2022-06-01 00:00:45.628 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.629 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.629 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=7168 2022-06-01 00:00:45.629 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=58368 2022-06-01 00:00:45.629 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.629 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.632 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.632 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.632 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.633 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.633 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.643 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.643 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.643 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.643 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.644 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:45.644 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:45.644 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.644 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.650 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.651 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.651 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.651 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.651 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.662 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.662 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.662 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.662 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.662 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.672 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.672 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.672 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.672 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.672 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:45.672 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:45.672 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.672 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.673 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.673 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.673 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.673 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.674 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:45.684 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:45.684 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:45.684 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:45.684 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:45.684 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=1024 2022-06-01 00:00:45.684 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=64512 2022-06-01 00:00:45.685 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.017 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=10240 2022-06-01 00:00:46.017 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=54272 2022-06-01 00:00:46.017 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.017 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.025 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.026 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.026 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.026 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.026 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.026 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.026 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.027 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.028 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.028 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.028 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.028 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.029 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.039 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.040 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.040 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.040 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.040 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.050 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.051 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.051 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.051 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.051 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.051 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.051 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.051 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.052 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.052 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.052 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.053 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.053 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.063 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.063 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.063 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.063 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.063 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.064 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.064 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.064 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.064 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.065 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.065 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.065 [ 1e] 9 HTTP2Transport:o[ 460.871755@1]s aml_wifi_get_platform_verid(947) version name: gva nActivity:this=0x24cc80 2022-06-01 00:00:46.065 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.407 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.407 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.408 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.408 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.408 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=11264 2022-06-01 00:00:46.408 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=54272 2022-06-01 00:00:46.408 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.408 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.417 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.418 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.418 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.418 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.418 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.429 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.429 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.429 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.429 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.429 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.429 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.429 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.429 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.430 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.430 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.430 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.430 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.430 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.441 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.441 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.441 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.441 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.441 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.451 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.451 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.451 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.451 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.452 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.452 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.452 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.452 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.453 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.453 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.453 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.453 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.453 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.463 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.463 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.463 [ 1e] 9 MessageRequestHandler[ 461.263065@0]s aml_wifi_get_platform_verid(947) version name: gva :onSendMimePartData:size=65536 2022-06-01 00:00:46.463 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.464 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=512 2022-06-01 00:00:46.797 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65024 2022-06-01 00:00:46.797 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.797 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=10240 2022-06-01 00:00:46.798 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=54784 2022-06-01 00:00:46.798 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.798 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.810 [ 1e] 9 Libc[ 461.361244@3]s snd_pcm: enter aml_pcm_trigger [ 461.365407@3]s snd_pcm: aml_pcm_timer_stop [ 461.369454@3]s snd_pcm_dai: aiu master pcm stream 1 disable [ 461.369454@3]s [ 461.376888@1]- snd_pcm: enter aml_pcm_prepare [ 461.381550@1]- snd_pcm_dai: aml_pcm_dai_prepare rate:48000, bits:16, slots:4, mclk:3072000 [ 461.389464@1]- snd_pcm_dai: aml_pcm_dai_prepare capture stream buffer start: 908591104 size: 0x4000 [ 461.399086@1]d snd_pcm: enter aml_pcm_trigger [ 461.402487@1]d snd_pcm: aml_pcm_timer_start [ 461.406627@1]d snd_pcm_dai: aiu pcm master stream 1 enable [ 461.406627@1]d [ 461.413790@1]d audio_hw_pcmpcm master in, pcm mode:1, valid bits:0xf, valid slot:0xf urlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.811 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.811 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.811 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.811 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=1024 2022-06-01 00:00:46.811 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=64512 2022-06-01 00:00:46.811 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.812 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.814 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.814 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.814 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.814 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.814 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.847 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.856 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.862 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.867 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.870 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.889 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.895 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.898 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.901 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.902 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=0,bytesRead=1024 2022-06-01 00:00:46.902 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=64512 2022-06-01 00:00:46.902 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.902 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.919 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:46.919 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:46.919 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:46.919 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.919 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=1,bytesRead=0 2022-06-01 00:00:46.929 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSDownChannel-1,size=1,nmemb=253,userData=0x1c9b88 2022-06-01 00:00:46.929 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=253 2022-06-01 00:00:46.929 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:46.929 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.929 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:46.930 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=199 2022-06-01 00:00:46.930 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.930 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:46.930 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:46.930 [ 1e] 9 ThreadPool:obtainWorker:created=16,obtained=110,releasedToPool=109,releasedFromPool=0,outstanding=1 2022-06-01 00:00:46.930 [ 12] 9 TaskThread:startThread:moniker= 12,duration=174 2022-06-01 00:00:46.930 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=13,userData=0x213e18 2022-06-01 00:00:46.930 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=200 2022-06-01 00:00:46.930 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:46.930 [ 1e] 9 MimeResponseSink:onReceiveResponseCode:responseCode=200 2022-06-01 00:00:47.294 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.295 [ 1e] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=200 2022-06-01 00:00:47.295 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:47.295 [ 1e] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x24cc80 2022-06-01 00:00:47.295 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:47.295 [ 1e] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS 2022-06-01 00:00:47.295 [ 1e] 0 AudioInputProcessor:onResponseStatusReceived:status=SUCCESS 2022-06-01 00:00:47.295 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 200

2022-06-01 00:00:47.296 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 200

2022-06-01 00:00:47.296 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.296 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=56,userData=0x213e18 2022-06-01 00:00:47.296 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 06bdf397-5785-47a5-9a09-55e581e13d73

2022-06-01 00:00:47.296 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 06bdf397-5785-47a5-9a09-55e581e13d73

2022-06-01 00:00:47.296 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.297 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 06bdf397-5785-47a5-9a09-55e581e13d73 2022-06-01 00:00:47.297 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=113,userData=0x213e18 2022-06-01 00:00:47.297 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=content-type\: multipart/related; boundary\=------abcde123; start\=metadata.1653594776965; type\="application/json"

2022-06-01 00:00:47.297 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:boundary=------abcde123 2022-06-01 00:00:47.297 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=content-type\: multipart/related; boundary\=------abcde123; start\=metadata.1653594776965; type\="application/json"

2022-06-01 00:00:47.297 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.297 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=83,userData=0x213e18 2022-06-01 00:00:47.298 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-11

2022-06-01 00:00:47.298 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-11

2022-06-01 00:00:47.298 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.298 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-11 2022-06-01 00:00:47.298 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=32,userData=0x213e18 2022-06-01 00:00:47.298 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:47.299 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:47.299 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.299 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-11,size=1,nmemb=2,userData=0x213e18 2022-06-01 00:00:47.299 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=

2022-06-01 00:00:47.299 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=

2022-06-01 00:00:47.299 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.300 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=652,userData=0x213e18 2022-06-01 00:00:47.300 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=652 2022-06-01 00:00:47.300 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:46.930 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:47.634 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:47.635 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:47.636 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:47.637 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:47.638 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:SpeechRecognizer\,name\:StopCapture\,messageId\:7a3df206-9fcf-491f-9f0d-e93d44e723a9\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:47.638 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:47.639 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.639 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.639 [ b] I DirectiveRouter:preHandleDirective:messageId=7a3df206-9fcf-491f-9f0d-e93d44e723a9,action=calling 2022-06-01 00:00:47.640 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=7a3df206-9fcf-491f-9f0d-e93d44e723a9 2022-06-01 00:00:47.640 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:47.640 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:47.641 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:47.641 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=1 2022-06-01 00:00:47.641 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=7a3df206-9fcf-491f-9f0d-e93d44e723a9,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2022-06-01 00:00:47.641 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.642 [ a] I DirectiveRouter:handleDirective:messageId=7a3df206-9fcf-491f-9f0d-e93d44e723a9,action=calling 2022-06-01 00:00:47.643 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.643 [ a] 9 ThreadPool:obtainWorker:created=16,obtained=111,releasedToPool=109,releasedFromPool=0,outstanding=2 2022-06-01 00:00:47.644 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:47.644 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:47.644 [ 22] 9 TaskThread:startThread:moniker= 22,duration=1220 2022-06-01 00:00:47.644 [ 22] 0 AudioInputProcessor:stopCapture:stopImmediately=true 2022-06-01 00:00:47.645 [ 22] 5 AudioInputProcessor:setState:currentDialogRequestId=c890ca2d-4b05-47b0-a187-3c352223a019 2022-06-01 00:00:47.645 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:47.645 [ 22] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY 2022-06-01 00:00:47.646 [ 22] 0 DialogUXStateAggregator:onStateChanged:AudioInputProcessorState=BUSY 2022-06-01 00:00:47.646 [ 22] 9 ThreadPool:obtainWorker:created=16,obtained=112,releasedToPool=109,releasedFromPool=0,outstanding=3 2022-06-01 00:00:47.646 [ 22] 9 ConnectionRetryTrigger:onStateChanged:state=BUSY 2022-06-01 00:00:47.647 [ 22] 0 DirectiveProcessor:onHandlingCompeted:messageId=7a3df206-9fcf-491f-9f0d-e93d44e723a9,directiveBeingPreHandled=(nullptr) 2022-06-01 00:00:47.647 [ 22] 0 CapabilityAgent:removingMessageIdFromMap:messageId=7a3df206-9fcf-491f-9f0d-e93d44e723a9 2022-06-01 00:00:47.647 [ 22] 5 UserInactivityMonitor:onUserActive 2022-06-01 00:00:47.648 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=1939 2022-06-01 00:00:47.648 [ 1c] 0 DialogUXStateAggregator:onStateChangedLambda:AudioInputProcessorState=BUSY 2022-06-01 00:00:47.649 [ 22] 5 UserInactivityMonitor:onUserActive::Timer stopped 2022-06-01 00:00:47.649 [ 22] 5 UserInactivityMonitor:onUserActive::Timer started 2022-06-01 00:00:47.645 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=286 2022-06-01 00:00:47.650 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.650 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:47.983 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.983 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:47.983 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.983 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:47.983 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=212 2022-06-01 00:00:47.983 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.983 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:47.983 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.984 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=600,userData=0x213e18 2022-06-01 00:00:47.984[ 462.525698@1]s aml_wifi_get_platform_verid(947) version name: gva [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=600 2022-06-01 00:00:47.984 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:47.984 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:47.984 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:47.984 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=531 2022-06-01 00:00:47.985 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:47.986 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:47.986 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:SpeechRecognizer\,name\:SetEndOfSpeechOffset\,messageId\:1ebae19d-2e67-47a6-92a7-a1d139e791f8\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:47.986 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:47.986 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:47.986 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:47.987 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:InteractionModel\,name\:RequestProcessingStarted\,messageId\:b2e1fd65-5551-4319-8286-631527e343d5\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:47.987 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:47.987 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.987 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.987 [ b] I DirectiveRouter:preHandleDirective:messageId=1ebae19d-2e67-47a6-92a7-a1d139e791f8,action=calling 2022-06-01 00:00:47.987 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=1ebae19d-2e67-47a6-92a7-a1d139e791f8 2022-06-01 00:00:47.987 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:47.987 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:47.987 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.987 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:47.987 [ b] I DirectiveRouter:preHandleDirective:messageId=b2e1fd65-5551-4319-8286-631527e343d5,action=calling 2022-06-01 00:00:47.988 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=b2e1fd65-5551-4319-8286-631527e343d5 2022-06-01 00:00:47.988 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:48.325 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=1 2022-06-01 00:00:48.325 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=1ebae19d-2e67-47a6-92a7-a1d139e791f8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2022-06-01 00:00:48.326 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:48.326 [ a] I DirectiveRouter:handleDirective:messageId=1ebae19d-2e67-47a6-92a7-a1d139e791f8,action=calling 2022-06-01 00:00:48.327 [ a] 0 AudioInputProcessor:handleSetEndOfSpeechOffsetDirective:startTimeSpeech(ms)=456739214000,endTimeSpeech(ms)=456739217332 2022-06-01 00:00:48.327 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:48.327 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:47.988 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.327 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:48.327 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.328 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=153,userData=0x213e18 2022-06-01 00:00:48.328 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=153 2022-06-01 00:00:48.328 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:48.328 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.333 [ 1e] 9 MimeResponseSink:attachmentContentDetected:contentId=DeviceTTSRendererV4_c19c3b6a-b046-4ed7-9a1f-4bd39cfbf748_249982373 2022-06-01 00:00:48.333 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=27 2022-06-01 00:00:48.334 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.334 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=31,userData=0x213e18 2022-06-01 00:00:48.327 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=1ebae19d-2e67-47a6-92a7-a1d139e791f8,directiveBeingPreHandled=(nullptr) 2022-06-01 00:00:48.334 [ a] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:48.334 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=1ebae19d-2e67-47a6-92a7-a1d139e791f8 2022-06-01 00:00:48.334 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=b2e1fd65-5551-4319-8286-631527e343d5,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2022-06-01 00:00:48.335 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:48.335 [ a] I DirectiveRouter:handleDirective:messageId=b2e1fd65-5551-4319-8286-631527e343d5,action=calling 2022-06-01 00:00:48.335 [ a] 5 InteractionModel:handleDirective 2022-06-01 00:00:48.336 [ a] 5 InteractionModel:handleDirectiveHelper 2022-06-01 00:00:48.336 [ a] 0 DialogUXStateAggregator:onRequestProcessingStarted 2022-06-01 00:00:48.336 [ 1c] 0 DialogUXStateAggregator:onRequestProcessingStartedLambda:currentState=LISTENING 2022-06-01 00:00:48.336 [ 1c] 0 DialogUXStateAggregator:executeSetState:from=LISTENING,to=THINKING,validTransition=true 2022-06-01 00:00:48.337 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=113,releasedToPool=109,releasedFromPool=0,outstanding=4 2022-06-01 00:00:48.337 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=114,releasedToPool=109,releasedFromPool=0,outstanding=5 2022-06-01 00:00:48.337 [ 1c] 5 TemplateRuntime:onDialogUXStateChanged:state=THINKING 2022-06-01 00:00:48.337 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=115,releasedToPool=109,releasedFromPool=0,outstanding=6 2022-06-01 00:00:48.337 [ 1c] 5 AlexaPresentation:onDialogUXStateChanged:state=THINKING 2022-06-01 00:00:48.337 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=116,releasedToPool=109,releasedFromPool=0,outstanding=7 2022-06-01 00:00:48.338 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=117,releasedToPool=109,releasedFromPool=0,outstanding=8 2022-06-01 00:00:48.338 [ f] 9 TaskThread:startThread:moniker= f,duration=1308 2022-06-01 00:00:48.334 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=31 2022-06-01 00:00:48.673 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=31 2022-06-01 00:00:48.673 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.673 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=993,userData=0x213e18 2022-06-01 00:00:48.674 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=993 2022-06-01 00:00:48.674 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=979 2022-06-01 00:00:48.674 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.674 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1 2022-06-01 00:00:48.674 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.674 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=12 2022-06-01 00:00:48.674 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.675 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=997,userData=0x213e18 2022-06-01 00:00:48.675 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=997 2022-06-01 00:00:48.675 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1 2022-06-01 00:00:48.675 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.675 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=997 2022-06-01 00:00:48.675 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.676 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=25,userData=0x213e18 2022-06-01 00:00:48.676 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=25 2022-06-01 00:00:48.676 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=25 2022-06-01 00:00:48.676 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.677 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=1024,userData=0x213e18 2022-06-01 00:00:48.677 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=1024 2022-06-01 00:00:48.677 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1024 2022-06-01 00:00:48.677 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.678 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=999,userData=0x213e18 2022-06-01 00:00:48.678 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=999 2022-06-01 00:00:48.678 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=999 2022-06-01 00:00:48.678 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.678 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=25,userData=0x213e18 2022-06-01 00:00:48.678 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=25 2022-06-01 00:00:48.678 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=25 2022-06-01 00:00:48.679 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.679 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=1024,userData=0x213e18 2022-06-01 00:00:48.679 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=1024 2022-06-01 00:00:48.679 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1024 2022-06-01 00:00:48.679 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:48.680 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=1024,userData=0x213e18 2022-06-01 00:00:48.680 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=1024 2022-06-01 00:00:48.338 [ 21] 9 TaskThread:startThread:moniker= 21,duration=1171 2022-06-01 00:00:48.338 [ 23] 9 TaskThread:startThread:moniker= 23,duration=947 2022-06-01 00:00:48.338 [ 11] 9 TaskThread:startThread:moniker= 11,duration=734 2022-06-01 00:00:48.338 [ 19] 9 TaskThread:startThread:moniker= 19,duration=624 2022-06-01 00:00:48.682 [ 19] 9 ThreadPool:obtainWorker:created=16,obtained=118,releasedToPool=109,releasedFromPool=0,outstanding=9 #####################################

ALEXA STATE: THINKING

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

2022-06-01 00:00:48.338 [ 22] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=THINKING 2022-06-01 00:00:48.338 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=b2e1fd65-5551-4319-8286-631527e343d5,directiveBeingPreHandled=(nullptr) 2022-06-01 00:00:49.014 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=b2e1fd65-5551-4319-8286-631527e343d5 2022-06-01 00:00:49.014 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:49.015 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:48.334 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:49.015 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:49.015 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:49.015 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:49.015 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:49.015 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:49.016 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:SpeechSynthesizer\,name\:Speak\,messageId\:541ce44d-73e0-4ea0-b0c6-80a2b000f286\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:49.016 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:49.016 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:49.017 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:49.017 [ b] I DirectiveRouter:preHandleDirective:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286,action=calling 2022-06-01 00:00:49.017 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:49.018 [ b] 9 SpeechSynthesizer:preHandleDirective:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:49.018 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:49.018 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:49.019 [ 21] 0 SpeechSynthesizer:executePreHandle:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:49.023 [ 21] 5 SpeechSynthesizer:captionsNotParsed:reason=captions disabled 2022-06-01 00:00:49.024 [ 21] 5 SpeechSynthesizer:addToDirectiveQueue:queueSize=0,playBehavior=ENQUEUE 2022-06-01 00:00:49.025 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:49.025 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=1 2022-06-01 00:00:49.026 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=541ce44d-73e0-4ea0-b0c6-80a2b000f286,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:True 2022-06-01 00:00:49.026 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:49.026 [ a] I DirectiveRouter:handleDirective:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286,action=calling 2022-06-01 00:00:49.026 [ a] 9 SpeechSynthesizer:handleDirective:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:49.027 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:49.027 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:48.681 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1024 2022-06-01 00:00:49.028 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.029 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=1024,userData=0x213e18 2022-06-01 00:00:49.029 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=1024 2022-06-01 00:00:49.029 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1024 2022-06-01 00:00:49.029 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.030 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=999,userData=0x213e18 2022-06-01 00:00:49.030 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=999 2022-06-01 00:00:49.030 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=996 2022-06-01 00:00:49.365 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.365 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1 2022-06-01 00:00:49.365 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.365 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=2 2022-06-01 00:00:49.365 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.365 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=27,userData=0x213e18 2022-06-01 00:00:49.365 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=27 2022-06-01 00:00:49.366 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=27 2022-06-01 00:00:49.366 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.366 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=1024,userData=0x213e18 2022-06-01 00:00:49.366 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=1024 2022-06-01 00:00:49.366 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=1024 2022-06-01 00:00:49.367 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.367 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=612,userData=0x213e18 2022-06-01 00:00:49.367 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=612 2022-06-01 00:00:49.367 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=594 2022-06-01 00:00:49.367 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.367 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:49.368 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.368 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=294,userData=0x213e18 2022-06-01 00:00:49.368 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=294 2022-06-01 00:00:49.368 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:49.368 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.368 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:49.368 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=225 2022-06-01 00:00:49.369 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.369 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:49.369 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.369 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:49.369 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:49.369 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:49.369 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:49.370 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:49.370 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:49.370 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:AudioPlayer\,name\:ClearQueue\,messageId\:a4e1f64f-c806-4667-bb9a-6c6ef432bccf\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:49.370 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:49.032 [ 21] 0 SpeechSynthesizer:executeHandle:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:49.371 [ 21] 1 FocusManager:acquireChannel:channelName=Dialog,interface=SpeechSynthesizer 2022-06-01 00:00:49.371 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=119,releasedToPool=110,releasedFromPool=0,outstanding=9 2022-06-01 00:00:49.372 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:49.372 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:49.372 [ b] I DirectiveRouter:preHandleDirective:messageId=a4e1f64f-c806-4667-bb9a-6c6ef432bccf,action=calling 2022-06-01 00:00:49.372 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=a4e1f64f-c806-4667-bb9a-6c6ef432bccf 2022-06-01 00:00:49.706 [ b] 5 AudioPlayer:preHandleDirective:name=ClearQueue,messageId=a4e1f64f-c806-4667-bb9a-6c6ef432bccf 2022-06-01 00:00:49.708 [ b] 1 AudioPlayer:handleClearQueue 2022-06-01 00:00:49.709 [ b] 0 DirectiveProcessor:onHandlingCompeted:messageId=a4e1f64f-c806-4667-bb9a-6c6ef432bccf,directiveBeingPreHandled=a4e1f64f-c806-4667-bb9a-6c6ef432bccf 2022-06-01 00:00:49.709 [ b] 0 CapabilityAgent:removingMessageIdFromMap:messageId=a4e1f64f-c806-4667-bb9a-6c6ef432bccf 2022-06-01 00:00:49.709 [ b] 9 ThreadPool:obtainWorker:created=16,obtained=120,releasedToPool=114,releasedFromPool=0,outstanding=6 2022-06-01 00:00:49.372 [ 3] 9 TaskThread:startThread:moniker= 3,duration=981 2022-06-01 00:00:49.710 [ 3] 5 FocusManager:acquireChannelHelper:incomingChannel=Dialog,incomingInterface=SpeechSynthesizer 2022-06-01 00:00:49.710 [ 3] 5 Channel:setPrimaryActivity:Interface=SpeechSynthesizer 2022-06-01 00:00:49.710 [ 3] 5 Channel:removeActivityHelperLocked:interface=SpeechRecognizer 2022-06-01 00:00:49.710 [ 3] 0 Channel:addToChannelUpdatesLocked:interface=SpeechRecognizer,focusState=NONE 2022-06-01 00:00:49.710 [ 3] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE,MixingBehavior=MUST_STOP 2022-06-01 00:00:49.710 [ 3] 5 Channel:setFocus:name=Dialog,newfocusState=FOREGROUND,prevfocusState=FOREGROUND,newMixingBehavior=PRIMARY,forceUpdate=true 2022-06-01 00:00:49.710 [ 3] 0 Channel:addToChannelUpdatesLocked:interface=SpeechSynthesizer,focusState=FOREGROUND 2022-06-01 00:00:49.711 [ 3] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND,MixingBehavior=PRIMARY 2022-06-01 00:00:49.711 [ 3] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS 2022-06-01 00:00:49.712 [ 3] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=0,currentId=0 2022-06-01 00:00:49.713 [ 3] 0 DialogUXStateAggregator:onStateChanged:SpeechSynthesizerState=GAINING_FOCUS 2022-06-01 00:00:49.713 [ 3] 9 ThreadPool:obtainWorker:created=16,obtained=121,releasedToPool=114,releasedFromPool=0,outstanding=7 2022-06-01 00:00:48.683 [ 25] 9 TaskThread:startThread:moniker= 25,duration=777 2022-06-01 00:00:49.714 [ 25] 9 WebSocketServer:writeMessageBegin 2022-06-01 00:00:49.714 [ 25] 9 WebSocket:AccessLog 2022-06-01 00:00:49.714 [ 25] 9 WebSocket:AccessLog 2022-06-01 00:00:49.714 [ 5] 9 TaskThread:startThread:moniker= 5,duration=4933 2022-06-01 00:00:49.714 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:49.714 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:49.715 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:49.715 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:49.715 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:49.715 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:49.714 [ 25] 9 WebSocketServer:writeMessageComplete 2022-06-01 00:00:49.373 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=934,userData=0x213e18 2022-06-01 00:00:49.716 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=934 2022-06-01 00:00:49.716 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:49.716 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.716 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:49.716 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=865 2022-06-01 00:00:49.716 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.716 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:49.717 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:49.718 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSDownChannel-1,size=1,nmemb=2488,userData=0x1c9b88 2022-06-01 00:00:49.718 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=2488 2022-06-01 00:00:49.719 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:49.719 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:49.719 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:49.719 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:50.054 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:50.054 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:50.055 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:AudioPlayer\,name\:Play\,messageId\:2eec7a0e-298b-455c-bcb2-9860336df369\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:50.055 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:49.714 [ 22] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE 2022-06-01 00:00:50.056 [ 22] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus 2022-06-01 00:00:50.056 [ 22] 0 AudioInputProcessor:executeResetState 2022-06-01 00:00:50.056 [ 22] 5 AudioInputProcessor:setState:currentDialogRequestId=c890ca2d-4b05-47b0-a187-3c352223a019 2022-06-01 00:00:50.057 [ 22] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE 2022-06-01 00:00:50.057 [ 22] 0 DialogUXStateAggregator:onStateChanged:AudioInputProcessorState=IDLE 2022-06-01 00:00:50.057 [ 22] 9 ConnectionRetryTrigger:onStateChanged:state=IDLE 2022-06-01 00:00:50.058 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:50.058 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:50.059 [ b] I DirectiveRouter:preHandleDirective:messageId=2eec7a0e-298b-455c-bcb2-9860336df369,action=calling 2022-06-01 00:00:50.059 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=2eec7a0e-298b-455c-bcb2-9860336df369 2022-06-01 00:00:50.059 [ b] 5 AudioPlayer:preHandleDirective:name=Play,messageId=2eec7a0e-298b-455c-bcb2-9860336df369 2022-06-01 00:00:50.060 [ b] 1 AudioPlayer:preHandlePlayDirective 2022-06-01 00:00:50.061 [ b] 9 AudioPlayer:prePLAY:payload={"audioItem"\:{"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e\:1"\,"normalizationLevel"\:"OFF"\,"stream"\:{"offsetInMilliseconds"\:0\,"expiryTime"\:"2022-06-01T06\:03\:14+0000"\,"interruptedBehavior"\:"ATTENUATE"\,"httpHeaders"\:{}\,"url"\:"https\://custom-hls.iheart.com/ihr-ingestion-pipeline-production-sbmg/encodes/Feb21/020221/A10301A0004545099C_20210202172913661/64064438.20122.m4a.m3u8?null"\,"token"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e\:1"}}\,"presentationSession"\:{"appId"\:"Domain\:Application\:Music"\,"presentationSessionId"\:"adfcb0d3-2217-4414-b72a-34861153e42e"}\,"playBehavior"\:"REPLACE_ALL"} 2022-06-01 00:00:50.062 [ b] I AudioPlayer:preHandlePlayDirective:normalizationMode=OFF 2022-06-01 00:00:50.064 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=streamFormat 2022-06-01 00:00:50.065 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=endOffsetInMilliseconds 2022-06-01 00:00:50.066 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=progressReport 2022-06-01 00:00:50.067 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=expectedPreviousToken 2022-06-01 00:00:50.067 [ b] 0 JsonUtils:retrieveStringMapFromArrayFailed:reason=couldNotFindObject,key=key 2022-06-01 00:00:50.067 [ b] 0 JsonUtils:retrieveStringMapFromArrayFailed:reason=couldNotFindObject,key=manifest 2022-06-01 00:00:50.068 [ b] 0 JsonUtils:retrieveStringMapFromArrayFailed:reason=couldNotFindObject,key=audioSegment 2022-06-01 00:00:50.068 [ b] 0 JsonUtils:retrieveStringMapFromArrayFailed:reason=couldNotFindObject,key=all 2022-06-01 00:00:50.070 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=caption 2022-06-01 00:00:50.071 [ b] 3 AudioPlayer:captionsNotParsed:reason=keyNotFoundInPayload 2022-06-01 00:00:50.071 [ b] 5 JsonUtils:findNode:reason=missingDirectChild,child=playRequestor 2022-06-01 00:00:49.715 [ 5] 1 AudioPlayer:executeClearQueue:clearBehavior=CLEAR_ENQUEUED 2022-06-01 00:00:50.075 [ 5] I UUIDGeneration:low entropy on call to generate UUID:current entropy=0 2022-06-01 00:00:50.077 [ 5] 7 AVSConnectionManager:sendMessage:this=0x25ca00 2022-06-01 00:00:50.077 [ 5] 7 HTTP2Transport:onRequestEnqueued:this=0x24cc80 2022-06-01 00:00:50.411 [ 5] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:50.412 [ 5] 1 AudioPlayer:executePrePlay:messageId=2eec7a0e-298b-455c-bcb2-9860336df369,preBufferBehavior=REPLACE_ALL 2022-06-01 00:00:50.412 [ 5] 0 AudioPlayer:executePrePlay::acquiringPlayerSetSource 2022-06-01 00:00:50.412 [ 5] 9 PooledMediaPlayerFactory:acquireMediaPlayer 2022-06-01 00:00:50.413 [ 5] 9 MediaPlayer:addObserverCalled:name=AudioMediaPlayer 2022-06-01 00:00:49.720 [ 21] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING 2022-06-01 00:00:50.414 [ 21] 9 SpeechSynthesizer:startPlaying 2022-06-01 00:00:50.415 [ 21] 9 MediaPlayer:setSourceCalled:name=SpeakMediaPlayer,sourceType=AttachmentReader 2022-06-01 00:00:50.416 [ 1f] 7 MessageRequestHandler:create:context=0x24ccd0,messageRequest=0xaea0cf74 2022-06-01 00:00:50.416 [ 1f] 5 ExchangeHandler:init:context=0x24ccd0 2022-06-01 00:00:50.416 [ 1f] 7 MessageRequestHandler:init:context=0x24ccd0,messageRequest=0xaea0cf74 2022-06-01 00:00:50.416 [ 1f] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie\=,source=0x7a51c 2022-06-01 00:00:50.417 [ 1f] 9 MimeResponseSink:init:handler=0x7a520 2022-06-01 00:00:50.417 [ 1f] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder 2022-06-01 00:00:50.417 [ 1f] 7 HTTP2Transport:onMessageRequestSent:this=0x24cc80,countOfUnfinishedMessageHandlers=2 2022-06-01 00:00:50.417 [ 1f] 7 HTTP2Transport:createAndSendRequest:this=0x24cc80,type=POST 2022-06-01 00:00:50.418 [ 1f] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines 2022-06-01 00:00:50.418 [ 1f] 9 MessageRequestHandler:getRequestHeaderLines 2022-06-01 00:00:50.418 [ 1f] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.419 [ 1f] 0 MessageRequestHandler:EventSent 2022-06-01 00:00:50.419 [ 1f] 9 ConditionVariableWrapper:waitForInner:duration=299999773000 2022-06-01 00:00:50.419 [ 8] 0 MediaPlayer:handleAddObserverCalled:name=AudioMediaPlayer 2022-06-01 00:00:49.720 [ 18] 9 TaskThread:startThread:moniker= 18,duration=6548 2022-06-01 00:00:50.420 [ 18] 0 DialogUXStateAggregator:onStateChangedLambda:SpeechSynthesizerState=GAINING_FOCUS 2022-06-01 00:00:50.420 [ 14] 0 MediaPlayer:handleSetAttachmentReaderSourceCalled:name=SpeakMediaPlayer 2022-06-01 00:00:50.421 [ 14] 9 MediaPlayer:tearDownTransientPipelineElements:name=SpeakMediaPlayer 2022-06-01 00:00:49.719 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:50.079 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:50.422 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:50.422 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.423 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:50.423 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=2434 2022-06-01 00:00:50.423 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.424 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:50.424 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.424 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:50.424 [ 14] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=SpeakMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:50.424 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=283,userData=0x213e18 2022-06-01 00:00:50.425 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=283 2022-06-01 00:00:50.425 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:00:50.425 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.425 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:00:50.425 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=214 2022-06-01 00:00:50.425 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.425 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:00:50.425 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.426 [ 1e] 9 LibcurlHTTP2Connection:insertActiveStream:this=0x20d008,handle=0x75300,streamId=AVSEvent-13 2022-06-01 00:00:50.783 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=SENDING_PART_DATA 2022-06-01 00:00:50.784 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65536 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.784 [ 1e] 0 DefaultAttachmentReader:readFailed:reason=SDS is closed 2022-06-01 00:00:50.784 [ 1e] 9 MessageRequestHandler:attachmentRead:readStatus=4,bytesRead=0 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS 2022-06-01 00:00:50.784 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES 2022-06-01 00:00:50.784 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE 2022-06-01 00:00:50.423 [ 18] 0 DialogUXStateAggregator:onStateChangedLambda:AudioInputProcessorState=IDLE 2022-06-01 00:00:50.785 [ 18] 5 DialogUXStateAggregator:executeTryEnterIdleState 2022-06-01 00:00:50.423 [ 5] 9 AudioPlayer:configureMediaPlayer:offset=0 2022-06-01 00:00:50.475 [ 14] 9 BaseStreamSource:initNoAudioFormat 2022-06-01 00:00:50.427 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:50.786 [ 5] 9 MediaPlayer:setSourceForUrlCalled:name=AudioMediaPlayer 2022-06-01 00:00:50.787 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-13,size=1,nmemb=65536,userData=0x1f5d60 2022-06-01 00:00:50.787 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:50.788 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:50.788 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:50.789 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:50.789 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:50.789 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:TemplateRuntime\,name\:RenderPlayerInfo\,messageId\:1c49ac9c-1e65-4693-b558-7b1f20c80fb8\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:50.790 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:50.790 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:00:50.790 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:50.788 [ 8] 0 MediaPlayer:handleSetSourceForUrlCalled:name=AudioMediaPlayer 2022-06-01 00:00:50.791 [ 8] 9 MediaPlayer:tearDownTransientPipelineElements:name=AudioMediaPlayer 2022-06-01 00:00:50.791 [ 8] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=AudioMediaPlayer 2022-06-01 00:00:50.791 [ 8] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=AudioMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:50.793 [ 8] 9 PowerMonitor:getInstance 2022-06-01 00:00:50.793 [ 8] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:44 2022-06-01 00:00:50.793 [ 8] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2022-06-01 00:00:50.794 [ 8] 9 PowerMonitor:getInstance 2022-06-01 00:00:50.794 [ 8] 9 PowerMonitor:createLocalPowerResource:identifier=Executor\:45 2022-06-01 00:00:50.794 [ 8] 9 PowerMonitor:isActiveLocked:isActiveLocked=false 2022-06-01 00:00:50.790 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:00:50.788 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW 2022-06-01 00:00:51.131 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS 2022-06-01 00:00:51.131 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:51.131 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:51.131 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY 2022-06-01 00:00:51.132 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS 2022-06-01 00:00:51.132 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA 2022-06-01 00:00:51.132 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65429 2022-06-01 00:00:51.132 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:51.132 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65224 2022-06-01 00:00:51.132 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:51.132 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY 2022-06-01 00:00:51.133 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS 2022-06-01 00:00:51.133 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:51.133 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:51.133 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES 2022-06-01 00:00:51.133 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE 2022-06-01 00:00:51.134 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-11,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:00:51.134 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE 2022-06-01 00:00:51.135 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-13,size=1,nmemb=65536,userData=0x1f5d60 2022-06-01 00:00:51.136 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE 2022-06-01 00:00:50.790 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:51.137 [ b] I DirectiveRouter:preHandleDirective:messageId=1c49ac9c-1e65-4693-b558-7b1f20c80fb8,action=calling 2022-06-01 00:00:51.138 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=1c49ac9c-1e65-4693-b558-7b1f20c80fb8 2022-06-01 00:00:51.138 [ b] 5 TemplateRuntime:preHandleDirective 2022-06-01 00:00:50.801 [ 8] 9 ThreadPool:obtainWorker:created=16,obtained=122,releasedToPool=115,releasedFromPool=0,outstanding=7 2022-06-01 00:00:50.839 [ 14] 5 MediaPlayer:configureSource:fadeIn=fadeIn{ enabled:0, start:100, end:100, duration(ms):0}, normalization{ enabled: 0}, endOffset(ms): 0, MediaDescription: BEHAVIOR_PAUSE, Channel:, , TrackId:, AdditionalData:{}, enabled: false } 2022-06-01 00:00:50.986 [ 27] 5 DialogUXStateAggregator:tryEnterIdleStateOnTimer 2022-06-01 00:00:50.798 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:00:51.140 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:00:51.143 [ 12] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:00:51.143 [ 12] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:00:51.144 [ 12] I DirectiveSequencer:onDirective:directive=namespace\:InteractionModel\,name\:RequestProcessingCompleted\,messageId\:8ff5fbd6-cab6-42b9-ad41-512b8d2e217f\,dialogRequestId\:c890ca2d-4b05-47b0-a187-3c352223a019\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:00:51.145 [ 12] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:51.143 [ 8] 0 MediaPlayer:handleSetAttachmentReaderSourceCalled:name=AudioMediaPlayer 2022-06-01 00:00:51.146 [ 8] 9 MediaPlayer:tearDownTransientPipelineElements:name=AudioMediaPlayer 2022-06-01 00:00:51.146 [ 8] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=AudioMediaPlayer 2022-06-01 00:00:51.146 [ 8] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=AudioMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:51.478 [ 8] 9 BaseStreamSource:initNoAudioFormat 2022-06-01 00:00:51.481 [ 8] 5 MediaPlayer:configureSource:fadeIn=fadeIn{ enabled:0, start:100, end:100, duration(ms):0}, normalization{ enabled: 0}, endOffset(ms): 0, MediaDescription: BEHAVIOR_DUCK, Channel:Content, , TrackId:amzn1.as[ 465.999475@0]- Mark lock mapping:ecca9ba4, f:40 [ 466.003963@0]- aml_meson_snd_card aml_sound_meson: [ 466.003963@0]- area=c5472268,addr=907018240,bytes=16384, rate:48000, channels:2, subformat:0 [ 466.017434@0]- snd_card_meson: audio speaker on -ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e:1, CaptionData:{forma[ 466.031445@3]- Mark lock mapping:ec1248e4, f:40 [ 466.035577@3]- Mark lock mapping:ec1242a4, f:40 [ 466.038683@3]- aml_meson_snd_card aml_sound_meson: [ 466.038683@3]- area=c5472268,addr=907018240,bytes=16384, rate:48000, channels:2, subformat:0 [ 466.051802@3]- Mark lock mapping:ec124c04, f:40 t:UNKNOWN, content:}, Analyzers:{}, AdditionalData:{{playBehavior:REPLACE_ALL}}, enabled: true } 2022-06-01 00:00:51.141 [ 18] 5 DialogUXStateAggregator:tryEnterIdleStateOnTimerExecutor:m_currentState=THINKING,m_audioInputProcessorState=IDLE,m_speechSynthesizerState=GAINING_FOCUS 2022-06-01 00:00:51.144 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:51.483 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:51.483 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:51.483 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:51.483 [ b] I DirectiveRouter:preHandleDirective:messageId=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f,action=calling 2022-06-01 00:00:51.484 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f 2022-06-01 00:00:51.484 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:51.484 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:51.147 [ 21] 0 MediaPlayer:callingOnBufferingComplete:name=SpeakMediaPlayer,currentId=1 2022-06-01 00:00:51.484 [ 21] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:51.484 [ 21] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:51.484 [ 21] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=SpeakMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:51.485 [ 21] 9 MediaPlayer:playCalled:name=SpeakMediaPlayer 2022-06-01 00:00:51.485 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:51.485 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=3 2022-06-01 00:00:51.486 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=1c49ac9c-1e65-4693-b558-7b1f20c80fb8,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False 2022-06-01 00:00:51.486 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:51.486 [ a] I DirectiveRouter:handleDirective:messageId=1c49ac9c-1e65-4693-b558-7b1f20c80fb8,action=calling 2022-06-01 00:00:51.486 [ a] 5 TemplateRuntime:handleDirective 2022-06-01 00:00:51.487 [ a] 5 TemplateRuntime:handleRenderPlayerInfoDirective 2022-06-01 00:00:51.487 [ a] 9 ThreadPool:obtainWorker:created=16,obtained=123,releasedToPool=116,releasedFromPool=0,outstanding=7 2022-06-01 00:00:51.487 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2022-06-01 00:00:51.488 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:51.488 [ 14] 0 MediaPlayer:handlePlayCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:51.488 [ e] 9 TaskThread:startThread:moniker= e,duration=903 2022-06-01 00:00:51.488 [ e] 5 TemplateRuntime:handleRenderPlayerInfoDirectiveInExecutor 2022-06-01 00:00:51.489 [ e] 3 TemplateRuntime:handleRenderPlayerInfoDirectiveInExecutor:audioItemId=amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e\:1:Not matching audioItemId in execution. 2022-06-01 00:00:51.490 [ e] 0 DirectiveProcesso[ 466.338272@1]s aml_wifi_get_platform_verid(947) version name: gva onHandlingCompeted:messageId=1c49ac9c-1e65-4693-b558-7b1f20c80fb8,directiveBeingPreHandled=(nullptr) 2022-06-01 00:00:51.490 [ e] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:51.872 [ e] 0 CapabilityAgent:removingMessageIdFromMap:messageId=1c49ac9c-1e65-4693-b558-7b1f20c80fb8 2022-06-01 00:00:51.143 [ 2] 9 TaskThread:startThread:moniker= 2,duration=341517 2022-06-01 00:00:51.876 [ 2] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher 2022-06-01 00:00:51.488 [ a] I DirectiveRouter:handleDirective:messageId=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f,action=calling 2022-06-01 00:00:51.173 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSEvent-11,size=1,nmemb=4,userData=0x213e18 2022-06-01 00:00:51.876 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=4 2022-06-01 00:00:51.877 [ 2] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition 2022-06-01 00:00:51.877 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-13,size=1,nmemb=13,userData=0x1f5d60 2022-06-01 00:00:51.877 [ 28] 9 LibCurlHttpContentFetcher:transferThread::start 2022-06-01 00:00:51.878 [ a] 5 InteractionModel:handleDirective 2022-06-01 00:00:51.878 [ a] 5 InteractionModel:handleDirectiveHelper 2022-06-01 00:00:51.878 [ a] 0 DialogUXStateAggregator:onRequestProcessingCompleted 2022-06-01 00:00:51.878 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f,directiveBeingPreHandled=(nullptr) 2022-06-01 00:00:51.878 [ a] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:51.878 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:51.878 [ 18] 0 DialogUXStateAggregator:executeTryExitThinkingState 2022-06-01 00:00:51.877 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:51.879 [ 1e] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:51.879 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:51.879 [ 1e] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:51.879 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:51.879 [ 1e] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x24cc80 2022-06-01 00:00:51.878 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=8ff5fbd6-cab6-42b9-ad41-512b8d2e217f 2022-06-01 00:00:51.482 [ 5] 5 AudioPlayer:configureMediaPlayer:sourceId=2,audioItemId=amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e\:1 2022-06-01 00:00:51.881 [ 5] I AudioPlayer:executePrePlay:enqueueing token=amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#adfcb0d3-2217-4414-b72a-34861153e42e\:1,expectedPreviousToken=,sourceId=2,messageId=2eec7a0e-298b-455c-bcb2-9860336df369 2022-06-01 00:00:51.881 [ 5] 0 AudioPlayer:changeState:fromState=IDLE,toState=BUFFERING 2022-06-01 00:00:51.882 [ 5] 0 AudioPlayer:executeProvideState:sendToken=false,stateRequestToken=0 2022-06-01 00:00:51.882 [ 5] 5 ContextManager:setState 2022-06-01 00:00:51.882 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=124,releasedToPool=116,releasedFromPool=0,outstanding=8 2022-06-01 00:00:51.883 [ 5] 1 AudioPlayer:notifyObserver:playerState=BUFFERING,playerActivity=BUFFER_UNDERRUN,trackProtectionScheme=CLEAR 2022-06-01 00:00:51.883 [ 5] 9 ThreadPool:obtainWorker:created=16,obtained=125,releasedToPool=116,releasedFromPool=0,outstanding=9 2022-06-01 00:00:51.883 [ 5] 5 TemplateRuntime:onRenderPlayerCardsInfoChanged 2022-06-01 00:00:51.884 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=1568 2022-06-01 00:00:51.884 [ 1a] I ContextManager:updateCapabilityState 2022-06-01 00:00:51.884 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:51.884 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:51.884 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:51.884 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:51.885 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:52.217 [ 1a] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:51.885 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=1608 2022-06-01 00:00:51.885 [ 17] E AplClientBridge:invokeExtensionEventHandlerFailed::Root context is missing 2022-06-01 00:00:51.885 [ e] 5 TemplateRuntime:onRenderPlayerCardsInfoChangedInExecutor 2022-06-01 00:00:52.220 [ e] 5 TemplateRuntime:executeAudioPlayerInfoUpdates:audioItemId=,offset=0,audioPlayerState=BUFFER_UNDERRUN,isNonPlayerInfoCardDisplayed=false 2022-06-01 00:00:51.885 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked::all queued directives are blocked 2022-06-01 00:00:52.221 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:52.221 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=1 2022-06-01 00:00:52.221 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked::all queued directives are blocked 2022-06-01 00:00:52.221 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:51.885 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:52.222 [ 1e] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS_NO_CONTENT 2022-06-01 00:00:52.222 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:00:52.222 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:00:52.222 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:52.222 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-13,size=1,nmemb=56,userData=0x1f5d60 2022-06-01 00:00:52.222 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 2d50bff7-0d5d-4f45-9e1c-caab0595761e

2022-06-01 00:00:52.223 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 2d50bff7-0d5d-4f45-9e1c-caab0595761e

2022-06-01 00:00:52.223 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:52.223 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 2d50bff7-0d5d-4f45-9e1c-caab0595761e 2022-06-01 00:00:52.223 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-13,size=1,nmemb=83,userData=0x1f5d60 2022-06-01 00:00:52.223 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-13

2022-06-01 00:00:52.223 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-13

2022-06-01 00:00:52.224 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:52.224 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-13 2022-06-01 00:00:52.224 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-13,size=1,nmemb=32,userData=0x1f5d60 2022-06-01 00:00:52.224 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:52.224 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:52.224 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:52.224 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-13,size=1,nmemb=2,userData=0x1f5d60 2022-06-01 00:00:52.225 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=

2022-06-01 00:00:52.225 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=

2022-06-01 00:00:52.225 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:52.225 [ 1e] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE 2022-06-01 00:00:52.558 [ 1e] 9 MimeResponseSink:onResponseFinished:status=COMPLETE 2022-06-01 00:00:52.558 [ 1e] 7 MessageRequestHandler:onR[ 467.102922@1]s snd_pcm: enter aml_pcm_trigger [ 467.103935@1]s snd_pcm: aml_pcm_timer_stop [ 467.107661@1]s snd_pcm_dai: aiu master pcm stream 1 disable [ 467.107661@1]s [ 467.114939@2]- snd_pcm: enter aml_pcm_prepare [ 467.119086@2]- snd_pcm_dai: aml_pcm_dai_prepare rate:48000, bits:16, slots:4, mclk:3072000 [ 467.127360@2]- snd_pcm_dai: aml_pcm_dai_prepare capture stream buffer start: 908591104 size: 0x4000 [ 467.136492@2]d snd_pcm: enter aml_pcm_trigger [ 467.140500@2]d snd_pcm: aml_pcm_timer_start [ 467.144640@2]d snd_pcm_dai: aiu pcm master stream 1 enable [ 467.144640@2]d [ 467.151805@2]d audio_hw_pcmpcm master in, pcm mode:1, valid bits:0xf, valid slot:0xf sponseFinished:status=COMPLETE,responseCode=200 2022-06-01 00:00:52.559 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:52.559 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:52.559 [ 1e] 7 HTTP2Transport:onMessageRequestFinished:this=0x24cc80,countOfUnfinishedMessageHandlers=1 2022-06-01 00:00:52.559 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:52.559 [ 1e] 7 LibcurlHTTP2Connection:streamFinished:this=0x20d008,streamId=AVSEvent-11,result=No error,CURLcode=0 2022-06-01 00:00:52.559 [ 1e] 9 LibcurlHTTP2Connection:releaseStream:this=0x20d008,streamId=AVSEvent-11 2022-06-01 00:00:52.560 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:52.560 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:52.560 [ 1e] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE 2022-06-01 00:00:52.560 [ 1e] 9 MimeResponseSink:onResponseFinished:status=COMPLETE 2022-06-01 00:00:52.560 [ 1e] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204 2022-06-01 00:00:52.560 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:52.560 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:52.560 [ 1e] 7 HTTP2Transport:onMessageRequestFinished:this=0x24cc80,countOfUnfinishedMessageHandlers=0 2022-06-01 00:00:52.560 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:52.561 [ 1e] 9 AudioPlayer:onSendCompleted:status=SUCCESS_NO_CONTENT 2022-06-01 00:00:52.561 [ 1e] 7 LibcurlHTTP2Connection:streamFinished:this=0x20d008,streamId=AVSEvent-13,result=No error,CURLcode=0 2022-06-01 00:00:52.561 [ 1e] 9 LibcurlHTTP2Connection:releaseStream:this=0x20d008,streamId=AVSEvent-13 2022-06-01 00:00:52.562 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:52.562 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:51.900 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:51.600 [ 14] 9 AttachmentReaderSource:handleSeekData:offset=0 2022-06-01 00:00:52.565 [ 14] 0 MediaPlayer:handlePlay:name=SpeakMediaPlayer,startingState=PAUSED,stateReturn=ASYNC 2022-06-01 00:00:52.566 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:52.566 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink 2022-06-01 00:00:52.566 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=fadeIn 2022-06-01 00:00:52.567 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=volume 2022-06-01 00:00:52.567 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=converter 2022-06-01 00:00:52.567 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decodedQueue 2022-06-01 00:00:52.567 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=typefind 2022-06-01 00:00:52.568 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decoder 2022-06-01 00:00:52.568 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=src 2022-06-01 00:00:52.568 [ 29] 9 BaseStreamSource:onNeedDataCalled:size=4096 2022-06-01 00:00:52.629 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio-pipeline 2022-06-01 00:00:52.635 [ 14] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PAUSED 2022-06-01 00:00:52.645 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=fadeIn 2022-06-01 00:00:52.650 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=volume 2022-06-01 00:00:52.652 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=converter 2022-06-01 00:00:52.652 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-status,source=src 2022-06-01 00:00:53.033 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decodedQueue 2022-06-01 00:00:53.034 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=typefind 2022-06-01 00:00:53.034 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-status,source=src 2022-06-01 00:00:53.034 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-status,source=src 2022-06-01 00:00:53.034 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=src 2022-06-01 00:00:53.035 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-status,source=src 2022-06-01 00:00:53.035 [ 14] 9 BaseStreamSource:handleNeedDataCalled 2022-06-01 00:00:53.036 [ 14] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=8 2022-06-01 00:00:53.036 [ 14] 9 AttachmentReaderSource:beforeRead:size=4096 2022-06-01 00:00:53.037 [ 14] 9 AttachmentReaderSource:read:size=4096,status=0 2022-06-01 00:00:53.037 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:53.037 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:53.037 [ 14] W MediaPlayer:getCurrentStreamOffsetError:name=SpeakMediaPlayer,reason=getElementGetStateUnsuccessful,stateChangeReturn=ASYNC 2022-06-01 00:00:53.038 [ 14] 0 SpeechSynthesizer:onFirstByteRead:id=1 2022-06-01 00:00:52.664 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.053 [ 14] 9 AttachmentReaderSource:beforeRead:size=4096 2022-06-01 00:00:53.053 [ 14] 9 AttachmentReaderSource:read:size=4096,status=0 2022-06-01 00:00:53.053 [ 14] 9 AttachmentReaderSource:beforeRead:size=4096 2022-06-01 00:00:53.053 [ 14] 9 AttachmentReaderSource:read:size=1645,status=0 2022-06-01 00:00:53.054 [ 14] 9 AttachmentReaderSource:beforeRead:size=4096 2022-06-01 00:00:53.054 [ 14] 0 DefaultAttachmentReader:readFailed:reason=SDS is closed 2022-06-01 00:00:53.054 [ 14] 9 AttachmentReaderSource:read:size=0,status=4 2022-06-01 00:00:53.054 [ 14] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData 2022-06-01 00:00:53.054 [ 14] 9 BaseStreamSource:signalEndOfDataCalled 2022-06-01 00:00:53.054 [ 14] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess 2022-06-01 00:00:53.055 [ 14] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=8 2022-06-01 00:00:53.101 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=id3demux0 2022-06-01 00:00:53.101 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=id3demux0 2022-06-01 00:00:53.118 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=mpegaudioparse0 2022-06-01 00:00:53.119 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=mpegaudioparse0 2022-06-01 00:00:53.140 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.241 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.341 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.442 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.542 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.642 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.743 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.843 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.877 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=avdec_mp3-0 2022-06-01 00:00:53.877 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=avdec_mp3-0 2022-06-01 00:00:53.921 [ 29] 9 MediaPlayer:onPadAddedCalled:name=SpeakMediaPlayer 2022-06-01 00:00:53.921 [ 14] 9 MediaPlayer:handlePadAddedSignalCalled:name=SpeakMediaPlayer 2022-06-01 00:00:53.933 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decoder 2022-06-01 00:00:53.934 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-start,source=audio-pipeline 2022-06-01 00:00:53.944 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.950 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=latency,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:53.950 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=stream-status,source=audiosinkringbuffer1 2022-06-01 00:00:53.958 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=tag,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:53.959 [ 14] 0 MediaPlayer:callingOnTags:name=SpeakMediaPlayer 2022-06-01 00:00:53.959 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:53.959 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:53.960 [ 14] 9 MediaPlayer:getCurrentStreamOffset:name=SpeakMediaPlayer,offsetInMilliseconds=0 2022-06-01 00:00:53.960 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=tag,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:53.960 [ 14] 0 MediaPlayer:callingOnTags:name=SpeakMediaPlayer 2022-06-01 00:00:53.960 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:53.961 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:53.961 [ 14] 9 MediaPlayer:getCurrentStreamOffset:name=SpeakMediaPlayer,offsetInMilliseconds=0 2022-06-01 00:00:53.961 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.961 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=1 2022-06-01 00:00:53.962 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.962 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=0 2022-06-01 00:00:53.962 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:53.962 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink 2022-06-01 00:00:53.962 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio-pipeline 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=VOID_PENDING 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=async-done,source=audio-pipeline 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=1 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=2 2022-06-01 00:00:53.963 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.964 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=3 2022-06-01 00:00:53.964 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.964 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=4 2022-06-01 00:00:53.964 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.964 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=6 2022-06-01 00:00:53.965 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.965 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=7 2022-06-01 00:00:53.965 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:53.965 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=8 2022-06-01 00:00:53.965 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.297 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=9 2022-06-01 00:00:54.297 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.297 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=10 2022-06-01 00:00:54.297 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=12 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=13 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=14 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.298 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=15 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=16 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=18 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=19 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.299 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=20 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=21 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=23 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=24 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.300 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=25 2022-06-01 00:00:54.301 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.301 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=26 2022-06-01 00:00:54.301 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.301 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=27 2022-06-01 00:00:54.301 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.633 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=29 2022-06-01 00:00:54.634 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.634 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=30 2022-06-01 00:00:54.635 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.635 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=31 2022-06-01 00:00:54.636 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.636 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=32 2022-06-01 00:00:54.637 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.637 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=33 2022-06-01 00:00:54.638 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.638 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=35 2022-06-01 00:00:54.638 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.638 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=36 2022-06-01 00:00:54.639 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.639 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=37 2022-06-01 00:00:54.640 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.640 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=38 2022-06-01 00:00:54.044 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:53.973 [ 29] 9 BaseStreamSource:onNeedDataCalled:size=4096 2022-06-01 00:00:54.641 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.642 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=40 2022-06-01 00:00:54.642 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.643 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=41 2022-06-01 00:00:54.643 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.643 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=42 2022-06-01 00:00:54.644 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.644 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=43 2022-06-01 00:00:54.645 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.645 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=44 2022-06-01 00:00:54.645 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.646 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=46 2022-06-01 00:00:54.646 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.646 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=47 2022-06-01 00:00:54.647 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.980 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=48 2022-06-01 00:00:54.981 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.981 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=49 2022-06-01 00:00:54.982 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.982 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=50 2022-06-01 00:00:54.983 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.983 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=52 2022-06-01 00:00:54.983 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.984 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=53 2022-06-01 00:00:54.984 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.984 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=54 2022-06-01 00:00:54.985 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.985 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=55 2022-06-01 00:00:54.986 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.987 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=56 2022-06-01 00:00:54.987 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.987 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=58 2022-06-01 00:00:54.720 [ 3] E SpeechSynthesizer:onFocusChangeFailed:reason=stateChangeTimeout,initialDesiredState=PLAYING,desiredState=PLAYING,currentState=GAINING_FOCUS 2022-06-01 00:00:54.741 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:54.988 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:54.988 [ 3] 9 ThreadPool:obtainWorker:created=16,obtained=126,releasedToPool=123,releasedFromPool=0,outstanding=3 2022-06-01 00:00:54.988 [ 3] 9 ThreadPool:obtainWorker:created=16,obtained=127,releasedToPool=123,releasedFromPool=0,outstanding=4 2022-06-01 00:00:54.989 [ 3] 1 FocusManager:notifyActivityTracker:name=Dialog,interfaceName=SpeechRecognizer,focusState=NONE 2022-06-01 00:00:54.989 [ 3] 1 FocusManager:notifyActivityTracker:name=Dialog,interfaceName=SpeechSynthesizer,focusState=FOREGROUND 2022-06-01 00:00:54.989 [ 3] 5 AudioActivityTracker:notifyOfActivityUpdates 2022-06-01 00:00:54.989 [ 3] 9 ThreadPool:obtainWorker:created=16,obtained=128,releasedToPool=123,releasedFromPool=0,outstanding=5 2022-06-01 00:00:54.989 [ f] 9 TaskThread:startThread:moniker= f,duration=1404 2022-06-01 00:00:54.989 [ f] 9 SpeechSynthesizer:onFocusChangedLambda:currentInfo=541ce44d-73e0-4ea0-b0c6-80a2b000f286,m_currentInfo=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:54.990 [ f] 5 AVSContext:toJson 2022-06-01 00:00:54.992 [ f] I UUIDGeneration:low entropy on call to generate UUID:current entropy=0 2022-06-01 00:00:54.993 [ 23] 9 TaskThread:startThread:moniker= 23,duration=4127 2022-06-01 00:00:54.993 [ 23] 0 GUIManager:ChannelFocusChanged:channelName=Dialog,newFocus=FOREGROUND 2022-06-01 00:00:54.993 [ f] 0 EventBuilder:buildJsonEventString:messageId=99e1e42f-a07a-4dc0-a253-e155dccdb421,namespace=System,name=ExceptionEncountered 2022-06-01 00:00:55.326 [ f] 0 EventBuilder:buildJsonEventString:event={"context"\:{"properties"\:[]}\,"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"ExceptionEncountered"\,"messageId"\:"99e1e42f-a07a-4dc0-a253-e155dccdb421"}\,"payload"\:{"unparsedDirective"\:"{\"directive\"\:{\"header\"\:{\"namespace\"\:\"SpeechSynthesizer\"\,\"name\"\:\"Speak\"\,\"messageId\"\:\"541ce44d-73e0-4ea0-b0c6-80a2b000f286\"\,\"dialogRequestId\"\:\"c890ca2d-4b05-47b0-a187-3c352223a019\"}\,\"payload\"\:{\"playBehavior\"\:\"ENQUEUE\"\,\"url\"\:\"cid\:DeviceTTSRendererV4_c19c3b6a-b046-4ed7-9a1f-4bd39cfbf748_249982373\"\,\"format\"\:\"AUDIO_MPEG\"\,\"token\"\:\"amzn1.as-ct.v1.Domain\:Application\:Music#ACRI#DeviceTTSRendererV4_c19c3b6a-b046-4ed7-9a1f-4bd39cfbf748\"\,\"caption\"\:{\"content\"\:\"WEBVTT\\n\\n1\\n00\:00.000 --> 00\:01.546\\nHere's iheart radio .\"\,\"type\"\:\"WEBVTT\"}}}}"\,"error"\:{"type"\:"INTERNAL_ERROR"\,"message"\:"stateChangeTimeout messageId\=541ce44d-73e0-4ea0-b0c6-80a2b000f286"}}}} 2022-06-01 00:00:55.326 [ f] 7 AVSConnectionManager:sendMessage:this=0x25ca00 2022-06-01 00:00:55.326 [ f] 7 HTTP2Transport:onRequestEnqueued:this=0x24cc80 2022-06-01 00:00:55.326 [ f] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:55.327 [ f] 0 CapabilityAgent:removingMessageIdFromMap:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:55.327 [ f] 0 DirectiveProcessor:onHandlingFailed:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286,namespace=SpeechSynthesizer,name=Speak,directiveBeingPreHandled=(nullptr),description=stateChangeTimeout messageId\=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:55.327 [ f] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:55.327 [ f] 0 DirectiveProcessor:scrubDialogRequestIdLocked:dialogRequestId=c890ca2d-4b05-47b0-a187-3c352223a019 2022-06-01 00:00:55.328 [ f] 9 DirectiveProcessor:notifyingProcessingLoop:size:=1 2022-06-01 00:00:55.328 [ f] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:00:55.328 [ f] 9 SpeechSynthesizer:stopPlaying 2022-06-01 00:00:55.328 [ f] 9 MediaPlayer:getOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:54.993 [ 11] 9 TaskThread:startThread:moniker= 11,duration=4211 2022-06-01 00:00:55.329 [ 11] 5 AudioActivityTracker:executeNotifyOfActivityUpdates 2022-06-01 00:00:55.329 [ 1f] 7 MessageRequestHandler:create:context=0x24ccd0,messageRequest=0xaea0d134 2022-06-01 00:00:55.329 [ 1f] 5 ExchangeHandler:init:context=0x24ccd0 2022-06-01 00:00:55.330 [ 1f] 7 MessageRequestHandler:init:context=0x24ccd0,messageRequest=0xaea0d134 2022-06-01 00:00:55.330 [ 1f] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie\=,source=0x7a51c 2022-06-01 00:00:55.330 [ 1f] 9 MimeResponseSink:init:handler=0x7a520 2022-06-01 00:00:55.330 [ 1f] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder 2022-06-01 00:00:55.330 [ 1f] 7 HTTP2Transport:onMessageRequestSent:this=0x24cc80,countOfUnfinishedMessageHandlers=1 2022-06-01 00:00:55.330 [ 1f] 7 HTTP2Transport:createAndSendRequest:this=0x24cc80,type=POST 2022-06-01 00:00:55.330 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=1 2022-06-01 00:00:55.331 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:00:55.331 [ 1f] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines 2022-06-01 00:00:55.331 [ 1f] 9 MessageRequestHandler:getRequestHeaderLines 2022-06-01 00:00:55.331 [ 1f] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:55.331 [ 1f] 0 MessageRequestHandler:EventSent 2022-06-01 00:00:55.332 [ 1f] 9 ConditionVariableWrapper:waitForInner:duration=299999819000 2022-06-01 00:00:55.088 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:55.331 [ a] I DirectiveRouter:cancelDirective:messageId=2eec7a0e-298b-455c-bcb2-9860336df369,action=calling 2022-06-01 00:00:55.664 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=2eec7a0e-298b-455c-bcb2-9860336df369 2022-06-01 00:00:55.664 [ a] 0 AudioPlayer:cancelDirective:name=Play 2022-06-01 00:00:55.664 [ a] 9 ThreadPool:obtainWorker:created=16,obtained=129,releasedToPool=123,releasedFromPool=0,outstanding=6 2022-06-01 00:00:55.665 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:00:55.665 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:00:54.993 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=59 2022-06-01 00:00:55.666 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:55.666 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=60 2022-06-01 00:00:55.667 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:55.667 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=61 2022-06-01 00:00:55.667 [ 19] 9 TaskThread:startThread:moniker= 19,duration=3164 2022-06-01 00:00:55.667 [ 19] 9 MediaPlayer:stopCalled:name=AudioMediaPlayer 2022-06-01 00:00:55.668 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:55.668 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=63 2022-06-01 00:00:55.668 [ 8] 0 MediaPlayer:handleStopCalled:name=AudioMediaPlayer,idPassed=2,currentId=2 2022-06-01 00:00:55.668 [ 8] E MediaPlayer:handleStopFailed:name=AudioMediaPlayer,reason=alreadyStopped 2022-06-01 00:00:55.668 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:55.669 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=64 2022-06-01 00:00:55.432 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:55.366 [ 1e] 9 LibcurlHTTP2Connection:insertActiveStream:this=0x20d008,handle=0x246208,streamId=AVSEvent-15 2022-06-01 00:00:55.670 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-15,size=1,nmemb=65536,userData=0x259b70 2022-06-01 00:00:55.670 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW 2022-06-01 00:00:55.670 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS 2022-06-01 00:00:55.671 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:55.671 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:55.671 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY 2022-06-01 00:00:55.671 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS 2022-06-01 00:00:55.671 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA 2022-06-01 00:00:55.671 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65429 2022-06-01 00:00:55.671 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:55.672 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=64547 2022-06-01 00:00:55.672 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:55.672 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY 2022-06-01 00:00:55.672 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS 2022-06-01 00:00:55.672 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:00:55.672 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:55.672 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES 2022-06-01 00:00:55.673 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE 2022-06-01 00:00:55.669 [ 19] 5 AudioPlayer:releaseMediaPlayer:sourceId=2 2022-06-01 00:00:56.006 [ 19] 9 MediaPlayer:removeObserverCalled:name=AudioMediaPlayer 2022-06-01 00:00:55.769 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:56.008 [ 8] 0 MediaPlayer:handleRemoveObserverCalled:name=AudioMediaPlayer 2022-06-01 00:00:55.672 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.008 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=65 2022-06-01 00:00:56.009 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.009 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=66 2022-06-01 00:00:56.008 [ 19] 9 PooledMediaPlayerFactory:releaseMediaPlayer 2022-06-01 00:00:56.010 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.010 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=67 2022-06-01 00:00:56.010 [ 19] 0 AudioPlayer:changeState:fromState=BUFFERING,toState=IDLE 2022-06-01 00:00:56.010 [ 19] 0 AudioPlayer:executeProvideState:sendToken=false,stateRequestToken=0 2022-06-01 00:00:56.010 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.010 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=69 2022-06-01 00:00:56.010 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-15,size=1,nmemb=65536,userData=0x259b70 2022-06-01 00:00:56.011 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE 2022-06-01 00:00:56.010 [ 19] E AudioPlayer:attachPlaybackAttributesIfAvailableFailed::playerMissing 2022-06-01 00:00:56.011 [ 19] 5 ContextManager:setState 2022-06-01 00:00:56.011 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.011 [ 19] 9 ThreadPool:obtainWorker:created=16,obtained=130,releasedToPool=125,releasedFromPool=0,outstanding=5 2022-06-01 00:00:56.012 [ 25] 9 TaskThread:startThread:moniker= 25,duration=719 2022-06-01 00:00:56.012 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=70 2022-06-01 00:00:56.012 [ 25] I ContextManager:updateCapabilityState 2022-06-01 00:00:56.013 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.013 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=71 2022-06-01 00:00:56.014 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.014 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=72 2022-06-01 00:00:56.014 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.014 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=73 2022-06-01 00:00:56.015 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.015 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=75 2022-06-01 00:00:56.015 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.015 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=76 2022-06-01 00:00:56.016 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.016 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=77 2022-06-01 00:00:56.016 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.350 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=78 2022-06-01 00:00:56.351 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.351 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=80 2022-06-01 00:00:56.351 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.351 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=81 2022-06-01 00:00:56.353 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=buffering,source=decodedQueue 2022-06-01 00:00:56.353 [ 14] 9 MediaPlayer:handleBusMessage:name=SpeakMediaPlayer,message=GST_MESSAGE_BUFFERING,percent=100 2022-06-01 00:00:56.354 [ 14] 9 MediaPlayer:offsetState:name=SpeakMediaPlayer,isSeekable=true,isSeekPointSet=false 2022-06-01 00:00:56.013 [ 19] 1 AudioPlayer:notifyObserver:playerState=IDLE,playerActivity=IDLE,trackProtectionScheme=CLEAR 2022-06-01 00:00:56.354 [ 19] 9 ThreadPool:obtainWorker:created=16,obtained=131,releasedToPool=126,releasedFromPool=0,outstanding=5 2022-06-01 00:00:56.108 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:56.355 [ 22] 9 TaskThread:startThread:moniker= 22,duration=327 2022-06-01 00:00:56.302 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-15,size=1,nmemb=13,userData=0x259b70 2022-06-01 00:00:56.355 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:56.355 [ 1e] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:56.355 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.355 [ 1e] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204 2022-06-01 00:00:56.355 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:56.355 [ 1e] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x24cc80 2022-06-01 00:00:56.356 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:56.356 [ 1e] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS_NO_CONTENT 2022-06-01 00:00:56.356 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:00:56.356 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:00:56.356 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.356 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-15,size=1,nmemb=56,userData=0x259b70 2022-06-01 00:00:56.356 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 8c634b7d-3f93-45a0-8fa1-1e3b6b68edea

2022-06-01 00:00:56.357 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 8c634b7d-3f93-45a0-8fa1-1e3b6b68edea

2022-06-01 00:00:56.357 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.357 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 8c634b7d-3f93-45a0-8fa1-1e3b6b68edea 2022-06-01 00:00:56.357 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-15,size=1,nmemb=83,userData=0x259b70 2022-06-01 00:00:56.357 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-15

2022-06-01 00:00:56.357 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-15

2022-06-01 00:00:56.357 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.357 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-15 2022-06-01 00:00:56.358 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-15,size=1,nmemb=32,userData=0x259b70 2022-06-01 00:00:56.691 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:56.692 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:00:56.692 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.692 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-15,size=1,nmemb=2,userData=0x259b70 2022-06-01 00:00:56.692 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=

2022-06-01 00:00:56.692 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=

2022-06-01 00:00:56.692 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:00:56.693 [ 1e] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE 2022-06-01 00:00:56.693 [ 1e] 9 MimeResponseSink:onResponseFinished:status=COMPLETE 2022-06-01 00:00:56.693 [ 1e] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204 2022-06-01 00:00:56.693 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:56.693 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:56.693 [ 1e] 7 HTTP2Transport:onMessageRequestFinished:this=0x24cc80,countOfUnfinishedMessageHandlers=0 2022-06-01 00:00:56.693 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:00:56.693 [ 1e] 7 LibcurlHTTP2Connection:streamFinished:this=0x20d008,streamId=AVSEvent-15,result=No error,CURLcode=0 2022-06-01 00:00:56.694 [ 1e] 9 LibcurlHTTP2Connection:releaseStream:this=0x20d008,streamId=AVSEvent-15 2022-06-01 00:00:56.694 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:00:56.694 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:00:56.354 [ 17] E AplClientBridge:AplCoreEngine::AplAudioPlayerExtension:updatePlayerActivity : Invalid Player Activity: IDLE 2022-06-01 00:00:56.355 [ 19] 5 TemplateRuntime:onRenderPlayerCardsInfoChanged 2022-06-01 00:00:56.695 [ 19] 9 ThreadPool:obtainWorker:created=16,obtained=132,releasedToPool=126,releasedFromPool=0,outstanding=6 2022-06-01 00:00:56.695 [ 12] 9 TaskThread:startThread:moniker= 12,duration=278 2022-06-01 00:00:56.695 [ 12] 5 TemplateRuntime:onRenderPlayerCardsInfoChangedInExecutor 2022-06-01 00:00:56.695 [ 12] 5 TemplateRuntime:executeAudioPlayerInfoUpdates:audioItemId=,offset=0,audioPlayerState=IDLE,isNonPlayerInfoCardDisplayed=false 2022-06-01 00:00:56.359 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=new-clock,source=audio-pipeline 2022-06-01 00:00:56.455 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:56.013 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.696 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:56.696 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.697 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.697 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.697 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.697 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:56.697 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio_sink 2022-06-01 00:00:56.697 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=fadeIn 2022-06-01 00:00:56.697 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=volume 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=converter 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decodedQueue 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=avdec_mp3-0 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=mpegaudioparse0 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=id3demux0 2022-06-01 00:00:57.030 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=typefind 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=decoder 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=src 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=state-changed,source=audio-pipeline 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING 2022-06-01 00:00:57.031 [ 14] 0 MediaPlayer:callingOnPlaybackStarted:name=SpeakMediaPlayer,currentId=1 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.031 [ 14] 9 MediaPlayer:getCurrentStreamOffset:name=SpeakMediaPlayer,offsetInMilliseconds=670 2022-06-01 00:00:57.031 [ 14] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=1 2022-06-01 00:00:57.032 [ 14] 9 MediaPlayer:messageReceived:name=SpeakMediaPlayer,type=tag,source=audio_sink-actual-sink-alsa 2022-06-01 00:00:57.032 [ 14] 0 MediaPlayer:callingOnTags:name=SpeakMediaPlayer 2022-06-01 00:00:57.032 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:57.032 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.032 [ 14] 9 MediaPlayer:getCurrentStreamOffset:name=SpeakMediaPlayer,offsetInMilliseconds=670 2022-06-01 00:00:57.032 [ 14] 9 BaseStreamSource:handleNeedDataCalled 2022-06-01 00:00:57.032 [ 14] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=12 2022-06-01 00:00:57.033 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:57.033 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:56.796 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:57.033 [ 14] 9 MediaPlayer:getCurrentStreamOffset:name=SpeakMediaPlayer,offsetInMilliseconds=670 2022-06-01 00:00:57.034 [ f] 9 MediaPlayer:stopCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.034 [ 14] 9 AttachmentReaderSource:beforeRead:size=4096 2022-06-01 00:00:57.034 [ 14] 0 DefaultAttachmentReader:readFailed:reason=SDS is closed 2022-06-01 00:00:57.034 [ 14] 9 AttachmentReaderSource:read:size=0,status=4 2022-06-01 00:00:57.034 [ 14] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData 2022-06-01 00:00:57.034 [ 14] 9 BaseStreamSource:signalEndOfDataCalled 2022-06-01 00:00:57.034 [ 14] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess 2022-06-01 00:00:57.034 [ 14] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=12 2022-06-01 00:00:57.034 [ 14] 0 MediaPlayer:handleStopCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:57.041 [ f] 0 SpeechSynthesizer:executePlaybackStarted 2022-06-01 00:00:57.041 [ f] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING 2022-06-01 00:00:57.041 [ f] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0,state=PLAYING 2022-06-01 00:00:57.041 [ f] 9 MediaPlayer:getOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.041 [ 14] 0 MediaPlayer:callingOnPlaybackStopped:name=SpeakMediaPlayer,currentId=1 2022-06-01 00:00:57.375 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=1 2022-06-01 00:00:57.375 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.375 [ 14] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=SpeakMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:57.375 [ 14] 9 SpeechSynthesizer:onPlaybackStopped:callbackSourceId=1 2022-06-01 00:00:57.376 [ 14] 9 MediaPlayer:tearDownTransientPipelineElements:name=SpeakMediaPlayer 2022-06-01 00:00:57.376 [ 14] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=SpeakMediaPlayer 2022-06-01 00:00:57.376 [ 14] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=SpeakMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:00:57.377 [ 14] 9 BaseStreamSource:~BaseStreamSource 2022-06-01 00:00:57.379 [ 14] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=0 2022-06-01 00:00:57.379 [ f] 5 ContextManager:setState 2022-06-01 00:00:57.379 [ f] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=0 2022-06-01 00:00:57.379 [ f] 0 DialogUXStateAggregator:onStateChanged:SpeechSynthesizerState=PLAYING 2022-06-01 00:00:57.379 [ f] 9 ThreadPool:obtainWorker:created=16,obtained=133,releasedToPool=127,releasedFromPool=0,outstanding=6 2022-06-01 00:00:57.380 [ f] 0 SpeechSynthesizer:executePlaybackStopped:callbackSourceId=1,sourceId=1,desiredState=FINISHED 2022-06-01 00:00:57.380 [ f] 0 SpeechSynthesizer:executePlaybackFinished 2022-06-01 00:00:57.381 [ f] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED 2022-06-01 00:00:57.381 [ f] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0,state=FINISHED 2022-06-01 00:00:57.381 [ f] 5 ContextManager:setState 2022-06-01 00:00:57.381 [ f] 9 MediaPlayer:handleGetOffsetCalled:name=SpeakMediaPlayer,idPassed=1,currentId=0 2022-06-01 00:00:57.381 [ f] 0 DialogUXStateAggregator:onStateChanged:SpeechSynthesizerState=FINISHED 2022-06-01 00:00:57.381 [ f] 3 SpeechSynthesizer:executePlaybackFinished:reason=SpeechFinished 2022-06-01 00:00:57.381 [ f] 0 CapabilityAgent:removingMessageIdFromMap:messageId=541ce44d-73e0-4ea0-b0c6-80a2b000f286 2022-06-01 00:00:57.382 [ 25] I ContextManager:updateCapabilityState 2022-06-01 00:00:57.382 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.382 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.382 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.383 [ 25] I ContextManager:updateCapabilityState 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.384 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.716 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.717 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.717 [ 25] 5 ContextManager:updateCapabilityStateDetailed 2022-06-01 00:00:57.382 [ 21] 9 TaskThread:startThread:moniker= 21,duration=3067 2022-06-01 00:00:57.717 [ 21] 0 DialogUXStateAggregator:onStateChangedLambda:SpeechSynthesizerState=PLAYING 2022-06-01 00:00:57.717 [ 21] 0 DialogUXStateAggregator:executeSetState:from=THINKING,to=SPEAKING,validTransition=true 2022-06-01 00:00:57.717 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=134,releasedToPool=129,releasedFromPool=0,outstanding=5 2022-06-01 00:00:57.718 [ 21] 5 TemplateRuntime:onDialogUXStateChanged:state=SPEAKING 2022-06-01 00:00:57.718 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=135,releasedToPool=129,releasedFromPool=0,outstanding=6 2022-06-01 00:00:57.718 [ 21] 5 AlexaPresentation:onDialogUXStateChanged:state=SPEAKING 2022-06-01 00:00:57.718 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=136,releasedToPool=129,releasedFromPool=0,outstanding=7 2022-06-01 00:00:57.718 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=137,releasedToPool=129,releasedFromPool=0,outstanding=8 2022-06-01 00:00:57.718 [ 21] 9 ThreadPool:obtainWorker:created=16,obtained=138,releasedToPool=129,releasedFromPool=0,outstanding=9 2022-06-01 00:00:57.719 [ 21] 0 DialogUXStateAggregator:onStateChangedLambda:SpeechSynthesizerState=FINISHED 2022-06-01 00:00:57.719 [ 21] 5 DialogUXStateAggregator:executeTryEnterIdleState 2022-06-01 00:00:57.133 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:57.719 [ 18] 9 TaskThread:startThread:moniker= 18,duration=2089 2022-06-01 00:00:57.720 [ 5] 9 TaskThread:startThread:moniker= 5,duration=2009 2022-06-01 00:00:57.720 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=1915 2022-06-01 00:00:57.720 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=1926 2022-06-01 00:00:57.720 [ 1c] 9 ThreadPool:obtainWorker:created=16,obtained=139,releasedToPool=129,releasedFromPool=0,outstanding=10 #####################################

ALEXA STATE: SPEAKING

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

2022-06-01 00:00:57.721 [ e] 9 TaskThread:startThread:moniker= e,duration=2402 2022-06-01 00:00:57.721 [ e] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=SPEAKING 2022-06-01 00:00:57.721 [ 3] 9 TaskThread:startThread:moniker= 3,duration=820 2022-06-01 00:00:57.721 [ 3] 9 WebSocketServer:writeMessageBegin 2022-06-01 00:00:57.721 [ 3] 9 WebSocket:AccessLog 2022-06-01 00:00:57.722 [ 3] 9 WebSocket:AccessLog 2022-06-01 00:00:57.722 [ 3] 9 WebSocketServer:writeMessageComplete 2022-06-01 00:00:57.722 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.722 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.722 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.722 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.723 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.819 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:57.919 [ 2a] 5 DialogUXStateAggregator:tryEnterIdleStateOnTimer 2022-06-01 00:00:57.920 [ 21] 5 DialogUXStateAggregator:tryEnterIdleStateOnTimerExecutor:m_currentState=SPEAKING,m_audioInputProcessorState=IDLE,m_speechSynthesizerState=FINISHED 2022-06-01 00:00:57.920 [ 21] 0 DialogUXStateAggregator:executeSetState:from=SPEAKING,to=IDLE,validTransition=true 2022-06-01 00:00:57.920 [ 21] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE 2022-06-01 00:00:57.920 [ 21] 5 AlexaPresentation:onDialogUXStateChanged:state=IDLE 2022-06-01 00:00:57.921 [ f] 1 FocusManager:releaseChannel:channelName=Dialog 2022-06-01 00:00:57.921 [ f] 9 ThreadPool:obtainWorker:created=16,obtained=140,releasedToPool=129,releasedFromPool=0,outstanding=11 #################################

ALEXA STATE: IDLE

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

2022-06-01 00:00:57.922 [ e] 0 AudioInputProcessor:executeOnDialogUXStateChanged:newState=IDLE 2022-06-01 00:00:57.922 [ e] 0 AudioInputProcessor:executeResetState 2022-06-01 00:00:57.922 [ 23] 9 TaskThread:startThread:moniker= 23,duration=1025 2022-06-01 00:00:57.922 [ 23] 5 FocusManager:releaseChannelHelper:channelToRelease=Dialog 2022-06-01 00:00:57.922 [ 23] 5 Channel:removeActivityHelperLocked:interface=SpeechSynthesizer 2022-06-01 00:00:57.922 [ 23] 0 Channel:addToChannelUpdatesLocked:interface=SpeechSynthesizer,focusState=NONE 2022-06-01 00:00:57.922 [ 3] 9 WebSocketServer:writeMessageBegin 2022-06-01 00:00:57.923 [ 3] 9 WebSocket:AccessLog 2022-06-01 00:00:57.923 [ 3] 9 WebSocket:AccessLog 2022-06-01 00:00:57.923 [ 3] 9 WebSocketServer:writeMessageComplete 2022-06-01 00:00:57.923 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.923 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.923 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.923 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.924 [ 1b] 9 WebSocket:AccessLog 2022-06-01 00:00:57.920 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:57.922 [ 23] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE,result=skip,state=FINISHED 2022-06-01 00:00:57.925 [ 23] 5 Channel:setFocus:name=Dialog,newfocusState=NONE,prevfocusState=FOREGROUND,newMixingBehavior=MUST_STOP,forceUpdate=false 2022-06-01 00:00:57.925 [ 23] W Channel:notifyActivitiesFailed::No Associated Activities Found 2022-06-01 00:00:57.926 [ 23] 1 FocusManager:notifyActivityTracker:name=Dialog,interfaceName=SpeechSynthesizer,focusState=NONE 2022-06-01 00:00:57.926 [ 23] 5 AudioActivityTracker:notifyOfActivityUpdates 2022-06-01 00:00:57.926 [ 23] 9 ThreadPool:obtainWorker:created=16,obtained=141,releasedToPool=129,releasedFromPool=0,outstanding=12 2022-06-01 00:00:57.926 [ 11] 9 TaskThread:startThread:moniker= 11,duration=301 2022-06-01 00:00:57.926 [ 11] 5 AudioActivityTracker:executeNotifyOfActivityUpdates 2022-06-01 00:00:57.926 [ 18] 0 GUIManager:ChannelFocusChanged:channelName=Dialog,newFocus=NONE 2022-06-01 00:00:58.025 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.126 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.226 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.327 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.428 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.528 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.628 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.729 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.830 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:58.930 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.031 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.131 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.231 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.332 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.433 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.533 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.634 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.734 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.835 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:00:59.935 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.036 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.136 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.237 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.337 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.437 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.538 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.639 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.739 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.839 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:00.940 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.040 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.141 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.241 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.342 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.442 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.543 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.643 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.744 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.844 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:01.945 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.045 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 [ 476.551506@2]- snd_card_meson: audio speaker off 2022-06-01 00:01:02.146 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.246 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.347 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.447 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.548 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.648 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.749 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.849 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:02.950 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.050 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.150 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.251 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.351 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.452 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.552 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.653 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.753 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.854 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:03.954 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.055 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.155 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.256 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.356 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.457 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.557 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.658 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.759 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.859 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:04.961 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.061 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.162 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.263 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.363 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.464 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.565 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.665 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.766 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.866 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:05.967 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.067 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.168 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.268 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.369 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.469 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.570 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.670 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.771 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.871 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:06.972 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:07.072 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:07.173 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:07.220 [ 28] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:01:07.222 [ 28] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=https\://custom-hls.iheart.com/ihr-ingestion-pipeline-production-sbmg/encodes/Feb21/020221/A10301A0004545099C_20210202172913661/64064438.20122.m4a.m3u8?null 2022-06-01 00:01:07.222 [ 28] 9 LibCurlHttpContentFetcher:transferThread::end 2022-06-01 00:01:07.226 [ 2] 0 PlaylistParser:nonSuccessStatusCodeFromGetHeader:statusCode=HTTP_RESPONSE_CODE_UNDEFINED 2022-06-01 00:01:07.227 [ 2] 3 UrlContentToAttachmentConverter:onPlaylistEntryParsed:status=ERROR 2022-06-01 00:01:07.228 [ 2] 9 ThreadPool:obtainWorker:created=16,obtained=142,releasedToPool=140,releasedFromPool=0,outstanding=2 2022-06-01 00:01:07.229 [ 28] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=BODY_DONE:State transition 2022-06-01 00:01:07.230 [ 22] 9 TaskThread:startThread:moniker= 22,duration=2192 2022-06-01 00:01:07.231 [ 22] 9 UrlContentToAttachmentConverter:closingWriter 2022-06-01 00:01:07.232 [ 22] 0 UrlContentToAttachmentConverter:closeStreamWriter 2022-06-01 00:01:07.232 [ 22] 9 MediaPlayer:onError:name=AudioMediaPlayer 2022-06-01 00:01:07.234 [ 8] 9 MediaPlayer:onErrorCallback:name=AudioMediaPlayer 2022-06-01 00:01:07.235 [ 8] 0 MediaPlayer:callingOnPlaybackError:name=AudioMediaPlayer,type=MEDIA_ERROR_INTERNAL_DEVICE_ERROR,error=streamingError,currentId=2 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:handleGetOffsetCalled:name=AudioMediaPlayer,idPassed=2,currentId=2 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=AudioMediaPlayer 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=AudioMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:tearDownTransientPipelineElements:name=AudioMediaPlayer 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:getCurrentStreamOffsetCalled:name=AudioMediaPlayer 2022-06-01 00:01:07.235 [ 8] 9 MediaPlayer:getCurrentStreamOffsetInvalid:name=AudioMediaPlayer,reason=invalidPipelineState,state=NULL,expectedStates=PAUSED/PLAYING 2022-06-01 00:01:07.236 [ 8] 9 BaseStreamSource:~BaseStreamSource 2022-06-01 00:01:07.235 [ 2] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher 2022-06-01 00:01:07.236 [ 2] 9 PlaylistParser:playQueueEmpty 2022-06-01 00:01:07.237 [ 8] 9 UrlContentToAttachmentConverter:doShutdown::Starting to shutdown 2022-06-01 00:01:31.283 [ 1e] 9 LibcurlHTTP2Request:writeCallback:id=AVSDownChannel-1,size=1,nmemb=186,userData=0x1c9b88 2022-06-01 00:01:31.283 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveData:size=186 2022-06-01 00:01:31.284 [ 1e] 9 MimeResponseSink:onBeginMimePart 2022-06-01 00:01:31.284 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.285 [ 1e] 9 MimeResponseSink:JsonContentDetected 2022-06-01 00:01:31.285 [ 1e] 9 MimeResponseSink:onReceiveMimeData:size=132 2022-06-01 00:01:31.285 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.285 [ 1e] 9 MimeResponseSink:onEndMimePart 2022-06-01 00:01:31.285 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.285 [ 1e] 9 ThreadPool:obtainWorker:created=16,obtained=143,releasedToPool=142,releasedFromPool=0,outstanding=1 2022-06-01 00:01:31.286 [ 19] 9 TaskThread:startThread:moniker= 19,duration=437 2022-06-01 00:01:31.286 [ 19] 5 JsonUtils:findNode:reason=missingDirectChild,child=instance 2022-06-01 00:01:31.286 [ 19] 5 JsonUtils:findNode:reason=missingDirectChild,child=payloadVersion 2022-06-01 00:01:31.286 [ 19] 5 JsonUtils:findNode:reason=missingDirectChild,child=correlationToken 2022-06-01 00:01:31.286 [ 19] 5 JsonUtils:findNode:reason=missingDirectChild,child=eventCorrelationToken 2022-06-01 00:01:31.287 [ 19] 5 JsonUtils:findNode:reason=missingDirectChild,child=endpoint 2022-06-01 00:01:31.287 [ 19] 0 AvsDirective:parseEndpoint::noEndpoint 2022-06-01 00:01:31.287 [ 19] I DirectiveSequencer:onDirective:directive=namespace\:System\,name\:ReportState\,messageId\:4f6690bd-18ef-4dff-95c1-0ed2d7342162\,dialogRequestId\:\,correlationToken\:\,eventCorrelationToken\:\,payloadVersion\:\,instance\: 2022-06-01 00:01:31.287 [ 19] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:01:31.287 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:01:31.288 [ b] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:01:31.288 [ b] I DirectiveRouter:preHandleDirective:messageId=4f6690bd-18ef-4dff-95c1-0ed2d7342162,action=calling 2022-06-01 00:01:31.288 [ b] 0 CapabilityAgent:addingMessageIdToMap:messageId=4f6690bd-18ef-4dff-95c1-0ed2d7342162 2022-06-01 00:01:31.288 [ b] 5 ConditionVariableWrapper:notifyOne 2022-06-01 00:01:31.288 [ b] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:01:31.288 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:01:31.289 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:queue size=1 2022-06-01 00:01:31.289 [ a] 9 DirectiveProcessor:handleQueuedDirectivesLocked:proceeding with directive=4f6690bd-18ef-4dff-95c1-0ed2d7342162,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False 2022-06-01 00:01:31.289 [ a] 5 DirectiveRouter:getHandlerAndPolicyLocked::configuration found 2022-06-01 00:01:31.289 [ a] I DirectiveRouter:handleDirective:messageId=4f6690bd-18ef-4dff-95c1-0ed2d7342162,action=calling 2022-06-01 00:01:31.289 [ a] 9 ThreadPool:obtainWorker:created=16,obtained=144,releasedToPool=142,releasedFromPool=0,outstanding=2 2022-06-01 00:01:31.290 [ a] 9 DirectiveProcessor:processCancelingQueueLocked:size=0 2022-06-01 00:01:31.290 [ a] 5 ConditionVariableWrapper:waitInner 2022-06-01 00:01:31.290 [ 12] 9 TaskThread:startThread:moniker= 12,duration=485 2022-06-01 00:01:31.290 [ 12] 5 ReportStateHandler:handleReportState 2022-06-01 00:01:31.305 [ 12] 5 ReportStateHandler:sendReportState:pendingReport=true 2022-06-01 00:01:31.306 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.306 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.307 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.307 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.307 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.307 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.307 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.308 [ 12] 5 StateReportGenerator:generateSettingStateReport 2022-06-01 00:01:31.309 [ 12] I UUIDGeneration:low entropy on call to generate UUID:current entropy=0 2022-06-01 00:01:31.309 [ 12] 0 EventBuilder:buildJsonEventString:messageId=3bde3d71-97d9-4dce-a57a-675ac35b57c1,namespace=System,name=StateReport 2022-06-01 00:01:31.310 [ 12] 0 EventBuilder:buildJsonEventString:event={"event"\:{"header"\:{"namespace"\:"System"\,"name"\:"StateReport"\,"messageId"\:"3bde3d71-97d9-4dce-a57a-675ac35b57c1"}\,"payload"\:{"states"\:[{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlarmVolumeRampReport"}\,"payload"\:{"alarmVolumeRamp"\:"NONE"}}\,{"header"\:{"namespace"\:"Alexa.DoNotDisturb"\,"name"\:"ReportDoNotDisturb"}\,"payload"\:{"enabled"\:false}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"SpeechConfirmationReport"}\,"payload"\:{"speechConfirmation"\:"NONE"}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"WakeWordConfirmationReport"}\,"payload"\:{"wakeWordConfirmation"\:"NONE"}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"WakeWordsReport"}\,"payload"\:{"wakeWords"\:["ALEXA"]}}\,{"header"\:{"namespace"\:"System"\,"name"\:"LocalesReport"}\,"payload"\:{"locales"\:["en-US"]}}\,{"header"\:{"namespace"\:"System"\,"name"\:"NetworkInfoReport"}\,"payload"\:{"networkInfo"\:{}}}\,{"header"\:{"namespace"\:"System"\,"name"\:"TimeZoneReport"}\,"payload"\:{"timeZone"\:"America/Vancouver"}}]}}} 2022-06-01 00:01:31.644 [ 12] 7 SettingEventSender:sendEvent:attempt=0 2022-06-01 00:01:31.644 [ 12] 7 AVSConnectionManager:sendMessage:this=0x25ca00 2022-06-01 00:01:31.645 [ 12] 7 HTTP2Transport:onRequestEnqueued:this=0x24cc80 2022-06-01 00:01:31.645 [ 12] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:01:31.645 [ 1f] 7 MessageRequestHandler:create:context=0x24ccd0,messageRequest=0xafe122b4 2022-06-01 00:01:31.645 [ 1f] 5 ExchangeHandler:init:context=0x24ccd0 2022-06-01 00:01:31.645 [ 1f] 7 MessageRequestHandler:init:context=0x24ccd0,messageRequest=0xafe122b4 2022-06-01 00:01:31.646 [ 1f] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie\=,source=0x1a67b4 2022-06-01 00:01:31.646 [ 1f] 9 MimeResponseSink:init:handler=0x1a67b8 2022-06-01 00:01:31.646 [ 1f] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder 2022-06-01 00:01:31.646 [ 1f] 7 HTTP2Transport:onMessageRequestSent:this=0x24cc80,countOfUnfinishedMessageHandlers=1 2022-06-01 00:01:31.646 [ 1f] 7 HTTP2Transport:createAndSendRequest:this=0x24cc80,type=POST 2022-06-01 00:01:31.647 [ 1f] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines 2022-06-01 00:01:31.647 [ 1f] 9 MessageRequestHandler:getRequestHeaderLines 2022-06-01 00:01:31.647 [ 1f] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.647 [ 1f] 0 MessageRequestHandler:EventSent 2022-06-01 00:01:31.648 [ 1f] 9 ConditionVariableWrapper:waitForInner:duration=299999790000 2022-06-01 00:01:31.688 [ 1e] 9 LibcurlHTTP2Connection:insertActiveStream:this=0x20d008,handle=0x246208,streamId=AVSEvent-17 2022-06-01 00:01:31.689 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-17,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS 2022-06-01 00:01:31.690 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS 2022-06-01 00:01:31.690 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA 2022-06-01 00:01:31.691 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=65429 2022-06-01 00:01:31.691 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.691 [ 1e] 9 MessageRequestHandler:onSendMimePartData:size=64464 2022-06-01 00:01:31.691 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.692 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY 2022-06-01 00:01:31.692 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS 2022-06-01 00:01:31.692 [ 1e] 9 MessageRequestHandler:getMimePartHeaderLines 2022-06-01 00:01:31.692 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:31.692 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES 2022-06-01 00:01:31.692 [ 1e] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE 2022-06-01 00:01:31.694 [ 1e] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-17,size=1,nmemb=65536,userData=0x213e18 2022-06-01 00:01:32.028 [ 1e] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE 2022-06-01 00:01:32.316 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-17,size=1,nmemb=13,userData=0x213e18 2022-06-01 00:01:32.316 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204 2022-06-01 00:01:32.317 [ 1e] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204 2022-06-01 00:01:32.317 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.317 [ 1e] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204 2022-06-01 00:01:32.317 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:01:32.317 [ 1e] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x24cc80 2022-06-01 00:01:32.317 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:01:32.317 [ 1e] 7 MessageRequestHandler:responseCodeTranslated:responseStatus=SUCCESS_NO_CONTENT 2022-06-01 00:01:32.318 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:01:32.318 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204

2022-06-01 00:01:32.318 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.318 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-17,size=1,nmemb=56,userData=0x213e18 2022-06-01 00:01:32.318 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 97598130-19c9-41e7-a7ca-6ac3eceb00fb

2022-06-01 00:01:32.318 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 97598130-19c9-41e7-a7ca-6ac3eceb00fb

2022-06-01 00:01:32.318 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.318 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 97598130-19c9-41e7-a7ca-6ac3eceb00fb 2022-06-01 00:01:32.319 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-17,size=1,nmemb=83,userData=0x213e18 2022-06-01 00:01:32.319 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-17

2022-06-01 00:01:32.319 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-17

2022-06-01 00:01:32.319 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.319 [ 1e] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid\: 120b9efffed13b13-00000fe0-026b2333-1bedf561e2c25fd0-7dcbfb65-17 2022-06-01 00:01:32.319 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-17,size=1,nmemb=32,userData=0x213e18 2022-06-01 00:01:32.319 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:01:32.319 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin\: *

2022-06-01 00:01:32.320 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.320 [ 1e] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-17,size=1,nmemb=2,userData=0x213e18 2022-06-01 00:01:32.320 [ 1e] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=

2022-06-01 00:01:32.320 [ 1e] 9 MimeResponseSink:onReceiveHeaderLine:line=

2022-06-01 00:01:32.320 [ 1e] 9 HTTP2Transport:onActivity:this=0x24cc80 2022-06-01 00:01:32.321 [ 1e] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE 2022-06-01 00:01:32.321 [ 1e] 9 MimeResponseSink:onResponseFinished:status=COMPLETE 2022-06-01 00:01:32.321 [ 1e] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204 2022-06-01 00:01:32.321 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:01:32.321 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:01:32.321 [ 1e] 7 HTTP2Transport:onMessageRequestFinished:this=0x24cc80,countOfUnfinishedMessageHandlers=0 2022-06-01 00:01:32.321 [ 1e] 5 ConditionVariableWrapper:notifyAll 2022-06-01 00:01:32.323 [ 12] 7 SettingEventSender:sendEvent:status=SUCCESS_NO_CONTENT 2022-06-01 00:01:32.325 [ 1e] 7 LibcurlHTTP2Connection:streamFinished:this=0x20d008,streamId=AVSEvent-17,result=No error,CURLcode=0 2022-06-01 00:01:32.325 [ 1e] 9 LibcurlHTTP2Connection:releaseStream:this=0x20d008,streamId=AVSEvent-17 2022-06-01 00:01:32.326 [ 1e] 7 MessageRequestHandler:reportMessageRequestAcknowledged 2022-06-01 00:01:32.326 [ 1e] 7 MessageRequestHandler:reportMessageRequestFinished 2022-06-01 00:01:32.336 [ 12] 0 CapabilityAgent:removingMessageIdFromMap:messageId=4f6690bd-18ef-4dff-95c1-0ed2d7342162 2022-06-01 00:01:32.659 [ 12] 0 DirectiveProcessor:onHandlingCompeted:messageId=4f6690bd-18ef-4dff-95c1-0ed2d7342162,directiveBeingPreHandled=(nullptr) [ 515.041262@2]s snd_pcm: enter aml_pcm_trigger [ 515.041351@2]s snd_pcm: aml_pcm_timer_stop [ 515.044080@2]s snd_pcm_dai: aiu master pcm stream 1 disable [ 515.044080@2]s [ 515.051556@1]- snd_pcm: enter aml_pcm_prepare [ 515.055748@1]- snd_pcm_dai: aml_pcm_dai_prepare rate:48000, bits:16, slots:4, mclk:3072000 [ 515.065112@1]- snd_pcm_dai: aml_pcm_dai_prepare capture stream buffer start: 908591104 size: 0x4000 [ 515.073461@0]d snd_pcm: enter aml_pcm_trigger [ 515.077109@0]d snd_pcm: aml_pcm_timer_start [ 515.081251@0]d snd_pcm_dai: aiu pcm master stream 1 enable [ 515.081251@0]d [ 515.088417@0]d audio_hw_pcmpcm master in, pcm mode:1, valid bits:0xf, valid slot:0xf [ 522.404239@1]s wifi_mac_find_tx_sta 1182 [ 523.404982@2]s wifi_mac_find_tx_sta 1182 [ 523.771366@2]s aml_wifi_get_platform_verid(947) version name: gva [ 524.406002@2]s wifi_mac_find_tx_sta 1182 [ 525.271289@1]s wifi_mac_find_tx_sta 1182 [ 525.407212@2]s wifi_mac_find_tx_sta 1182 [ 571.508607@1]s aml_wifi_get_platform_verid(947) version name: gva [ 626.059789@2]- 904:rx_host_offset:106928, rx_fifo_fw:106928, hal_open:1 [ 642.404571@2]s wifi_mac_find_tx_sta 1182 [ 642.726505@0]s aml_wifi_get_platform_verid(947) version name: gva [ 643.406473@1]s wifi_mac_find_tx_sta 1182 [ 643.627543@0]s aml_wifi_get_platform_verid(947) version name: gva [ 643.628983@0]s aml_wifi_get_platform_verid(947) version name: gva [ 644.407262@1]s wifi_mac_find_tx_sta 1182 [ 645.407718@0]s wifi_mac_find_tx_sta 1182 [ 646.312174@0]s aml_wifi_get_platform_verid(947) version name: gva [ 646.547252@1]s aml_wifi_get_platform_verid(947) version name: gva [ 703.191353@0]s aml_wifi_get_platform_verid(947) version name: gva [ 709.591341@2]s aml_wifi_get_platform_verid(947) version name: gva 2022-06-01 00:05:04.144 [ 6] 5 InternetConnectionMonitor:testConnection 2022-06-01 00:05:04.144 [ 6] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher 2022-06-01 00:05:04.146 [ 6] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition 2022-06-01 00:05:04.147 [ 2b] 9 LibCurlHttpContentFetcher:transferThread::start 2022-06-01 00:05:04.149 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.251 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.302 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.304 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.406 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.505 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.514 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.616 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.717 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.731 [ 2b] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1 2022-06-01 00:05:04.732 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.732 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.732 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.732 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.733 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.734 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=419 2022-06-01 00:05:04.734 [ 2b] 9 LibCurlHttpContentFetcher:headerCallback::CALLED 2022-06-01 00:05:04.734 [ 2b] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found. 2022-06-01 00:05:04.734 [ 6] 9 InternetConnectionMonitor:testConnection:contentLength=419 2022-06-01 00:05:04.734 [ 2b] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition 2022-06-01 00:05:04.735 [ 6] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition 2022-06-01 00:05:04.735 [ 2b] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=419,contentLength=419,currentContentReceived=419,remaining=0,totalBytesWritten=419 2022-06-01 00:05:04.735 [ 2b] 7 CurlEasyHandleWrapper:getEffectiveUrl:effectiveURL=http\://spectrum.s3.amazonaws.com/kindle-wifi/wifistub.html 2022-06-01 00:05:04.736 [ 2b] 9 LibCurlHttpContentFetcher:transferThread::end 2022-06-01 00:05:04.736 [ 2b] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition 2022-06-01 00:05:04.835 [ 6] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes 2022-06-01 00:05:04.835 [ 6] 9 InternetConnectionMonitor:testConnection::Finished reading 2022-06-01 00:05:04.835 [ 6] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true 2022-06-01 00:05:04.835 [ 6] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher [ 762.406048@2]s wifi_mac_find_tx_sta 1182 [ 763.407561@1]s wifi_mac_find_tx_sta 1182

[aravnata]

Since this issue is reproducible with the AVS Device SDK it does not appear to be related to the Smart Screen SDK. Please open a new issue here and work with them to resolve it.

[liuyiamlogic]

ok，tks！