Alexa Wake up Word not work

Briefly summarize your issue:
Hi
Alexa work perfectly when i set audioInputInitiator on "TAP" but it not respond when i say "alexa" with the audioInputInitiator setted on "WAKEWORD"
What is the expected behavior?
Alexa respond when say Alexa
What behavior are you observing?
Alexa not enter in listening status when i say Alexa
Provide the steps to reproduce the issue, if applicable:
I tried to create a new application with Alexa Smart Screen SDK, i followed this guide https://developer.amazon.com/it-IT/docs/alexa/alexa-smart-screen-sdk/raspberry-pi.html.
i setted the system to accept word wakeup in SmartScreenSDKConfig.json
"gui": {
    "appConfig": {
      "description": "TV PTT Device - Fullscreen + Lower Third Panel",
      "mode": "TV",
      "emulateDisplayDimensions": true,
      "scaleToFill": true,
      "audioInputInitiator": "WAKEWORD",
      "windows": [
        {....

if i try to say "ALEXA" nothing is happening, but if i set audioInputInitiator to "TAP" the system work perfectly when i press "A".
This is my command to start Alexa Smart Screen SDK
 PA_ALSA_PLUGHW=1 ./modules/Alexa/SampleApp/src/SampleApp -C\
 $HOME/sdk_folder/sdk-build/Integration/AlexaClientSDKConfig.json  -C\
 $HOME/sdk_folder/alexa-smart-screen-sdk/modules/GUI/config/SmartScreenSDKConfig.json \
 -K  $HOME/sdk_folder/third-party/alexa-rpi/models  -L DEBUG9

What version of the AVS Device SDK are you using?
  <1.19.1>

Tell us what hardware you're using:

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

Tell us about your OS (Type & version):

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

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

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

Which wake word engine did you build with?

I've used the Sensory wake word
if i try to start Alexa without Alexa Smart Screen SDK from this code
cd /home/pi/sdk_folder/sdk-build
 PA_ALSA_PLUGHW=1 ./SampleApp/src/SampleApp ./Integration/AlexaClientSDKConfig.json ../third-party/alexa-rpi/models

Wakeup word work perfectly but if i tray to start Alexa Smart Screen SDK with this code "ALEXA" word wakeup doesn't work!
 cd $HOME/sdk_folder/ss-build
 PA_ALSA_PLUGHW=1 ./modules/Alexa/SampleApp/src/SampleApp -C \
 $HOME/sdk_folder/sdk-build/Integration/AlexaClientSDKConfig.json -C \
 $HOME/sdk_folder/alexa-smart-screen-sdk/modules/GUI/config/SmartScreenSDKConfig.json  \
 -K ../third-party/alexa-rpi/models  -L DEBUG9

Thank you for your help

@jbllea,
Have you tried the same use case with AVS Device SDK SampleApp? If you have not, can you please try with AVS Device SDK SampleApp only and let us know whether it works or not there?
Thanks,
Mike

Hi
Yes, i’ve done it, and with Device SDK the system work perfectly

@jbllea
Did you also follow the same steps that you did to enable wake word engine on Device SDK for Smart Screen SDK?
You'll have to follow the same steps, which you did for Device SDK, for Smart Screen SDK to enable wake word engine on Smart Screen SDK. We've submitted an internal ticket to improve documentation on this matter.
Please let me know if you still have issues with the wake word engine after.
Mike

Hi
to start wake word engine i followed this step:
wake word engine
and to build seample app for Screen SDK i've added this instruction on make command:
 -DSENSORY_KEY_WORD_DETECTOR=ON \
 -DSENSORY_KEY_WORD_DETECTOR_LIB_PATH=/home/pi/sdk-folder/third-party/alexa-rpi/lib/libsnsr.a \
 -DSENSORY_KEY_WORD_DETECTOR_INCLUDE_DIR=/home/pi/sdk-folder/third-party/alexa-rpi/include \

and to start the alexa app i've used
 cd $HOME/sdk_folder/ss-build
 PA_ALSA_PLUGHW=1 ./modules/Alexa/SampleApp/src/SampleApp -C \
 $HOME/sdk_folder/sdk-build/Integration/AlexaClientSDKConfig.json -C \
 $HOME/sdk_folder/alexa-smart-screen-sdk/modules/GUI/config/SmartScreenSDKConfig.json  \
 -K ../third-party/alexa-rpi/models  -L DEBUG9

But the Wake Word not work!
What i do wrong?!

Hello,
I just set up Smart Screen SDK and wakeword works properly. Please reconfirm that your wakeword setup works with Alexa device sdk.
For further debug, please attach full logs after starting sample app, as well as your full cmake command for Smart Screen SDK

Hi,
I can confirm the same malfunction here. The configuration is exactly the same as jbllea. Wakeword does not work, but it does in the SampleApp of the alexa device sdk. First the cmake command, then the full DEBUG9 log after starting the sample app with the following command:
 cmake -DCMAKE_PREFIX_PATH=$HOME/sdk_folder/sdk-install \  -DWEBSOCKETPP_INCLUDE_DIR=$HOME/sdk_folder/third-party/websocketpp-0.8.1 \  -DDISABLE_WEBSOCKET_SSL=ON \  -DGSTREAMER_MEDIA_PLAYER=ON \  -DCMAKE_BUILD_TYPE=DEBUG \  -DPORTAUDIO=ON -DPORTAUDIO_LIB_PATH=$HOME/sdk_folder/third-party/portaudio/lib/.libs/libportaudio.a \  -DPORTAUDIO_INCLUDE_DIR=$HOME/sdk_folder/third-party/portaudio/include/ \  -DAPL_CORE=ON \  -DAPLCORE_INCLUDE_DIR=$HOME/sdk_folder/apl-core-library/aplcore/include \  -DAPLCORE_LIB_DIR=$HOME/sdk_folder/apl-core-library/build/aplcore \  -DYOGA_INCLUDE_DIR=$HOME/sdk_folder/apl-core-library/build/yoga-prefix/src/yoga \  -DYOGA_LIB_DIR=$HOME/sdk_folder/apl-core-library/build/lib \   ../alexa-smart-screen-sdk
 PA_ALSA_PLUGHW=1 ./modules/Alexa/SampleApp/src/SampleApp -C \  $HOME/sdk_folder/sdk-build/Integration/AlexaClientSDKConfig.json -C \  $HOME/sdk_folder/alexa-smart-screen-sdk/modules/GUI/config/SmartScreenSDKConfig.json \  -K $HOME/sdk_folder/third-party/alexa-rpi/models -L DEBUG9
`SmartScreenSDKVersion 2.2
configFile /home/pi/sdk_folder/sdk-build/Integration/AlexaClientSDKConfig.json
configFile /home/pi/sdk_folder/alexa-smart-screen-sdk/modules/GUI/config/SmartScreenSDKConfig.json
Running app with log level: DEBUG9
2020-07-19 22:30:03.891 [  1] I AlexaClientSdkInit:initialize:sdkversion=1.20.0
2020-07-19 22:30:03.895 [  1] 0 ConfigurationNode:mergeDocument:reason=objectsMerged,path=root.sampleApp
2020-07-19 22:30:03.896 [  1] 0 ConfigurationNode:initializeSuccess
2020-07-19 22:30:03.900 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2020-07-19 22:30:03.900 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2020-07-19 22:30:03.901 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating Equalizer configuration
2020-07-19 22:30:03.901 [  1] 1 InMemoryEqualizerConfiguration:validateConfiguration::Validating default Equalizer state
2020-07-19 22:30:03.903 [  1] 9 MediaPlayer:createCalled:name=SpeakMediaPlayer
2020-07-19 22:30:03.969 [  1] 9 MediaPlayer:noOutputConversion:name=SpeakMediaPlayer
2020-07-19 22:30:03.971 [  1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2020-07-19 22:30:03.973 [  1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2020-07-19 22:30:03.975 [  1] 9 MediaPlayer:createCalled:name=AudioMediaPlayer
2020-07-19 22:30:03.976 [  1] 9 MediaPlayer:noOutputConversion:name=AudioMediaPlayer
2020-07-19 22:30:03.978 [  1] 9 MediaPlayer:createCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:03.979 [  1] 9 MediaPlayer:noOutputConversion:name=NotificationsMediaPlayer
2020-07-19 22:30:03.981 [  1] 9 MediaPlayer:createCalled:name=BluetoothMediaPlayer
2020-07-19 22:30:03.982 [  1] 9 MediaPlayer:noOutputConversion:name=BluetoothMediaPlayer
2020-07-19 22:30:03.983 [  1] 9 MediaPlayer:createCalled:name=RingtoneMediaPlayer
2020-07-19 22:30:03.984 [  1] 9 MediaPlayer:noOutputConversion:name=RingtoneMediaPlayer
2020-07-19 22:30:03.986 [  1] 9 MediaPlayer:createCalled:name=AlertsMediaPlayer
2020-07-19 22:30:03.986 [  1] 9 MediaPlayer:noOutputConversion:name=AlertsMediaPlayer
2020-07-19 22:30:03.988 [  1] 9 MediaPlayer:createCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:03.989 [  1] 9 MediaPlayer:noOutputConversion:name=SystemSoundMediaPlayer
2020-07-19 22:30:03.991 [  1] 5 SQLiteDeviceSettingStorage:create
2020-07-19 22:30:03.991 [  1] 5 SQLiteBluetoothStorage:create
2020-07-19 22:30:03.991 [  1] I LocaleAssetsManager:initialize:supportedWakeWords=NONE
2020-07-19 22:30:03.992 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.992 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.992 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.993 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.994 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.994 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.994 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.994 [  1] 9 WebSocket:AccessLog
2020-07-19 22:30:03.996 [  1] 3 GUIClient:getAPLMaxVersionFromStorage:APLMaxVersion=1.3
2020-07-19 22:30:03.999 [  1] 3 GUIClient:setAplClientBridge
2020-07-19 22:30:03.999 [  1] 9 ThreadPool:obtainWorker:created=0,obtained=0,releasedToPool=0,releasedFromPool=0,outstanding=0
2020-07-19 22:30:04.000 [  1] I DeviceInfo:create:result=skipDeviceType,key=deviceType
2020-07-19 22:30:04.000 [  1] I DeviceInfo:create:result=skipFriendlyName,key=friendlyName
2020-07-19 22:30:04.000 [  1] I DeviceInfo:create:result=skipRegistrationKey,key=registrationKey
2020-07-19 22:30:04.000 [  1] I DeviceInfo:create:result=skipProductIdKey,key=productIdKey
2020-07-19 22:30:04.000 [  2] 9 TaskThread:startThread:moniker=  2,duration=954
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegate:create
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegate:CBLAuthDelegate
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegate:init
2020-07-19 22:30:04.001 [  3] 3 GUIClient:serverThread
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegateConfiguration:create
2020-07-19 22:30:04.001 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegateConfiguration:init
2020-07-19 22:30:04.001 [  1] 5 CBLAuthDelegateConfiguration:initScopeData
2020-07-19 22:30:04.002 [  1] 9 CBLAuthDelegateConfiguration:initScopeDataSucceeded
2020-07-19 22:30:04.002 [  1] 5 SQLiteCBLAuthDelegateStorage:open
2020-07-19 22:30:04.002 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:04.003 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:04.003 [  1] 5 SQLiteCapabilitiesDelegateStorage:create
2020-07-19 22:30:04.003 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:04.003 [  1] 5 SQLiteCapabilitiesDelegateStorage:open
2020-07-19 22:30:04.004 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0x1fa13d8
2020-07-19 22:30:04.004 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x1fa13dc
2020-07-19 22:30:04.004 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:04.005 [  1] 9 ThreadPool:obtainWorker:created=1,obtained=1,releasedToPool=0,releasedFromPool=0,outstanding=1
2020-07-19 22:30:04.005 [  1] 5 InternetConnectionMonitor:startMonitoring
2020-07-19 22:30:04.005 [  3] I WebSocketServer:Listening for websocket connections:interface=<IP_ADDRESS>,port=8933
2020-07-19 22:30:04.006 [  5] 5 InternetConnectionMonitor:testConnection
2020-07-19 22:30:04.006 [  5] 9 HTTPContentFetcherFactory:create::Creating a new http content fetcher
2020-07-19 22:30:04.006 [  1] 5 AVSGatewayManager:create
2020-07-19 22:30:04.006 [  5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=INITIALIZED,newState=FETCHING_HEADER:State transition
2020-07-19 22:30:04.006 [  6] 5 CBLAuthDelegate:handleAuthorizationFlow
2020-07-19 22:30:04.007 [  6] 5 CBLAuthDelegate:handleStarting
2020-07-19 22:30:04.007 [  6] 5 SQLiteCBLAuthDelegateStorage:getRefreshToken
2020-07-19 22:30:04.007 [  1] 5 AVSGatewayManager:init:avsGateway=https://bob-dispatch-prod-eu.amazon.com
2020-07-19 22:30:04.007 [  1] 5 SynchronizeStateSenderFactory:create
2020-07-19 22:30:04.008 [  6] 5 CBLAuthDelegate:handleRefreshingToken
2020-07-19 22:30:04.008 [  6] 5 CBLAuthDelegate:requestRefresh
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread::start
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.010 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  4] 9 TaskThread:startThread:moniker=  4,duration=6079
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.011 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.012 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.013 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.014 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.015 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.016 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.017 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.018 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.019 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.020 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.021 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.053 [  1] 0 PortAudioMicrophoneWrapper:startStreamingMicrophoneData
2020-07-19 22:30:04.056 [  1] 9 ThreadPool:obtainWorker:created=2,obtained=2,releasedToPool=0,releasedFromPool=0,outstanding=2
2020-07-19 22:30:04.056 [  8] 9 TaskThread:startThread:moniker=  8,duration=433
2020-07-19 22:30:04.057 [  1] 5 AVSConnectionManager:create::Subscribing to InternetConnectionMonitor Callbacks
2020-07-19 22:30:04.057 [  1] 5 AVSConnectionManager:onConnectionStatusChanged:connected=false,isEnabled=false
2020-07-19 22:30:04.058 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.059 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.061 [  9] 9 DirectiveProcessor:processingLoop
2020-07-19 22:30:04.062 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x211681c
2020-07-19 22:30:04.062 [  1] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=UNINITIALIZED,error=UNINITIALIZED,callback=true
2020-07-19 22:30:04.062 [  1] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged
2020-07-19 22:30:04.063 [  1] 9 ThreadPool:obtainWorker:created=3,obtained=3,releasedToPool=0,releasedFromPool=0,outstanding=3
2020-07-19 22:30:04.063 [  a] 9 TaskThread:startThread:moniker=  a,duration=346
2020-07-19 22:30:04.063 [  1] 5 SQLiteDeviceSettingStorage:open
2020-07-19 22:30:04.065 [  1] 5 DNDSettingProtocol:create:settingName=enabled
2020-07-19 22:30:04.065 [  1] 5 DNDSettingProtocol:restoreValue:setting=Alexa.DoNotDisturb::enabled
2020-07-19 22:30:04.065 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alexa.DoNotDisturb::enabled
2020-07-19 22:30:04.065 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.066 [  1] I Setting:handleSetValue:value=false3990065800
2020-07-19 22:30:04.067 [  1] 5 SharedAVSSettingProtocol:create:settingName=alarmVolumeRamp
2020-07-19 22:30:04.068 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=Alerts::alarmVolumeRamp
2020-07-19 22:30:04.068 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=Alerts::alarmVolumeRamp
2020-07-19 22:30:04.068 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.069 [  1] I Setting:handleSetValue:value="NONE"3990065800
2020-07-19 22:30:04.069 [  1] 5 SharedAVSSettingProtocol:create:settingName=wakeWordConfirmation
2020-07-19 22:30:04.069 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::wakeWordConfirmation
2020-07-19 22:30:04.069 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::wakeWordConfirmation
2020-07-19 22:30:04.070 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.070 [  1] I Setting:handleSetValue:value="NONE"3990065800
2020-07-19 22:30:04.070 [  1] 5 SharedAVSSettingProtocol:create:settingName=speechConfirmation
2020-07-19 22:30:04.071 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=SpeechRecognizer::speechConfirmation
2020-07-19 22:30:04.071 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::speechConfirmation
2020-07-19 22:30:04.071 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.071 [  1] I Setting:handleSetValue:value="NONE"3990065800
2020-07-19 22:30:04.072 [  1] 5 SharedAVSSettingProtocol:create:settingName=timeZone
2020-07-19 22:30:04.072 [  1] 5 SharedAVSSettingProtocol:restoreValue:setting=System::timeZone
2020-07-19 22:30:04.072 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System::timeZone
2020-07-19 22:30:04.073 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.073 [  1] I Setting:handleSetValue:value="America/Vancouver"3990065800
2020-07-19 22:30:04.073 [  1] 5 LocaleWakeWordsSetting:create:settingName=LocaleWakeWords
2020-07-19 22:30:04.073 [  1] 5 SQLiteDeviceSettingStorage:loadSetting:key=System.locales
2020-07-19 22:30:04.074 [  1] 5 SQLiteDeviceSettingStorage:loadSetting
2020-07-19 22:30:04.074 [  1] 2 LocaleWakeWordsSetting:restoreInitialValue:wakeWords=[],locale=["en-US"]
2020-07-19 22:30:04.074 [  1] 9 ThreadPool:obtainWorker:created=4,obtained=4,releasedToPool=0,releasedFromPool=0,outstanding=4
2020-07-19 22:30:04.076 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.078 [  b] 9 TaskThread:startThread:moniker=  b,duration=3974
2020-07-19 22:30:04.078 [  b] I LocaleAssetsManager:changeAssets:Locale=["en-US"],WakeWords=[]
2020-07-19 22:30:04.079 [  1] 9 MediaPlayer:addObserverCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.080 [  c] 0 MediaPlayer:handleAddObserverCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.082 [  8] 9 ThreadPool:obtainWorker:created=5,obtained=5,releasedToPool=0,releasedFromPool=0,outstanding=5
2020-07-19 22:30:04.083 [  1] 9 MediaPlayer:addObserverCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.083 [  e] 0 MediaPlayer:handleAddObserverCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.083 [  d] 9 TaskThread:startThread:moniker=  d,duration=1427
2020-07-19 22:30:04.083 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.084 [  8] 9 ThreadPool:obtainWorker:created=6,obtained=6,releasedToPool=0,releasedFromPool=0,outstanding=6
2020-07-19 22:30:04.084 [  1] 9 SpeechSynthesizer:addObserver:observer=0x20fd1e4
2020-07-19 22:30:04.085 [  1] 9 PlaybackRouter:create::called
2020-07-19 22:30:04.085 [  f] 9 TaskThread:startThread:moniker=  f,duration=1168
2020-07-19 22:30:04.085 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.085 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.085 [  e] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.086 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.086 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.086 [  c] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.086 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.086 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.086 [ 10] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.087 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.087 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.087 [ 11] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.087 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.087 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=BluetoothMediaPlayer
2020-07-19 22:30:04.087 [ 12] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=BluetoothMediaPlayer
2020-07-19 22:30:04.087 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.087 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer
2020-07-19 22:30:04.087 [ 13] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer
2020-07-19 22:30:04.088 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.088 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.088 [ 14] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.088 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.088 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.088 [ 15] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.088 [  1] I SpeakerManager:mapCreated:numSpeakerVolume=6,numAlertsVolume=2
2020-07-19 22:30:04.089 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.089 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.089 [  e] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SpeakMediaPlayer
2020-07-19 22:30:04.089 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.089 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.090 [  c] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=SystemSoundMediaPlayer
2020-07-19 22:30:04.090 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.090 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.090 [ 10] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.090 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.090 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.090 [ 11] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AudioMediaPlayer
2020-07-19 22:30:04.090 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.090 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=BluetoothMediaPlayer
2020-07-19 22:30:04.090 [ 12] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=BluetoothMediaPlayer
2020-07-19 22:30:04.090 [  1] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.090 [  1] 9 MediaPlayer:getSpeakerSettingsCalled:name=RingtoneMediaPlayer
2020-07-19 22:30:04.090 [ 13] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=RingtoneMediaPlayer
2020-07-19 22:30:04.090 [  1] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2020-07-19 22:30:04.091 [  1] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2020-07-19 22:30:04.091 [  1] 9 SpeakerManager:updateContextManagerCalled:speakerType=AVS_SPEAKER_VOLUME
2020-07-19 22:30:04.091 [  1] 5 ContextManager:setState
2020-07-19 22:30:04.091 [  1] 9 ThreadPool:obtainWorker:created=7,obtained=7,releasedToPool=0,releasedFromPool=0,outstanding=7
2020-07-19 22:30:04.091 [ 16] 9 TaskThread:startThread:moniker= 16,duration=205
2020-07-19 22:30:04.092 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.093 [  1] 5 Renderer:resetSourceId
2020-07-19 22:30:04.093 [  1] 9 MediaPlayer:addObserverCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.093 [ 14] 0 MediaPlayer:handleAddObserverCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.095 [  1] 9 AlertScheduler:setTimerForNextAlertLocked
2020-07-19 22:30:04.095 [  1] 9 AlertScheduler:executeScheduleNextAlertForRendering::no work to do.
2020-07-19 22:30:04.095 [  1] 9 SpeakerManager:getSpeakerSettingsCalled
2020-07-19 22:30:04.095 [  1] 9 ThreadPool:obtainWorker:created=8,obtained=8,releasedToPool=0,releasedFromPool=0,outstanding=8
2020-07-19 22:30:04.095 [ 17] 9 TaskThread:startThread:moniker= 17,duration=291
2020-07-19 22:30:04.095 [ 17] 9 SpeakerManager:executeGetSpeakerSettingsCalled
2020-07-19 22:30:04.095 [ 17] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.095 [ 17] 9 MediaPlayer:getSpeakerSettingsCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.096 [ 14] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=AlertsMediaPlayer
2020-07-19 22:30:04.096 [ 17] 0 ChannelVolumeManager:getSpeakerSettings
2020-07-19 22:30:04.096 [ 17] 9 MediaPlayer:getSpeakerSettingsCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.096 [ 15] 9 MediaPlayer:handleGetSpeakerSettingsCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.096 [ 17] 9 SpeakerManager:validateSpeakerSettingsConsistencyResult:consistent=true
2020-07-19 22:30:04.096 [ 17] 9 SpeakerManager:validateSpeakerSettings:volume=100,mute=false
2020-07-19 22:30:04.096 [  1] 5 ContextManager:setState
2020-07-19 22:30:04.097 [  1] 9 ThreadPool:obtainWorker:created=9,obtained=9,releasedToPool=0,releasedFromPool=0,outstanding=9
2020-07-19 22:30:04.097 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2020-07-19 22:30:04.097 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x213649c
2020-07-19 22:30:04.097 [  1] 5 NotificationRenderer:create
2020-07-19 22:30:04.098 [  1] 9 MediaPlayer:addObserverCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.098 [ 15] 0 MediaPlayer:handleAddObserverCalled:name=NotificationsMediaPlayer
2020-07-19 22:30:04.098 [ 18] 9 TaskThread:startThread:moniker= 18,duration=1310
2020-07-19 22:30:04.098 [ 18] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=DISCONNECTED,reason=ACL_CLIENT_REQUEST
2020-07-19 22:30:04.099 [  1] 5 NotificationsCapabilityAgent:init
2020-07-19 22:30:04.099 [  1] 5 NotificationRenderer:addObserver
2020-07-19 22:30:04.099 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.100 [  1] 9 ThreadPool:obtainWorker:created=10,obtained=10,releasedToPool=0,releasedFromPool=0,outstanding=10
2020-07-19 22:30:04.100 [  1] 5 InteractionModel:InteractionModelCapabilityAgent
2020-07-19 22:30:04.101 [ 19] 9 TaskThread:startThread:moniker= 19,duration=676
2020-07-19 22:30:04.101 [ 19] 5 NotificationsCapabilityAgent:executeInit
2020-07-19 22:30:04.101 [ 19] 5 NotificationsCapabilityAgent:notifyObserversOfIndicatorState:indicatorState=0
2020-07-19 22:30:04.101 [ 19] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=false,stateRequestToken=0
2020-07-19 22:30:04.102 [ 19] 5 ContextManager:setState
2020-07-19 22:30:04.102 [  1] 5 ExternalMediaPlayer:init
2020-07-19 22:30:04.102 [  1] 5 AuthorizedSender:create
2020-07-19 22:30:04.102 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.102 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.103 [  1] 0 ExternalMediaPlayer:createAdapters
2020-07-19 22:30:04.103 [  1] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32
2020-07-19 22:30:04.104 [  1] 0 EventBuilder:buildJsonEventString:messageId=9c97cb6c-bf64-4c43-bd53-8132e3fee7af,namespace=ExternalMediaPlayer,name=ReportDiscoveredPlayers
2020-07-19 22:30:04.104 [  1] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"ExternalMediaPlayer","name":"ReportDiscoveredPlayers","messageId":"9c97cb6c-bf64-4c43-bd53-8132e3fee7af"},"payload":{"agent":"CQCAFYNYDC","players":[]}}}
2020-07-19 22:30:04.104 [  1] 9 ThreadPool:obtainWorker:created=11,obtained=11,releasedToPool=0,releasedFromPool=0,outstanding=11
2020-07-19 22:30:04.105 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.106 [  1] 0 AlexaPresentation:initialize::Proactive state report timer disabled
2020-07-19 22:30:04.106 [  1] 3 AlexaPresentation:create:visualStateProvider=On
2020-07-19 22:30:04.106 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.107 [  1] 1 AlexaPresentation:setAPLMaxVersion:APLVersion=1.3
2020-07-19 22:30:04.107 [  8] 5 AlexaPresentation:onDialogUXStateChanged:state=IDLE
2020-07-19 22:30:04.107 [  8] 9 ThreadPool:obtainWorker:created=12,obtained=12,releasedToPool=0,releasedFromPool=0,outstanding=12
2020-07-19 22:30:04.107 [ 1a] 9 TaskThread:startThread:moniker= 1a,duration=2956
2020-07-19 22:30:04.108 [ 1b] 9 TaskThread:startThread:moniker= 1b,duration=1255
2020-07-19 22:30:04.109 [  1] 5 ExternalMediaPlayer:setObserver
2020-07-19 22:30:04.109 [  1] 1 AudioPlayer:setObserver
2020-07-19 22:30:04.109 [  1] 9 ThreadPool:obtainWorker:created=13,obtained=13,releasedToPool=0,releasedFromPool=0,outstanding=13
2020-07-19 22:30:04.110 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=437
2020-07-19 22:30:04.110 [  1] 5 AlexaPresentation:addObserver
2020-07-19 22:30:04.110 [ 1b] 5 AlexaPresentation:addObserverInExecutor
2020-07-19 22:30:04.110 [  1] 9 VisualCharacteristics:getVisualCharacteristicsCapabilityConfiguration
2020-07-19 22:30:04.110 [  8] 5 TemplateRuntime:onDialogUXStateChanged:state=IDLE
2020-07-19 22:30:04.111 [  8] 9 ThreadPool:obtainWorker:created=14,obtained=14,releasedToPool=0,releasedFromPool=0,outstanding=14
2020-07-19 22:30:04.111 [  1] 5 ContextManager:addStateProvider
2020-07-19 22:30:04.112 [ 1d] 9 TaskThread:startThread:moniker= 1d,duration=1627
2020-07-19 22:30:04.113 [  1] 5 ContextManager:setState
2020-07-19 22:30:04.117 [  1] 5 SmartScreenClient:bluetoothCapabilityAgentDisabled:reason=nullBluetoothDeviceManager
2020-07-19 22:30:04.118 [  1] 5 ApiGateway:create
2020-07-19 22:30:04.118 [  1] 5 AlexaInterfaceCapabilityAgent:create
2020-07-19 22:30:04.121 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetLocales,handler=0x1fa6260,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.122 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=SetTimeZone,handler=0x1f73cb8,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.122 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ReportState,handler=0x1f84e00,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.122 [  1] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=System,name=ResetUserInactivity,handler=0x1ff969c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.123 [  1] 9 SpeakerManager:addSpeakerManagerObserverCalled
2020-07-19 22:30:04.123 [  1] 9 SpeakerManager:addSpeakerManagerObserver:observer=0x1fa13e8
2020-07-19 22:30:04.123 [  1] 5 NotificationsCapabilityAgent:addObserver
2020-07-19 22:30:04.123 [  1] 5 TemplateRuntime:addObserver
2020-07-19 22:30:04.124 [  1] 5 AlexaPresentation:addObserver
2020-07-19 22:30:04.124 [ 1d] 5 TemplateRuntime:addObserverInExecutor
2020-07-19 22:30:04.124 [ 1b] 5 AlexaPresentation:addObserverInExecutor
2020-07-19 22:30:04.124 [  1] 1 AudioPlayer:addObserver
2020-07-19 22:30:04.124 [  1] 9 ThreadPool:obtainWorker:created=15,obtained=15,releasedToPool=0,releasedFromPool=0,outstanding=15
#################################################
NOTIFICATION INDICATOR STATE: OFF
#################################################
2020-07-19 22:30:04.124 [  1] 3 GUIClient:setGUIManager
2020-07-19 22:30:04.125 [  1] 5 CBLAuthDelegate:addAuthObserver:observer=0x20b63c8
2020-07-19 22:30:04.125 [  2] 9 ThreadPool:obtainWorker:created=16,obtained=16,releasedToPool=0,releasedFromPool=0,outstanding=16
2020-07-19 22:30:04.125 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x20b63cc
2020-07-19 22:30:04.125 [  1] 5 CapabilitiesDelegate:addCapabilitiesObserver:observer=0x20fcf00
2020-07-19 22:30:04.126 [  1] E Endpoint:addCapabilityAgentFailed:reason=capabilityAlreadyExists,interface=InteractionModel,type=AlexaInterface,instance=
2020-07-19 22:30:04.126 [  1] 2 EndpointBuilder:buildImplementation:isDefault=true,#capabilities=24
2020-07-19 22:30:04.127 [  1] 5 EndpointRegistrationManager:registerEndpoint
2020-07-19 22:30:04.127 [  a] 5 EndpointRegistrationManager:executeRegisterEndpoint
2020-07-19 22:30:04.128 [  a] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=System
2020-07-19 22:30:04.125 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=1011
2020-07-19 22:30:04.128 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=2924
2020-07-19 22:30:04.128 [  a] 5 DoNotDisturbCapabilityAgent:getConfiguration
2020-07-19 22:30:04.129 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.DoNotDisturb,name=SetDoNotDisturb,handler=0x1ffda28,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.129 [  a] 5 EqualizerController:getConfiguration
2020-07-19 22:30:04.129 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=SetMode,handler=0x1ffcc0c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.130 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=SetBands,handler=0x1ffcc0c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.130 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=ResetBands,handler=0x1ffcc0c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.130 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=EqualizerController,name=AdjustBands,handler=0x1ffcc0c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.130 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=ClearIndicator,handler=0x213c71c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.131 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Notifications,name=SetIndicator,handler=0x213c71c,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.131 [  a] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=VisualActivityTracker
2020-07-19 22:30:04.131 [  a] 5 TemplateRuntime:getConfiguration
2020-07-19 22:30:04.131 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderPlayerInfo,handler=0x21440b0,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2020-07-19 22:30:04.132 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=TemplateRuntime,name=RenderTemplate,handler=0x21440b0,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2020-07-19 22:30:04.132 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Unfavorite,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.132 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=AdjustSeekPosition,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.133 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.SeekController,name=SetSeekPosition,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.133 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableShuffle,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.133 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableShuffle,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.133 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=DisableRepeat,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.133 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.FavoritesController,name=Favorite,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeatOne,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=StartOver,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Stop,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Previous,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Pause,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.134 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=FastForward,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.135 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Play,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.135 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Rewind,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.135 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Logout,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.135 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaylistController,name=EnableRepeat,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.135 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Login,handler=0x2140d50,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.138 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=Play,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.138 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.PlaybackController,name=Next,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.138 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=ExternalMediaPlayer,name=AuthorizeDiscoveredPlayers,handler=0x2140d50,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.139 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechSynthesizer,name=Speak,handler=0x2132f38,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:True
2020-07-19 22:30:04.139 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetMute,handler=0x2134a58,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.139 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=SetVolume,handler=0x2134a58,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.139 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Speaker,name=AdjustVolume,handler=0x2134a58,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.140 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetSpeechConfirmation,handler=0x2131b70,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.140 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetWakeWordConfirmation,handler=0x2131b70,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.140 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetEndOfSpeechOffset,handler=0x2131b70,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.140 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=SetWakeWords,handler=0x2131b70,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.140 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=StopCapture,handler=0x2131b70,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.141 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=SpeechRecognizer,name=ExpectSpeech,handler=0x2131b70,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:True
2020-07-19 22:30:04.141 [  a] 5 EndpointRegistrationManager:registerEndpoint:emptyHandler=AudioActivityTracker
2020-07-19 22:30:04.141 [  a] 5 AlexaPresentation:getConfiguration
2020-07-19 22:30:04.141 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.Presentation.APL,name=SendTokenListData,handler=0x2143230,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2020-07-19 22:30:04.141 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.Presentation.APL,name=UpdateIndexListData,handler=0x2143230,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2020-07-19 22:30:04.141 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.Presentation.APL,name=SendIndexListData,handler=0x2143230,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:False
2020-07-19 22:30:04.142 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.Presentation.APL,name=RenderDocument,handler=0x2143230,policy= Mediums:MEDIUM_VISUAL10 .isBlocking:True
2020-07-19 22:30:04.142 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.Presentation.APL,name=ExecuteCommands,handler=0x2143230,policy= Mediums:MEDIUMS_AUDIO_AND_VISUAL11 .isBlocking:True
2020-07-19 22:30:04.142 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=RequestProcessingCompleted,handler=0x2140ba0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.142 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=NewDialogRequest,handler=0x2140ba0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.142 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=InteractionModel,name=RequestProcessingStarted,handler=0x2140ba0,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlarmVolumeRamp,handler=0x2136430,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=AdjustVolume,handler=0x2136430,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetVolume,handler=0x2136430,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlerts,handler=0x2136430,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=SetAlert,handler=0x2136430,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alerts,name=DeleteAlert,handler=0x2136430,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.143 [  a] 5 ApiGateway:getConfiguration
2020-07-19 22:30:04.144 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa.ApiGateway,name=SetGateway,handler=0x1f6ca60,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.144 [  a] 5 AlexaInterfaceCapabilityAgent:getConfiguration
2020-07-19 22:30:04.144 [  a] 5 AlexaInterfaceCapabilityAgent:registeringEventProcessedDirective:reason=defaultEndpoint
2020-07-19 22:30:04.144 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa,name=ReportState,handler=0x2172948,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.144 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=Alexa,name=EventProcessed,handler=0x2172948,policy= Mediums:MEDIUMS_NONE00 .isBlocking:False
2020-07-19 22:30:04.145 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=UpdateProgressReportInterval,handler=0x2135610,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.145 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=ClearQueue,handler=0x2135610,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.145 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Play,handler=0x2135610,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.145 [  a] 9 DirectiveRouter:addDirectiveHandlers:action=added,namespace=AudioPlayer,name=Stop,handler=0x2135610,policy= Mediums:MEDIUM_AUDIO01 .isBlocking:False
2020-07-19 22:30:04.145 [  a] 5 CapabilitiesDelegate:addOrUpdateEndpoint
2020-07-19 22:30:04.147 [  a] 9 ThreadPool:obtainWorker:created=17,obtained=17,releasedToPool=0,releasedFromPool=0,outstanding=17
2020-07-19 22:30:04.147 [ 20] 9 TaskThread:startThread:moniker= 20,duration=219
2020-07-19 22:30:04.147 [  a] 2 EndpointRegistrationManager:executeRegisterEndpoint:result=finished
2020-07-19 22:30:04.147 [ 20] 5 CapabilitiesDelegate:executeSendPendingEndpoints
2020-07-19 22:30:04.147 [ 20] 5 CapabilitiesDelegate:executeSendPendingEndpoints:Deferred=Not connected
2020-07-19 22:30:04.147 [  1] 5 AVSGatewayManager:setAVSGatewayAssigner
2020-07-19 22:30:04.147 [  1] I MessageRouter:setAVSGateway:avsGateway=https://bob-dispatch-prod-eu.amazon.com
2020-07-19 22:30:04.147 [  1] 5 AVSConnectionManager:enable
2020-07-19 22:30:04.147 [  1] I MessageRouter:enable
2020-07-19 22:30:04.147 [  1] I MessageRouter:setConnectionStatusLocked:status=PENDING,reason=ACL_CLIENT_REQUEST
2020-07-19 22:30:04.147 [  1] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=PENDING
2020-07-19 22:30:04.148 [  1] 9 ThreadPool:obtainWorker:created=18,obtained=18,releasedToPool=0,releasedFromPool=0,outstanding=18
2020-07-19 22:30:04.148 [ 21] 9 TaskThread:startThread:moniker= 21,duration=182
2020-07-19 22:30:04.148 [  1] 5 LibcurlHTTP2ConnectionFactory:createHTTP2Connection
2020-07-19 22:30:04.148 [  1] 5 HTTP2Transport:create:authDelegate=0x1ff9250,avsGateway=https://bob-dispatch-prod-eu.amazon.com,http2Connection=0x1f74728,messageConsumer=0x1fe38f0,attachmentManager=0x2080928,transportObserver=0x1fe38ec,postConnectFactory=0x20beb08,sharedRequestQueue=0x1ff27ac
2020-07-19 22:30:04.149 [  1] I MessageRouter:createActiveTransportLocked:transport=0x1f5ec00,sizeOf m_transports=0
2020-07-19 22:30:04.149 [  1] I HTTP2Transport:connect:this=0x1f5ec00
2020-07-19 22:30:04.149 [  1] I HTTP2Transport:setStateLocked:this=0x1f5ec00,currentState=INIT,newState=AUTHORIZING,changedReason=ACL_CLIENT_REQUEST
2020-07-19 22:30:04.149 [  1] I MessageRouter:setAsActiveTransport:transport=0x1f5ec00,sizeOf m_transports=1
2020-07-19 22:30:04.149 [  1] 3 GUIClient:run
2020-07-19 22:30:04.149 [ 22] 5 LibcurlHTTP2Connection:networkLoop
2020-07-19 22:30:04.149 [  2] 9 WebSocketServer:writeMessageBegin
#######################################
ALEXA STATE: CONNECTING
#######################################
2020-07-19 22:30:04.150 [ 23] 7 HTTP2Transport:mainLoop:this=0x1f5ec00
2020-07-19 22:30:04.150 [  2] E WebSocketServer:server::send:errorCode=15,errorCategory=websocketpp
2020-07-19 22:30:04.150 [  2] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:04.150 [ 18] 1 AlertsCapabilityAgent:executeOnConnectionStatusChanged:status=PENDING,reason=ACL_CLIENT_REQUEST
2020-07-19 22:30:04.150 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.150 [ 23] 5 SynchronizeStateSenderFactory:createPostConnectOperation
2020-07-19 22:30:04.151 [ 23] 5 AVSGatewayManager:createPostConnectOperation
2020-07-19 22:30:04.151 [ 23] 5 AVSGatewayManager:createPostConnectOperation::Gateway already verified, skipping gateway verification step
2020-07-19 22:30:04.151 [ 23] 5 CapabilitiesDelegate:createPostConnectOperation
2020-07-19 22:30:04.151 [ 23] 5 CapabilitiesDelegate:resetDiscoveryEventSender
2020-07-19 22:30:04.151 [ 23] 5 CapabilitiesDelegate:moveInFlightEndpointsToPendingLocked
2020-07-19 22:30:04.151 [ 23] 5 SQLiteCapabilitiesDelegateStorage:load
2020-07-19 22:30:04.152 [ 23] 5 CapabilitiesDelegate:createPostConnectOperation:num endpoints stored=1
2020-07-19 22:30:04.152 [ 23] 5 CapabilitiesDelegate:filterUnchangedPendingAddOrUpdateEndpointsLocked
2020-07-19 22:30:04.152 [ 23] 9 CapabilitiesDelegate:filterUnchangedPendingAddOrUpdateEndpointsLocked:step=endpoint not be included in addOrUpdateReport
2020-07-19 22:30:04.152 [ 23] 5 CapabilitiesDelegate:addStaleEndpointsToPendingDeleteLocked
2020-07-19 22:30:04.152 [ 23] 5 CapabilitiesDelegate:setCapabilitiesState:newCapabilitiesState=SUCCESS
2020-07-19 22:30:04.152 [ 23] 9 CapabilitiesDelegate:callingOnCapabilitiesStateChange:state=SUCCESS,error=SUCCESS
2020-07-19 22:30:04.152 [ 23] 5 AVSConnectionManager:enable
2020-07-19 22:30:04.152 [ 23] I MessageRouter:enable
2020-07-19 22:30:04.152 [ 23] I MessageRouter:enable::already enabled
2020-07-19 22:30:04.152 [ 23] 5 EndpointRegistrationManager:onCapabilitiesStateChange:state=SUCCESS,error=SUCCESS,callback=true
2020-07-19 22:30:04.152 [ 23] 5 EndpointRegistrationManager:onCapabilityRegistrationStatusChanged
######################################################
Successfully registered 1 endpoint(s)!
######################################################
2020-07-19 22:30:04.153 [ 23] 5 CapabilitiesDelegate:createPostConnectOperation::No change in Capabilities, skipping post connect step
2020-07-19 22:30:04.153 [ 23] 5 PostConnectSequencer:doPostConnect
2020-07-19 22:30:04.153 [  a] 9 EndpointRegistrationManager:updateAddedOrUpdatedEndpoints:result=success
2020-07-19 22:30:04.153 [ 23] I HTTP2Transport:handleAuthorizing:this=0x1f5ec00
2020-07-19 22:30:04.153 [ 24] 5 PostConnectSequencer:mainLoop
2020-07-19 22:30:04.153 [ 23] 5 CBLAuthDelegate:addAuthObserver:observer=0x1f5ec48
2020-07-19 22:30:04.153 [ 24] 5 PostConnectSynchronizeStateSender:performOperation
2020-07-19 22:30:04.153 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext
2020-07-19 22:30:04.153 [ 23] I HTTP2Transport:onAuthStateChange:this=0x1f5ec00,newState=UNINITIALIZED,error=SUCCESS
2020-07-19 22:30:04.153 [ 24] 5 ContextManager:getContext
2020-07-19 22:30:04.154 [ 24] 5 ContextManager:getContextInternal
2020-07-19 22:30:04.154 [ 16] 9 ThreadPool:obtainWorker:created=19,obtained=19,releasedToPool=0,releasedFromPool=0,outstanding=19
2020-07-19 22:30:04.154 [ 25] 9 TaskThread:startThread:moniker= 25,duration=221
2020-07-19 22:30:04.154 [ 16] 9 ThreadPool:obtainWorker:created=20,obtained=20,releasedToPool=0,releasedFromPool=0,outstanding=20
2020-07-19 22:30:04.155 [ 26] 9 TaskThread:startThread:moniker= 26,duration=169
2020-07-19 22:30:04.155 [ 26] 5 ContextManager:setState
2020-07-19 22:30:04.155 [ 16] 9 ThreadPool:obtainWorker:created=21,obtained=21,releasedToPool=0,releasedFromPool=0,outstanding=21
2020-07-19 22:30:04.155 [ 27] 9 TaskThread:startThread:moniker= 27,duration=177
2020-07-19 22:30:04.155 [ 16] 9 SpeechSynthesizer:provideState:token=1
2020-07-19 22:30:04.155 [ 27] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2020-07-19 22:30:04.155 [  f] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=1,state=FINISHED
2020-07-19 22:30:04.155 [ 27] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers
2020-07-19 22:30:04.156 [ 1b] 3 AlexaPresentation:executeProvideState:token=1
2020-07-19 22:30:04.156 [ 1b] 5 ContextManager:setState
2020-07-19 22:30:04.156 [ 27] 5 ContextManager:setState
2020-07-19 22:30:04.155 [ 16] 5 AudioActivityTracker:provideState
2020-07-19 22:30:04.156 [  f] 5 ContextManager:setState
2020-07-19 22:30:04.156 [ 16] 9 ThreadPool:obtainWorker:created=22,obtained=22,releasedToPool=0,releasedFromPool=0,outstanding=22
2020-07-19 22:30:04.156 [ 28] 9 TaskThread:startThread:moniker= 28,duration=181
2020-07-19 22:30:04.156 [ 16] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=1
2020-07-19 22:30:04.156 [ 28] 5 AudioActivityTracker:executeProvideState
2020-07-19 22:30:04.156 [ 16] 5 VisualActivityTracker:provideState
2020-07-19 22:30:04.156 [ 19] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=1
2020-07-19 22:30:04.156 [ 16] 9 ThreadPool:obtainWorker:created=23,obtained=23,releasedToPool=0,releasedFromPool=0,outstanding=23
2020-07-19 22:30:04.156 [ 29] 9 TaskThread:startThread:moniker= 29,duration=192
2020-07-19 22:30:04.156 [ 29] 5 VisualActivityTracker:executeProvideState
2020-07-19 22:30:04.156 [ 19] 5 ContextManager:setState
2020-07-19 22:30:04.156 [ 16] 0 AudioPlayer:provideState:stateRequestToken=1
2020-07-19 22:30:04.156 [ 28] 5 ContextManager:setState
2020-07-19 22:30:04.157 [ 29] 5 ContextManager:setState
2020-07-19 22:30:04.157 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=9
2020-07-19 22:30:04.157 [ 1c] 0 AudioPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2020-07-19 22:30:04.157 [ 1c] 5 ContextManager:setState
2020-07-19 22:30:04.157 [ 27] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=1
2020-07-19 22:30:04.157 [ 16] E ContextManager:setStateFailed:missingState=Alexa.Display.Window::WindowState
2020-07-19 22:30:04.157 [ 16] 5 ContextManager:getContextFailureCallbackLocked:token=1
2020-07-19 22:30:04.157 [ 27] 5 ContextManager:setState
2020-07-19 22:30:04.158 [ 29] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:04.158 [ 16] E PostConnectSynchronizeStateSender:onContextFailure:reason=BUILD_CONTEXT_ERROR
2020-07-19 22:30:04.158 [ 28] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:04.158 [ 27] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:04.158 [ 27] E ExternalMediaPlayer:executeProvideState:reason=contextManagerSetStateFailedForEMPState
2020-07-19 22:30:04.158 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2020-07-19 22:30:04.159 [ 16] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=1
2020-07-19 22:30:04.159 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2020-07-19 22:30:04.159 [ 16] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=1
2020-07-19 22:30:04.159 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2020-07-19 22:30:04.159 [ 16] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=1
2020-07-19 22:30:04.159 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2020-07-19 22:30:04.159 [ 16] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=1
2020-07-19 22:30:04.159 [ 16] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=1
2020-07-19 22:30:04.159 [ 16] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=1
2020-07-19 22:30:04.247 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.254 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.254 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback:type=content-length,length=419
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:headerCallback::CALLED
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:transferThread:numTransfersLeft=1
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:bodyCallback::End of header found.
2020-07-19 22:30:04.255 [  7] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_HEADER,newState=HEADER_DONE:State transition
2020-07-19 22:30:04.255 [  5] 9 InternetConnectionMonitor:testConnection:contentLength=419
2020-07-19 22:30:04.255 [  5] 9 LibCurlHttpContentFetcher:stateTransition:oldState=HEADER_DONE,newState=FETCHING_BODY:State transition
2020-07-19 22:30:04.355 [  7] 9 LibCurlHttpContentFetcher:bodyCallback:totalContentReceived=419,contentLength=419,currentContentReceived=419,remaining=0,totalBytesWritten=419
2020-07-19 22:30:04.356 [  7] 9 LibCurlHttpContentFetcher:transferThread::end
2020-07-19 22:30:04.356 [  7] 9 LibCurlHttpContentFetcher:stateTransition:oldState=FETCHING_BODY,newState=BODY_DONE:State transition
2020-07-19 22:30:04.456 [  5] 9 InternetConnectionMonitor:testConnection::alreadyReadAllBytes
2020-07-19 22:30:04.456 [  5] 9 InternetConnectionMonitor:testConnection::Finished reading
2020-07-19 22:30:04.456 [  5] 5 InternetConnectionMonitor:updateConnectionStatus:connected=true
2020-07-19 22:30:04.456 [  5] 5 InternetConnectionMonitor:notifyObserversLocked
2020-07-19 22:30:04.456 [  5] 5 AVSConnectionManager:onConnectionStatusChanged:connected=true,isEnabled=true
2020-07-19 22:30:04.457 [  5] I MessageRouter:onWakeConnectionRetry
2020-07-19 22:30:04.457 [  5] I MessageRouter:onWakeConnectionRetry:m_activeTransport=0x1f5ec00
2020-07-19 22:30:04.457 [  5] I HTTP2Transport:onWakeConnectionRetry:this=0x1f5ec00
2020-07-19 22:30:04.457 [  5] 9 LibCurlHttpContentFetcher:~LibCurlHttpContentFetcher
2020-07-19 22:30:04.606 [  6] 5 HttpPost:doPostSucceeded:code=200
2020-07-19 22:30:04.607 [  6] 5 CBLAuthDelegate:receiveTokenResponse:code=200
2020-07-19 22:30:04.607 [  6] 5 CBLAuthDelegate:mapHTTPStatusToError:code=200,error=SUCCESS
2020-07-19 22:30:04.608 [  6] 5 CBLAuthDelegate:setAuthError:authError=SUCCESS
2020-07-19 22:30:04.608 [  6] 5 CBLAuthDelegate:setRefreshToken
2020-07-19 22:30:04.608 [  6] 5 SQLiteCBLAuthDelegateStorage:setRefreshToken
2020-07-19 22:30:04.624 [  6] 5 CBLAuthDelegate:setAuthState:newAuthState=REFRESHED
2020-07-19 22:30:04.624 [  6] 9 CBLAuthDelegate:callingOnAuthStateChange:state=REFRESHED,error=SUCCESS
2020-07-19 22:30:04.624 [  6] I HTTP2Transport:onAuthStateChange:this=0x1f5ec00,newState=REFRESHED,error=SUCCESS
2020-07-19 22:30:04.624 [  6] I HTTP2Transport:setStateLocked:this=0x1f5ec00,currentState=AUTHORIZING,newState=CONNECTING,changedReason=SUCCESS
2020-07-19 22:30:04.624 [ 23] I HTTP2Transport:handleConnecting:this=0x1f5ec00
###########################
Authorized!
###########################
2020-07-19 22:30:04.625 [  2] 9 WebSocketServer:writeMessageBegin
2020-07-19 22:30:04.625 [  2] E WebSocketServer:server::send:errorCode=15,errorCategory=websocketpp
2020-07-19 22:30:04.625 [  2] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:04.626 [ 23] 9 DownchannelHandler:create:context=0x1f5ec50
2020-07-19 22:30:04.626 [ 23] 5 ExchangeHandler:ExchangeHandler:context=0x1f5ec50
2020-07-19 22:30:04.626 [ 23] 9 DownchannelHandler:DownchannelHandler:context=0x1f5ec50
2020-07-19 22:30:04.626 [ 23] 9 MimeResponseSink:MimeResponseSink:handler=0xaf804018
2020-07-19 22:30:04.626 [ 23] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder
2020-07-19 22:30:04.627 [ 23] 7 HTTP2Transport:createAndSendRequest:this=0x1f5ec00,type=GET
2020-07-19 22:30:04.627 [ 23] 9 DownchannelHandler:getRequestHeaderLines
2020-07-19 22:30:04.627 [ 22] 9 LibcurlHTTP2Connection:insertActiveStream:handle=0xaf807b18,streamId=AVSDownChannel-1
2020-07-19 22:30:04.833 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=13,userData=0xaf8050d0
2020-07-19 22:30:04.833 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=200
2020-07-19 22:30:04.833 [ 22] 9 MimeResponseSink:onReceiveResponseCode:responseCode=200
2020-07-19 22:30:04.833 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] 5 DownchannelHandler:onReceiveResponseCode:responseCode=200
2020-07-19 22:30:04.833 [ 22] I HTTP2Transport:onDownchannelConnected:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] I HTTP2Transport:setStateLocked:this=0x1f5ec00,currentState=CONNECTING,newState=POST_CONNECTING,changedReason=SUCCESS
2020-07-19 22:30:04.833 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 200
2020-07-19 22:30:04.833 [ 23] I HTTP2Transport:handlePostConnecting:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 200
2020-07-19 22:30:04.833 [ 23] 7 HTTP2Transport:sendMessagesAndPings:this=0x1f5ec00,whileState=POST_CONNECTING
2020-07-19 22:30:04.833 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=64,userData=0xaf8050d0
2020-07-19 22:30:04.833 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-headers: authorization,x-amz-access-token
2020-07-19 22:30:04.833 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-headers: authorization,x-amz-access-token
2020-07-19 22:30:04.833 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=32,userData=0xaf8050d0
2020-07-19 22:30:04.833 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin: *
2020-07-19 22:30:04.833 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin: *
2020-07-19 22:30:04.833 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.833 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=81,userData=0xaf8050d0
2020-07-19 22:30:04.834 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=content-type: multipart/related; boundary=------abcde123; type=application/json
2020-07-19 22:30:04.834 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=content-type: multipart/related; boundary=------abcde123; type=application/json
2020-07-19 22:30:04.834 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.834 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=20,userData=0xaf8050d0
2020-07-19 22:30:04.834 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid:
2020-07-19 22:30:04.834 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid:
2020-07-19 22:30:04.834 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.834 [ 22] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid:
2020-07-19 22:30:04.834 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSDownChannel-1,size=1,nmemb=2,userData=0xaf8050d0
2020-07-19 22:30:04.834 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=
2020-07-19 22:30:04.834 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=
2020-07-19 22:30:04.834 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:04.834 [ 22] 9 LibcurlHTTP2Request:writeCallback:id=AVSDownChannel-1,size=1,nmemb=18,userData=0xaf8050d0
2020-07-19 22:30:04.834 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveData:size=18
2020-07-19 22:30:06.154 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext:reason=context fetch timeout
2020-07-19 22:30:06.381 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext
2020-07-19 22:30:06.382 [ 24] 5 ContextManager:getContext
2020-07-19 22:30:06.382 [ 24] 5 ContextManager:getContextInternal
2020-07-19 22:30:06.382 [ 24] 9 ThreadPool:obtainWorker:created=24,obtained=24,releasedToPool=20,releasedFromPool=3,outstanding=1
2020-07-19 22:30:06.382 [ 17] 9 TaskThread:startThread:moniker= 17,duration=472
2020-07-19 22:30:06.383 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=25,releasedToPool=20,releasedFromPool=3,outstanding=2
2020-07-19 22:30:06.383 [ 1d] 9 TaskThread:startThread:moniker= 1d,duration=271
2020-07-19 22:30:06.383 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=26,releasedToPool=20,releasedFromPool=3,outstanding=3
2020-07-19 22:30:06.383 [ 1d] 5 ContextManager:setState
2020-07-19 22:30:06.384 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=388
2020-07-19 22:30:06.384 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=27,releasedToPool=20,releasedFromPool=3,outstanding=4
2020-07-19 22:30:06.384 [ 1e] 3 AlexaPresentation:executeProvideState:token=2
2020-07-19 22:30:06.384 [ 17] 5 AudioActivityTracker:provideState
2020-07-19 22:30:06.384 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=559
2020-07-19 22:30:06.385 [ 1f] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2020-07-19 22:30:06.385 [ 1f] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers
2020-07-19 22:30:06.384 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=28,releasedToPool=20,releasedFromPool=3,outstanding=5
2020-07-19 22:30:06.385 [ 1f] 5 ContextManager:setState
2020-07-19 22:30:06.385 [ 17] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=2
2020-07-19 22:30:06.385 [ 20] 9 TaskThread:startThread:moniker= 20,duration=787
2020-07-19 22:30:06.384 [ 1e] 5 ContextManager:setState
2020-07-19 22:30:06.386 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=29,releasedToPool=20,releasedFromPool=3,outstanding=6
2020-07-19 22:30:06.386 [ 20] 5 AudioActivityTracker:executeProvideState
2020-07-19 22:30:06.386 [ 18] 9 TaskThread:startThread:moniker= 18,duration=397
2020-07-19 22:30:06.386 [ 20] 5 ContextManager:setState
2020-07-19 22:30:06.386 [ 18] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=2
2020-07-19 22:30:06.387 [ 18] 5 ContextManager:setState
2020-07-19 22:30:06.386 [ 17] 5 VisualActivityTracker:provideState
2020-07-19 22:30:06.387 [ 17] 9 ThreadPool:obtainWorker:created=24,obtained=30,releasedToPool=20,releasedFromPool=3,outstanding=7
2020-07-19 22:30:06.387 [ 21] 9 TaskThread:startThread:moniker= 21,duration=206
2020-07-19 22:30:06.387 [ 21] 5 VisualActivityTracker:executeProvideState
2020-07-19 22:30:06.387 [ 17] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=7
2020-07-19 22:30:06.387 [ 21] 5 ContextManager:setState
2020-07-19 22:30:06.388 [ 17] E ContextManager:setStateFailed:missingState=Alexa.Display.Window::WindowState
2020-07-19 22:30:06.388 [ 17] 5 ContextManager:getContextFailureCallbackLocked:token=2
2020-07-19 22:30:06.388 [ 1f] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=2
2020-07-19 22:30:06.388 [ 1f] 5 ContextManager:setState
2020-07-19 22:30:06.389 [ 17] E PostConnectSynchronizeStateSender:onContextFailure:reason=BUILD_CONTEXT_ERROR
2020-07-19 22:30:06.389 [ 20] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:06.389 [ 18] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:06.389 [ 18] E NotificationsCapabilityAgent:executeProvideState:reason=contextManagerSetStateFailed
2020-07-19 22:30:06.389 [ 21] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:06.389 [ 1f] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:06.389 [ 1f] E ExternalMediaPlayer:executeProvideState:reason=contextManagerSetStateFailedForEMPState
2020-07-19 22:30:06.389 [ 17] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=2
2020-07-19 22:30:06.390 [ 17] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=2
2020-07-19 22:30:06.390 [ 1e] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:08.383 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext:reason=context fetch timeout
2020-07-19 22:30:09.867 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext
2020-07-19 22:30:09.867 [ 24] 5 ContextManager:getContext
2020-07-19 22:30:09.867 [ 24] 5 ContextManager:getContextInternal
2020-07-19 22:30:09.868 [ 24] 9 ThreadPool:obtainWorker:created=24,obtained=31,releasedToPool=27,releasedFromPool=4,outstanding=0
2020-07-19 22:30:09.868 [  a] 9 TaskThread:startThread:moniker=  a,duration=467
2020-07-19 22:30:09.868 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=32,releasedToPool=27,releasedFromPool=4,outstanding=1
2020-07-19 22:30:09.869 [ 26] 9 TaskThread:startThread:moniker= 26,duration=306
2020-07-19 22:30:09.869 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=33,releasedToPool=27,releasedFromPool=4,outstanding=2
2020-07-19 22:30:09.869 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=375
2020-07-19 22:30:09.869 [ 1c] 5 ContextManager:setState
2020-07-19 22:30:09.869 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=34,releasedToPool=27,releasedFromPool=4,outstanding=3
2020-07-19 22:30:09.870 [ 1b] 9 TaskThread:startThread:moniker= 1b,duration=284
2020-07-19 22:30:09.870 [ 1b] 3 AlexaPresentation:executeProvideState:token=3
2020-07-19 22:30:09.870 [ 1b] 5 ContextManager:setState
2020-07-19 22:30:09.870 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=35,releasedToPool=27,releasedFromPool=4,outstanding=4
2020-07-19 22:30:09.870 [  f] 9 TaskThread:startThread:moniker=  f,duration=239
2020-07-19 22:30:09.870 [  a] 5 AudioActivityTracker:provideState
2020-07-19 22:30:09.870 [  f] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
2020-07-19 22:30:09.870 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=36,releasedToPool=27,releasedFromPool=4,outstanding=5
2020-07-19 22:30:09.871 [  a] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=3
2020-07-19 22:30:09.871 [ 19] 9 TaskThread:startThread:moniker= 19,duration=311
2020-07-19 22:30:09.871 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=37,releasedToPool=27,releasedFromPool=4,outstanding=6
2020-07-19 22:30:09.871 [ 19] 5 AudioActivityTracker:executeProvideState
2020-07-19 22:30:09.871 [  a] 5 VisualActivityTracker:provideState
2020-07-19 22:30:09.871 [ 28] 9 TaskThread:startThread:moniker= 28,duration=303
2020-07-19 22:30:09.871 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=38,releasedToPool=27,releasedFromPool=4,outstanding=7
2020-07-19 22:30:09.871 [  f] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers
2020-07-19 22:30:09.871 [  f] 5 ContextManager:setState
2020-07-19 22:30:09.871 [ 28] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=3
2020-07-19 22:30:09.872 [ 27] 9 TaskThread:startThread:moniker= 27,duration=424
2020-07-19 22:30:09.872 [ 27] 5 VisualActivityTracker:executeProvideState
2020-07-19 22:30:09.872 [ 27] 5 ContextManager:setState
2020-07-19 22:30:09.872 [  a] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=7
2020-07-19 22:30:09.872 [ 28] 5 ContextManager:setState
2020-07-19 22:30:09.872 [  a] E ContextManager:setStateFailed:missingState=Alexa.Display.Window::WindowState
2020-07-19 22:30:09.873 [  f] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=3
2020-07-19 22:30:09.873 [  a] 5 ContextManager:getContextFailureCallbackLocked:token=3
2020-07-19 22:30:09.871 [ 19] 5 ContextManager:setState
2020-07-19 22:30:09.873 [  f] 5 ContextManager:setState
2020-07-19 22:30:09.874 [  a] E PostConnectSynchronizeStateSender:onContextFailure:reason=BUILD_CONTEXT_ERROR
2020-07-19 22:30:09.874 [ 19] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:09.874 [  f] E ContextManager:setStateFailed:reason=outdatedStateToken
2020-07-19 22:30:09.874 [  f] E ExternalMediaPlayer:executeProvideState:reason=contextManagerSetStateFailedForEMPState
2020-07-19 22:30:09.874 [  a] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=3
2020-07-19 22:30:09.874 [  a] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=3
2020-07-19 22:30:09.875 [  a] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=3
2020-07-19 22:30:09.875 [  a] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=3
2020-07-19 22:30:09.875 [  a] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=3
2020-07-19 22:30:09.875 [  a] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=3
2020-07-19 22:30:09.875 [  a] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=3
2020-07-19 22:30:09.875 [  a] E ContextManager:getContextAvailableCallbackIfReadyLockedFailed:reason=nullRequester,token=3
2020-07-19 22:30:11.868 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext:reason=context fetch timeout
2020-07-19 22:30:14.001 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.001 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.002 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.002 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.002 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.006 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.006 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.006 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.007 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.009 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.010 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.010 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.010 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.010 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.012 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.014 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.014 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.017 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.017 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.019 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.019 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.022 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.022 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.026 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.027 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.027 [  3] I WebSocketServer:onConnectionOpen:remoteHost=<IP_ADDRESS>:37842
2020-07-19 22:30:14.028 [  3] 3 GUIClient:onConnectionOpened
2020-07-19 22:30:14.028 [  3] 9 ThreadPool:obtainWorker:created=24,obtained=39,releasedToPool=35,releasedFromPool=4,outstanding=0
2020-07-19 22:30:14.028 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.028 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.029 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.028 [ 29] 9 TaskThread:startThread:moniker= 29,duration=210
2020-07-19 22:30:14.029 [ 29] 9 ThreadPool:obtainWorker:created=24,obtained=40,releasedToPool=35,releasedFromPool=4,outstanding=1
2020-07-19 22:30:14.029 [ 2a] 9 GUIClient:sendInitRequestAndWait::waiting for server to be ready
2020-07-19 22:30:14.030 [ 2a] 3 GUIClient:start::waiting for InitResponse
2020-07-19 22:30:14.030 [ 29] 9 WebSocketServer:writeMessageBegin
2020-07-19 22:30:14.030 [ 2a] 9 GUIClient:sendInitRequestAndWait:errorState=0,initMessage received=0
2020-07-19 22:30:14.030 [ 16] 9 TaskThread:startThread:moniker= 16,duration=921
2020-07-19 22:30:14.031 [ 29] 9 WebSocket:AccessLog
#################################################
Message Server Connection Opened.
#################################################
#######################################
ALEXA STATE: CONNECTING
#######################################
2020-07-19 22:30:14.032 [ 29] 9 WebSocket:AccessLog
2020-07-19 22:30:14.034 [ 29] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:14.034 [ 29] 9 WebSocketServer:writeMessageBegin
2020-07-19 22:30:14.034 [ 29] 9 WebSocket:AccessLog
2020-07-19 22:30:14.034 [ 29] 9 WebSocket:AccessLog
2020-07-19 22:30:14.035 [ 29] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:14.036 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.036 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.037 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.037 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.037 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.038 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.040 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.040 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.040 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.040 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.041 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.041 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.041 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.041 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"logEvent","level":"info","component":"WSClient","message":"received message"}
2020-07-19 22:30:14.042 [ 29] 9 ThreadPool:obtainWorker:created=24,obtained=41,releasedToPool=35,releasedFromPool=4,outstanding=2
2020-07-19 22:30:14.042 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.042 [  4] 9 TaskThread:startThread:moniker=  4,duration=262
2020-07-19 22:30:14.042 [  4] 3 GUILogBridge:GUILog:component=WSClient:received message
2020-07-19 22:30:14.042 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.043 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"initResponse","isSupported":true,"APLMaxVersion":"1.3"}
2020-07-19 22:30:14.043 [ 29] I GUIClient:executeProcessInitResponse:APL Max Version=1.3
2020-07-19 22:30:14.043 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.044 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.044 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.044 [ 2a] 9 GUIClient:sendInitRequestAndWait:errorState=0,initMessage received=1
2020-07-19 22:30:14.044 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.045 [ 2a] 3 GUIClient:start::InitResponse received
2020-07-19 22:30:14.045 [ 2a] 9 AplClientBridge:onConnectionOpened
2020-07-19 22:30:14.045 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.045 [ 2a] 9 ThreadPool:obtainWorker:created=24,obtained=42,releasedToPool=35,releasedFromPool=4,outstanding=3
2020-07-19 22:30:14.045 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.045 [  2] 9 TaskThread:startThread:moniker=  2,duration=242
2020-07-19 22:30:14.046 [ 29] 9 GUIClient:executeSendGuiConfiguration
2020-07-19 22:30:14.045 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.047 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.047 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.047 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.049 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.049 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"logEvent","level":"info","component":"WSClient","message":"message sent, type: initResponse"}
2020-07-19 22:30:14.049 [ 29] 9 WebSocketServer:writeMessageBegin
2020-07-19 22:30:14.050 [ 29] 9 WebSocket:AccessLog
2020-07-19 22:30:14.050 [ 29] 9 WebSocket:AccessLog
2020-07-19 22:30:14.050 [ 29] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:14.051 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"logEvent","level":"info","component":"WSClient","message":"received message"}
2020-07-19 22:30:14.051 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.051 [  4] 3 GUILogBridge:GUILog:component=WSClient:message sent, type: initResponse
2020-07-19 22:30:14.052 [  4] 3 GUILogBridge:GUILog:component=WSClient:received message
2020-07-19 22:30:14.052 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.052 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.053 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.054 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.057 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.058 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.059 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.059 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.060 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.060 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.060 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.060 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"logEvent","level":"info","component":"WSClient","message":"received message"}
2020-07-19 22:30:14.061 [  4] 3 GUILogBridge:GUILog:component=WSClient:received message
2020-07-19 22:30:14.061 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.061 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.061 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.062 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.063 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.064 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.064 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"deviceWindowState","payload":{"defaultWindowId":"tvFullscreen","instances":[{"id":"tvFullscreen","templateId":"tvFullscreen","token":null,"configuration":{"interactionMode":"tv","sizeConfigurationId":"fullscreen"}},{"id":"tvOverlayLandscape","templateId":"tvOverlayLandscape","token":null,"configuration":{"interactionMode":"tv_overlay","sizeConfigurationId":"landscapePanel"}}]}}
2020-07-19 22:30:14.064 [ 29] 9 ThreadPool:obtainWorker:created=24,obtained=43,releasedToPool=35,releasedFromPool=4,outstanding=4
2020-07-19 22:30:14.064 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.064 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.065 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.065 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.064 [ 1d] 9 TaskThread:startThread:moniker= 1d,duration=239
2020-07-19 22:30:14.065 [ 29] 9 GUIClient:onMessageInExector:payload={"type":"logEvent","level":"info","component":"WSClient","message":"message sent, type: deviceWindowState"}
2020-07-19 22:30:14.065 [  4] 3 GUILogBridge:GUILog:component=WSClient:message sent, type: deviceWindowState
2020-07-19 22:30:14.065 [  3] 9 WebSocket:AccessLog
2020-07-19 22:30:14.066 [ 1d] 9 ThreadPool:obtainWorker:created=24,obtained=44,releasedToPool=35,releasedFromPool=4,outstanding=5
2020-07-19 22:30:14.066 [ 20] 9 TaskThread:startThread:moniker= 20,duration=322
2020-07-19 22:30:15.754 [ 24] 5 PostConnectSynchronizeStateSender:fetchContext
2020-07-19 22:30:15.755 [ 24] 5 ContextManager:getContext
2020-07-19 22:30:15.755 [ 24] 5 ContextManager:getContextInternal
2020-07-19 22:30:15.755 [ 24] 9 ThreadPool:obtainWorker:created=24,obtained=45,releasedToPool=40,releasedFromPool=4,outstanding=1
2020-07-19 22:30:15.755 [ 18] 9 TaskThread:startThread:moniker= 18,duration=213
2020-07-19 22:30:15.755 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=46,releasedToPool=40,releasedFromPool=4,outstanding=2
2020-07-19 22:30:15.755 [ 21] 9 TaskThread:startThread:moniker= 21,duration=191
2020-07-19 22:30:15.756 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=47,releasedToPool=40,releasedFromPool=4,outstanding=3
2020-07-19 22:30:15.756 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=48,releasedToPool=40,releasedFromPool=4,outstanding=4
2020-07-19 22:30:15.756 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=49,releasedToPool=40,releasedFromPool=4,outstanding=5
2020-07-19 22:30:15.756 [ 18] 5 AudioActivityTracker:provideState
2020-07-19 22:30:15.756 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=50,releasedToPool=40,releasedFromPool=4,outstanding=6
2020-07-19 22:30:15.757 [ 1e] 9 TaskThread:startThread:moniker= 1e,duration=257
2020-07-19 22:30:15.757 [ 18] 5 NotificationsCapabilityAgent:provideState:stateRequestToken=4
2020-07-19 22:30:15.757 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=51,releasedToPool=40,releasedFromPool=4,outstanding=7
2020-07-19 22:30:15.757 [ 18] 5 VisualActivityTracker:provideState
2020-07-19 22:30:15.757 [ 18] 9 ThreadPool:obtainWorker:created=24,obtained=52,releasedToPool=40,releasedFromPool=4,outstanding=8
2020-07-19 22:30:15.758 [ 28] 9 TaskThread:startThread:moniker= 28,duration=165
2020-07-19 22:30:15.758 [ 28] 5 VisualActivityTracker:executeProvideState
2020-07-19 22:30:15.757 [ 17] 9 TaskThread:startThread:moniker= 17,duration=860
2020-07-19 22:30:15.758 [ 17] 3 AlexaPresentation:executeProvideState:token=4
2020-07-19 22:30:15.758 [ 17] 5 ContextManager:setState
2020-07-19 22:30:15.757 [ 25] 9 TaskThread:startThread:moniker= 25,duration=454
2020-07-19 22:30:15.757 [ 1e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=4
2020-07-19 22:30:15.758 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=7
2020-07-19 22:30:15.757 [ 1f] 9 TaskThread:startThread:moniker= 1f,duration=1237
2020-07-19 22:30:15.758 [ 28] 5 ContextManager:setState
2020-07-19 22:30:15.758 [ 25] 5 AudioActivityTracker:executeProvideState
2020-07-19 22:30:15.759 [ 1f] 5 ContextManager:setState
2020-07-19 22:30:15.759 [ 1c] 9 TaskThread:startThread:moniker= 1c,duration=1610
2020-07-19 22:30:15.759 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=6
2020-07-19 22:30:15.760 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=5
2020-07-19 22:30:15.760 [ 1c] 5 NotificationsCapabilityAgent:executeProvideState:sendToken=true,stateRequestToken=4
2020-07-19 22:30:15.760 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=4
2020-07-19 22:30:15.759 [ 25] 5 ContextManager:setState
2020-07-19 22:30:15.759 [ 1e] 5 ExternalMediaPlayer:notifyRenderPlayerInfoCardsObservers
2020-07-19 22:30:15.761 [ 1e] 5 ContextManager:setState
2020-07-19 22:30:15.761 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=3
2020-07-19 22:30:15.761 [ 1e] 0 ExternalMediaPlayer:executeProvideState:sendToken=true,stateRequestToken=4
2020-07-19 22:30:15.762 [ 1e] 5 ContextManager:setState
2020-07-19 22:30:15.762 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=2
2020-07-19 22:30:15.762 [ 1c] 5 ContextManager:setState
2020-07-19 22:30:15.763 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:result=stateNotAvailableYet,pendingStates=1
2020-07-19 22:30:15.763 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:token=4
2020-07-19 22:30:15.763 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:Alexa.Presentation.APL,name:RenderedDocumentState}
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:AudioActivityTracker,name:ActivityState}
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.764 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.765 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked:skipping state for legacy capabilityIdentifier={endpointId:,namespace:VisualActivityTracker,name:ActivityState}
2020-07-19 22:30:15.765 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.765 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.765 [ 18] 5 ContextManager:getContextAvailableCallbackIfReadyLocked
2020-07-19 22:30:15.766 [ 18] 5 AVSContext:toJson
2020-07-19 22:30:15.766 [ 18] 5 PostConnectSynchronizeStateSender:onContextAvailable
2020-07-19 22:30:15.768 [ 24] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32
2020-07-19 22:30:15.768 [ 24] 0 EventBuilder:buildJsonEventString:messageId=4a78acfe-76c4-4e7e-a8ad-bacd7134ef15,namespace=System,name=SynchronizeState
2020-07-19 22:30:15.768 [ 24] 0 EventBuilder:buildJsonEventString:event={"context":{"properties":[{"namespace":"Alerts","name":"AlertsState","value":{"allAlerts":[],"activeAlerts":[]},"timeOfSample":"2020-07-19T22:30:04.096Z","uncertaintyInMilliseconds":0},{"namespace":"Alexa.Display.Window","name":"WindowState","value":{"defaultWindowId":"tvFullscreen","instances":[{"id":"tvFullscreen","templateId":"tvFullscreen","token":null,"configuration":{"interactionMode":"tv","sizeConfigurationId":"fullscreen"}},{"id":"tvOverlayLandscape","templateId":"tvOverlayLandscape","token":null,"configuration":{"interactionMode":"tv_overlay","sizeConfigurationId":"landscapePanel"}}]},"timeOfSample":"2020-07-19T22:30:15.760Z","uncertaintyInMilliseconds":0},{"namespace":"Alexa.PlaybackStateReporter","name":"playbackState","value":{"state":"IDLE","supportedOperations":[],"shuffle":"NOT_SHUFFLED","repeat":"NOT_REPEATED","favorite":"NOT_RATED","positionMilliseconds":0,"players":[]},"timeOfSample":"2020-07-19T22:30:15.762Z","uncertaintyInMilliseconds":0},{"namespace":"AudioPlayer","name":"PlaybackState","value":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"},"timeOfSample":"2020-07-19T22:30:04.158Z","uncertaintyInMilliseconds":0},{"namespace":"EqualizerController","name":"EqualizerState","value":{"bands":[{"name":"TREBLE","level":0},{"name":"BASS","level":0},{"name":"MIDRANGE","level":0}]},"timeOfSample":"2020-07-19T22:30:04.113Z","uncertaintyInMilliseconds":0},{"namespace":"ExternalMediaPlayer","name":"ExternalMediaPlayerState","value":{"agent":"CQCAFYNYDC","spiVersion":"1.0","playerInFocus":"","players":[]},"timeOfSample":"2020-07-19T22:30:15.762Z","uncertaintyInMilliseconds":0},{"namespace":"Notifications","name":"IndicatorState","value":{"isEnabled":false,"isVisualIndicatorPersisted":false},"timeOfSample":"2020-07-19T22:30:15.763Z","uncertaintyInMilliseconds":0},{"namespace":"Speaker","name":"VolumeState","value":{"volume":100,"muted":false},"timeOfSample":"2020-07-19T22:30:04.091Z","uncertaintyInMilliseconds":0},{"namespace":"SpeechSynthesizer","name":"SpeechState","value":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"},"timeOfSample":"2020-07-19T22:30:04.159Z","uncertaintyInMilliseconds":0}]},"event":{"header":{"namespace":"System","name":"SynchronizeState","messageId":"4a78acfe-76c4-4e7e-a8ad-bacd7134ef15"},"payload":{}}}
2020-07-19 22:30:15.769 [ 24] 7 HTTP2Transport:sendMessage:this=0x1f5ec00
2020-07-19 22:30:15.769 [ 23] 7 MessageRequestHandler:create:context=0x1f5ec50,messageRequest=0xb0205308
2020-07-19 22:30:15.770 [ 23] 5 ExchangeHandler:ExchangeHandler:context=0x1f5ec50
2020-07-19 22:30:15.770 [ 23] 7 MessageRequestHandler:MessageRequestHandler:context=0x1f5ec50,messageRequest=0xb0205308
2020-07-19 22:30:15.770 [ 23] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie!,source=0xaf80ce2c
2020-07-19 22:30:15.770 [ 23] 9 MimeResponseSink:MimeResponseSink:handler=0xaf80ce30
2020-07-19 22:30:15.770 [ 23] 9 HTTP2MimeResponseDecoder:HTTP2MimeResponseDecoder
2020-07-19 22:30:15.771 [ 23] 7 HTTP2Transport:onMessageRequestSent:this=0x1f5ec00,countOfUnfinishedMessageHandlers=1
2020-07-19 22:30:15.771 [ 23] 7 HTTP2Transport:createAndSendRequest:this=0x1f5ec00,type=POST
2020-07-19 22:30:15.771 [ 23] 9 HTTP2MimeRequestEncoder:getRequestHeaderLines
2020-07-19 22:30:15.771 [ 23] 9 MessageRequestHandler:getRequestHeaderLines
2020-07-19 22:30:15.771 [ 23] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:15.771 [ 23] 0 MessageRequestHandler:EventSent
2020-07-19 22:30:15.859 [ 22] 9 LibcurlHTTP2Connection:insertActiveStream:handle=0xaf80e470,streamId=AVSEvent-3
2020-07-19 22:30:15.859 [ 22] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-3,size=1,nmemb=65536,userData=0xaf80ceb0
2020-07-19 22:30:15.859 [ 22] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=NEW
2020-07-19 22:30:15.860 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=NEW,newState=GETTING_1ST_PART_HEADERS
2020-07-19 22:30:15.860 [ 22] 9 MessageRequestHandler:getMimePartHeaderLines
2020-07-19 22:30:15.860 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:15.860 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_1ST_PART_HEADERS,newState=SENDING_1ST_BOUNDARY
2020-07-19 22:30:15.860 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_1ST_BOUNDARY,newState=SENDING_PART_HEADERS
2020-07-19 22:30:15.860 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_HEADERS,newState=SENDING_PART_DATA
2020-07-19 22:30:15.860 [ 22] 9 MessageRequestHandler:onSendMimePartData:size=65429
2020-07-19 22:30:15.860 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:15.860 [ 22] 9 MessageRequestHandler:onSendMimePartData:size=63125
2020-07-19 22:30:15.860 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:15.860 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_PART_DATA,newState=SENDING_END_BOUNDARY
2020-07-19 22:30:15.861 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_END_BOUNDARY,newState=GETTING_NTH_PART_HEADERS
2020-07-19 22:30:15.861 [ 22] 9 MessageRequestHandler:getMimePartHeaderLines
2020-07-19 22:30:15.861 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:15.861 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=GETTING_NTH_PART_HEADERS,newState=SENDING_TERMINATING_DASHES
2020-07-19 22:30:15.861 [ 22] 9 HTTP2MimeRequestEncoder:setState:state=SENDING_TERMINATING_DASHES,newState=DONE
2020-07-19 22:30:15.861 [ 22] 9 LibcurlHTTP2Request:readCallback:id=AVSEvent-3,size=1,nmemb=65536,userData=0xaf80ceb0
2020-07-19 22:30:15.861 [ 22] 9 HTTP2MimeRequestEncoder:onSendData:size=65536,state=DONE
2020-07-19 22:30:16.027 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=13,userData=0xaf80ceb0
2020-07-19 22:30:16.027 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveResponseCode:responseCode=204
2020-07-19 22:30:16.027 [ 22] 9 MimeResponseSink:onReceiveResponseCode:responseCode=204
2020-07-19 22:30:16.027 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.027 [ 22] 7 MessageRequestHandler:onReceiveResponseCode:responseCode=204
2020-07-19 22:30:16.027 [ 22] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2020-07-19 22:30:16.028 [ 22] 7 HTTP2Transport:onMessageRequestAcknowledged:this=0x1f5ec00
2020-07-19 22:30:16.028 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=HTTP/2 204
2020-07-19 22:30:16.028 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=HTTP/2 204
2020-07-19 22:30:16.028 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.028 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=56,userData=0xaf80ceb0
2020-07-19 22:30:16.028 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: 24f3e290-acc7-44db-b7e7-91f5f4b4b4d7
2020-07-19 22:30:16.028 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: 24f3e290-acc7-44db-b7e7-91f5f4b4b4d7
2020-07-19 22:30:16.028 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.028 [ 22] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: 24f3e290-acc7-44db-b7e7-91f5f4b4b4d7
2020-07-19 22:30:16.028 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=82,userData=0xaf80ceb0
2020-07-19 22:30:16.028 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=x-amzn-requestid: 0ad5b5fffe7d9fd8-00007628-003ea0a5-59c3185624aa7098-6613b161-3
2020-07-19 22:30:16.028 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=x-amzn-requestid: 0ad5b5fffe7d9fd8-00007628-003ea0a5-59c3185624aa7098-6613b161-3
2020-07-19 22:30:16.028 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.029 [ 22] 0 MimeResponseSink:receivedRequestId:value=x-amzn-requestid: 0ad5b5fffe7d9fd8-00007628-003ea0a5-59c3185624aa7098-6613b161-3
2020-07-19 22:30:16.029 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=32,userData=0xaf80ceb0
2020-07-19 22:30:16.029 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=access-control-allow-origin: *
2020-07-19 22:30:16.029 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=access-control-allow-origin: *
2020-07-19 22:30:16.029 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.029 [ 22] 9 LibcurlHTTP2Request:headerCallback:id=AVSEvent-3,size=1,nmemb=2,userData=0xaf80ceb0
2020-07-19 22:30:16.029 [ 22] 9 HTTP2MimeResponseDecoder:onReceiveHeaderLine:line=
2020-07-19 22:30:16.029 [ 22] 9 MimeResponseSink:onReceiveHeaderLine:line=
2020-07-19 22:30:16.029 [ 22] 9 HTTP2Transport:onActivity:this=0x1f5ec00
2020-07-19 22:30:16.029 [ 22] 9 HTTP2MimeResponseDecoder:onResponseFinished:status=COMPLETE
2020-07-19 22:30:16.030 [ 22] 9 MimeResponseSink:onResponseFinished:status=COMPLETE
2020-07-19 22:30:16.030 [ 22] 7 MessageRequestHandler:onResponseFinished:status=COMPLETE,responseCode=204
2020-07-19 22:30:16.030 [ 22] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2020-07-19 22:30:16.030 [ 22] 7 MessageRequestHandler:reportMessageRequestFinished
2020-07-19 22:30:16.030 [ 22] 7 HTTP2Transport:onMessageRequestFinished:this=0x1f5ec00,countOfUnfinishedMessageHandlers=0
2020-07-19 22:30:16.031 [ 22] 7 LibcurlHTTP2Connection:streamFinished:streamId=AVSEvent-3,result=No error,CURLcode=0
2020-07-19 22:30:16.031 [ 24] 5 PostConnectSynchronizeStateSender:performOperation:SynchronizeState event status=SUCCESS_NO_CONTENT
2020-07-19 22:30:16.031 [ 24] I HTTP2Transport:onPostConnected:this=0x1f5ec00
2020-07-19 22:30:16.031 [ 24] I HTTP2Transport:setStateLocked:this=0x1f5ec00,currentState=POST_CONNECTING,newState=CONNECTED,changedReason=SUCCESS
2020-07-19 22:30:16.031 [ 24] 5 PostConnectSequencer:mainLoopReturning
2020-07-19 22:30:16.031 [ 23] I HTTP2Transport:handleConnected:this=0x1f5ec00
2020-07-19 22:30:16.032 [ 23] 5 PostConnectSequencer:~PostConnectSequencer
2020-07-19 22:30:16.032 [ 23] 5 PostConnectSequencer:stop
2020-07-19 22:30:16.031 [ 22] 9 LibcurlHTTP2Connection:releaseStream:streamId=AVSEvent-3
2020-07-19 22:30:16.032 [ 23] 7 HTTP2Transport:notifyObserversOnConnected:this=0x1f5ec00
2020-07-19 22:30:16.032 [ 23] I MessageRouter:onConnected:transport=0x1f5ec00,m_activeTransport=0x1f5ec00
2020-07-19 22:30:16.032 [ 23] I MessageRouter:setConnectionStatusLocked:status=CONNECTED,reason=ACL_CLIENT_REQUEST
2020-07-19 22:30:16.033 [ 23] 0 MessageRouter:connectionStatusChanged:reason=ACL_CLIENT_REQUEST,newStatus=CONNECTED
2020-07-19 22:30:16.033 [ 23] 9 ThreadPool:obtainWorker:created=24,obtained=53,releasedToPool=40,releasedFromPool=4,outstanding=9
2020-07-19 22:30:16.033 [ 27] 9 TaskThread:startThread:moniker= 27,duration=215
2020-07-19 22:30:16.033 [ 23] 7 HTTP2Transport:sendMessagesAndPings:this=0x1f5ec00,whileState=CONNECTED
2020-07-19 22:30:16.033 [ 22] 7 MessageRequestHandler:reportMessageRequestAcknowledged
2020-07-19 22:30:16.034 [ 22] 7 MessageRequestHandler:reportMessageRequestFinished
2020-07-19 22:30:16.034 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=54,releasedToPool=40,releasedFromPool=4,outstanding=10
2020-07-19 22:30:16.035 [ 1b] 9 TaskThread:startThread:moniker= 1b,duration=353
2020-07-19 22:30:16.035 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=55,releasedToPool=40,releasedFromPool=4,outstanding=11
2020-07-19 22:30:16.035 [ 19] 9 TaskThread:startThread:moniker= 19,duration=227
2020-07-19 22:30:16.035 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=56,releasedToPool=40,releasedFromPool=4,outstanding=12
2020-07-19 22:30:16.035 [ 19] W UUIDGeneration:low entropy on call to generate UUID:current entropy=32
2020-07-19 22:30:16.036 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=57,releasedToPool=40,releasedFromPool=4,outstanding=13
2020-07-19 22:30:16.036 [ 19] 0 EventBuilder:buildJsonEventString:messageId=7d40dce4-13cf-4fc9-943a-cfab418fd586,namespace=Alexa.DoNotDisturb,name=ReportDoNotDisturb
2020-07-19 22:30:16.036 [ 19] 0 EventBuilder:buildJsonEventString:event={"event":{"header":{"namespace":"Alexa.DoNotDisturb","name":"ReportDoNotDisturb","messageId":"7d40dce4-13cf-4fc9-943a-cfab418fd586"},"payload":{"enabled":false}}}
2020-07-19 22:30:16.036 [  a] 9 TaskThread:startThread:moniker=  a,duration=288
2020-07-19 22:30:16.036 [  a] 9 ThreadPool:obtainWorker:created=24,obtained=58,releasedToPool=40,releasedFromPool=4,outstanding=14
2020-07-19 22:30:16.036 [ 19] 7 HTTP2Transport:onRequestEnqueued:this=0x1f5ec00
#################################
ALEXA STATE: IDLE
#################################
2020-07-19 22:30:16.037 [  f] 9 TaskThread:startThread:moniker=  f,duration=1605
2020-07-19 22:30:16.037 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=59,releasedToPool=40,releasedFromPool=4,outstanding=15
2020-07-19 22:30:16.037 [  f] 5 ReportStateHandler:sendReportState:pendingReport=false
2020-07-19 22:30:16.037 [ 16] 9 TaskThread:startThread:moniker= 16,duration=349
2020-07-19 22:30:16.037 [ 26] 9 TaskThread:startThread:moniker= 26,duration=1243
2020-07-19 22:30:16.038 [ 26] 9 WebSocketServer:writeMessageBegin
2020-07-19 22:30:16.038 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=60,releasedToPool=40,releasedFromPool=4,outstanding=16
2020-07-19 22:30:16.038 [ 26] 9 WebSocket:AccessLog
2020-07-19 22:30:16.038 [  4] 9 TaskThread:startThread:moniker=  4,duration=297
2020-07-19 22:30:16.038 [ 26] 9 WebSocket:AccessLog
2020-07-19 22:30:16.038 [  4] 5 SQLiteDeviceSettingStorage:loadSetting:key=SpeechRecognizer::wakeWordConfirmation
2020-07-19 22:30:16.038 [ 26] 9 WebSocketServer:writeMessageComplete
2020-07-19 22:30:16.038 [ 2b] 7 HTTP2Transport:onRequestEnqueued:this=0x1f5ec00
2020-07-19 22:30:16.038 [ 23] 7 MessageRequestHandler:create:context=0x1f5ec50,messageRequest=0xae4068f4
2020-07-19 22:30:16.038 [ 27] 9 ThreadPool:obtainWorker:created=24,obtained=61,releasedToPool=40,releasedFromPool=4,outstanding=17
2020-07-19 22:30:16.039 [ 23] 5 ExchangeHandler:ExchangeHandler:context=0x1f5ec50
2020-07-19 22:30:16.039 [ 23] 7 MessageRequestHandler:MessageRequestHandler:context=0x1f5ec50,messageRequest=0xae4068f4
2020-07-19 22:30:16.039 [ 29] 9 TaskThread:startThread:moniker= 29,duration=1063
2020-07-19 22:30:16.039 [ 23] 9 HTTP2MimeRequestEncoder:HTTP2MimeRequestEncoder:boundary=WhooHooZeerOoonie!,source=0xaf804b44