search

mozilla-mobile / firefox-tv

Firefox for Amazon's Fire TV
https://blog.mozilla.org/blog/2017/12/20/firefox-is-now-on-amazon-fire-tv-happy-holiday-watching/
Mozilla Public License 2.0
255 stars 109 forks source link

Tabs are not received on FFTV latest build from Master only after resuming FFTV(not reproducible on RC 4.5) #2955

Closed abodea closed 4 years ago

abodea commented 4 years ago

Steps to reproduce

  1. Install and launch FFTV.
  2. Login with valid credentials.
  3. Access the menu overview.
  4. Send a tab from desktop.

    Expected behavior

    The tab is received on FFTV.

    Actual behavior

    The tab is not received on FFTV.

    Device information

    • Fire TV device: Fire TV Stick 4k
    • Latest affected Firefox version: Latest version built from master
    • Earliest affected Firefox version: Latest version built from master
    • Turbo Mode: On and Off

      Notes

      Note that the tabs are received if I press the home menu button and when resuming FFTV the tab is loaded. Note that this issue is not reproducible on the latest release version Firefox TV v4.5 video

abodea commented 4 years ago 

2019-11-12 15:30:56.917 249-2666/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:56.917 1181-1596/? D/Vizzini_2.0.2362.0:MediaSessionObserver: Playback State Changed. New State: 6
2019-11-12 15:30:56.933 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Media State Update: ANDROID_MEDIA_SESSION is ACTIVE
2019-11-12 15:30:56.933 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Getting media context for source ANDROID_MEDIA_SESSION
2019-11-12 15:30:56.934 1181-1596/? I/Vizzini_2.0.2362.0:AlexaContextClient: Adding device context namespace: LocalMediaPlayer, name: PlaybackState
2019-11-12 15:30:56.942 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Acquiring audio content focus
2019-11-12 15:30:56.942 1181-1596/? I/DeviceEvent: CSMSIM#alexa_focus_acquire#0#LocalMediaPlayer#content
2019-11-12 15:30:56.943 1181-1565/? I/SPCH-SIM_SimStateMachine: Got DeviceContextEvent in ReadyState
2019-11-12 15:30:56.944 1181-1596/? D/AlexaFocusContract: acquireFocus namespace (LocalMediaPlayer) channel (content) package (com.amazon.vizzini)
2019-11-12 15:30:56.944 1181-1565/? I/SPCH-SIM_SimStateMachine: Got CapabilityCheckEvent in ReadyState
2019-11-12 15:30:56.944 1181-1596/? D/AlexaFocusChannelSet: acquireFocus namespace (LocalMediaPlayer) channel (content) client (com.amazon.vizzini) type (Binder)
2019-11-12 15:30:56.944 1181-1596/? D/AlexaFocusFilter: Namespace (LocalMediaPlayer) accepted as Local Application interface
2019-11-12 15:30:56.944 1181-1596/? D/AlexaFocusContentChannel: acquireFocus namespace (LocalMediaPlayer) channel (content) client (com.amazon.vizzini) type (Binder)
2019-11-12 15:30:56.944 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: AcquireFocus result: SUCCESS
2019-11-12 15:30:56.944 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Getting media context for source ANDROID_MEDIA_SESSION
2019-11-12 15:30:56.946 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: We aren't associated with another device, don't send an event.
2019-11-12 15:30:56.970 249-2666/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:56.971 249-2666/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:56.971 249-2666/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:56.971 249-2666/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:56.971 249-2666/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:56.975 616-678/? D/AmazonWindowMetrics: On window event - Package org.mozilla.tv.firefox, isDreaming false, state 1, alexaMode 0
2019-11-12 15:30:56.976 249-2666/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:56.977 249-2666/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:56.977 249-2666/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:56.977 249-2666/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:56.977 249-2666/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:56.979 616-1574/? D/ConnectivityService: requestNetwork for uid/pid:10198/8792 NetworkRequest [ TRACK_DEFAULT id=46, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
2019-11-12 15:30:56.980 616-638/? I/ActivityManager: Displayed org.mozilla.tv.firefox/.MainActivity: +191ms
2019-11-12 15:30:56.981 616-691/? D/WIFI: got request NetworkRequest [ TRACK_DEFAULT id=46, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 60
2019-11-12 15:30:56.981 616-691/? D/WIFI_UT: got request NetworkRequest [ TRACK_DEFAULT id=46, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 60
2019-11-12 15:30:56.981 616-737/? D/Ethernet: got request NetworkRequest [ TRACK_DEFAULT id=46, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 60
2019-11-12 15:30:56.986 249-583/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:56.988 249-583/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:56.988 249-583/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:56.988 249-583/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:56.988 249-583/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:56.997 249-2666/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:56.997 249-2666/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:56.998 249-2666/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:56.998 249-2666/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:56.998 249-2666/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:57.006 1331-1474/? I/FeaturedRotatorMRCViewa: ViewabilityReportEvent(super=BaseEvent(super=BlackbirdCommonBase(bbcSenderVersion=0, bbcReceiverVersion=0), source=null, creationTimestamp=1573565457006, additionalInfo=null, eventMetadata=null, reportMetadata=null, isScreenMagnifierEnabled=null, screenState=null, ad=null), viewabilityType=iab, percentage=1.0, duration=1000)
2019-11-12 15:30:57.007 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.common.NavigationNodePresenterDI
2019-11-12 15:30:57.007 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.MBMiniDetailsNodePresenterDI
2019-11-12 15:30:57.007 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.MBBackgroundImageNodePresenterDI
2019-11-12 15:30:57.008 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.NavMenuScreenNodePresenterDI
2019-11-12 15:30:57.009 1331-1535/? I/kso.PM.BaseEventHandler: Received an event from Launcher of typeScreenVisibilityEvent
2019-11-12 15:30:57.009 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.ContextMenuHintNodePresenterDI
2019-11-12 15:30:57.009 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.AlexaNavHintNodePresenterDI
2019-11-12 15:30:57.009 1331-1331/? I/SceneNodePresenter: SceneNodePresenter onStop() is called by class com.amazon.tv.launcher.ui.sceneflow.presenters.model1.FeaturedItemRotatorNodePresenterDI
2019-11-12 15:30:57.009 1331-1331/? I/KsoRefreshStateObservab: Refresh Previous State: VISIBLE, Refresh Current State: INVISIBLE
2019-11-12 15:30:57.010 1331-1331/? I/AppLifecycleListener: went background Activities: 0
2019-11-12 15:30:57.010 1273-1456/? I/...rics/PlatformWrapper: MAP support direct boot aware on current platform: false
2019-11-12 15:30:57.010 1273-1456/? I/...rics/PlatformWrapper: MAP is in direct boot mode: false
2019-11-12 15:30:57.015 1331-1331/? I/MediaBrowseInMemoryCacheImpl: Cache size before trim: 232
2019-11-12 15:30:57.015 1331-1331/? I/MediaBrowseInMemoryCacheImpl: Cache size after trim: 232
2019-11-12 15:30:57.016 1331-1473/? I/BuellerApplication: Notified of having no active activities
2019-11-12 15:30:57.016 1331-1473/? I/AivConnectorManager: Attempting to release AivConnector
2019-11-12 15:30:57.016 1331-1473/? I/AivConnector: cleanUp() called
2019-11-12 15:30:57.016 1331-1473/? I/AivConnector: Calling tearDownPreCaching on AivConnector
2019-11-12 15:30:57.018 1331-1468/? I/ImageCache: handleTrimMemory - evicting cached images - memorySaved: 0 bytes
2019-11-12 15:30:57.018 249-249/? I/hwcomposer: [PLTC] planeAlpha = 233
2019-11-12 15:30:57.019 249-249/? I/hwcomposer: [PLTC] planeAlpha = 233
2019-11-12 15:30:57.021 1331-1468/? I/ImageCache: handleTrimMemory - scheduled frozen image releases - numberOfImages: 30, memory: 24 MB
2019-11-12 15:30:57.024 1331-1468/? I/ImageCache: scheduling secondary trim
2019-11-12 15:30:57.031 1331-1331/? I/AivConnector: Calling cleanup on AivPlayback
2019-11-12 15:30:57.035 249-249/? I/hwcomposer: [PLTC] planeAlpha = 214
2019-11-12 15:30:57.035 249-249/? I/hwcomposer: [PLTC] planeAlpha = 214
2019-11-12 15:30:57.051 1331-1468/? I/ImageCache: handleTrimMemory - evicting cached images - memorySaved: 0 bytes
2019-11-12 15:30:57.051 1331-1468/? I/ImageCache: handleTrimMemory - scheduled frozen image releases - numberOfImages: 0, memory: 0 bytes
2019-11-12 15:30:57.051 249-249/? I/hwcomposer: [PLTC] planeAlpha = 195
2019-11-12 15:30:57.051 249-249/? I/hwcomposer: [PLTC] planeAlpha = 195
2019-11-12 15:30:57.068 249-249/? I/hwcomposer: [PLTC] planeAlpha = 176
2019-11-12 15:30:57.068 249-249/? I/hwcomposer: [PLTC] planeAlpha = 176
2019-11-12 15:30:57.085 249-249/? I/hwcomposer: [PLTC] planeAlpha = 160
2019-11-12 15:30:57.085 249-249/? I/hwcomposer: [PLTC] planeAlpha = 160
2019-11-12 15:30:57.101 249-249/? I/hwcomposer: [PLTC] planeAlpha = 143
2019-11-12 15:30:57.101 249-249/? I/hwcomposer: [PLTC] planeAlpha = 143
2019-11-12 15:30:57.118 249-249/? I/hwcomposer: [PLTC] planeAlpha = 127
2019-11-12 15:30:57.118 249-249/? I/hwcomposer: [PLTC] planeAlpha = 127
2019-11-12 15:30:57.135 249-249/? I/hwcomposer: [PLTC] planeAlpha = 112
2019-11-12 15:30:57.135 249-249/? I/hwcomposer: [PLTC] planeAlpha = 112
2019-11-12 15:30:57.149 1181-1932/? I/SPCH-SCL_Http2Message: status: 204
2019-11-12 15:30:57.149 1181-1932/? I/SPCH-SCL_Http2Message: header: DefaultHttp2Headers[:status: 204, x-amzn-requestid: 90c9438c-ffed-4282-a199-7dee1963e987, x-amzn-requestid: 16da75fffeba546a-00002aab-00be0c4d-625d28af348a5936-bd9d0cf0-71, access-control-allow-origin: *]
2019-11-12 15:30:57.150 1181-1932/? D/SPCH-SCL_Http2Client-1: removing message with stream id: 71
2019-11-12 15:30:57.150 1181-1181/? I/AppStateReporter:EventJobService: event sent successfully
2019-11-12 15:30:57.151 249-249/? I/hwcomposer: [PLTC] planeAlpha = 99
2019-11-12 15:30:57.152 249-249/? I/hwcomposer: [PLTC] planeAlpha = 99
2019-11-12 15:30:57.152 1181-1565/? I/SPCH-SIM_SimStateMachine: Got EndEventResponseEvent in ReadyState
2019-11-12 15:30:57.152 1181-1565/? I/SPCH-SIM_SIMState: Ignoring EndEventResponseEvent
2019-11-12 15:30:57.155 1181-1932/? E/SPCH-SCL_Http2ConnectionHandlerDecorator: onStreamError
2019-11-12 15:30:57.156 1181-1932/? E/SPCH-SCL_Http2ConnectionHandlerDecorator: onError
2019-11-12 15:30:57.168 249-249/? I/hwcomposer: [PLTC] planeAlpha = 86
2019-11-12 15:30:57.168 249-249/? I/hwcomposer: [PLTC] planeAlpha = 86
2019-11-12 15:30:57.185 249-249/? I/hwcomposer: [PLTC] planeAlpha = 74
2019-11-12 15:30:57.185 249-249/? I/hwcomposer: [PLTC] planeAlpha = 74
2019-11-12 15:30:57.201 249-249/? I/hwcomposer: [PLTC] planeAlpha = 63
2019-11-12 15:30:57.201 249-249/? I/hwcomposer: [PLTC] planeAlpha = 63
2019-11-12 15:30:57.218 249-249/? I/hwcomposer: [PLTC] planeAlpha = 53
2019-11-12 15:30:57.218 249-249/? I/hwcomposer: [PLTC] planeAlpha = 53
2019-11-12 15:30:57.235 249-249/? I/hwcomposer: [PLTC] planeAlpha = 43
2019-11-12 15:30:57.235 249-249/? I/hwcomposer: [PLTC] planeAlpha = 43
2019-11-12 15:30:57.251 8792-8808/? I/WM-WorkerWrapper: Worker result SUCCESS for Work [ id=5743be02-bd4f-43ce-ac59-eec7749fdd9a, tags={ org.mozilla.tv.firefox.pocket.PocketVideoFetchWorker } ]
2019-11-12 15:30:57.252 249-249/? I/hwcomposer: [PLTC] planeAlpha = 35
2019-11-12 15:30:57.252 249-249/? I/hwcomposer: [PLTC] planeAlpha = 35
2019-11-12 15:30:57.256 616-701/? D/ConnectivityService: releasing NetworkRequest [ TRACK_DEFAULT id=46, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
2019-11-12 15:30:57.268 249-249/? I/hwcomposer: [PLTC] planeAlpha = 28
2019-11-12 15:30:57.268 249-249/? I/hwcomposer: [PLTC] planeAlpha = 28
2019-11-12 15:30:57.285 249-249/? I/hwcomposer: [PLTC] planeAlpha = 21
2019-11-12 15:30:57.285 249-249/? I/hwcomposer: [PLTC] planeAlpha = 21
2019-11-12 15:30:57.302 249-249/? I/hwcomposer: [PLTC] planeAlpha = 15
2019-11-12 15:30:57.302 249-249/? I/hwcomposer: [PLTC] planeAlpha = 15
2019-11-12 15:30:57.318 249-249/? I/hwcomposer: [PLTC] planeAlpha = 10
2019-11-12 15:30:57.318 249-249/? I/hwcomposer: [PLTC] planeAlpha = 10
2019-11-12 15:30:57.335 249-249/? I/hwcomposer: [PLTC] planeAlpha = 6
2019-11-12 15:30:57.335 249-249/? I/hwcomposer: [PLTC] planeAlpha = 6
2019-11-12 15:30:57.352 249-249/? I/hwcomposer: [PLTC] planeAlpha = 3
2019-11-12 15:30:57.352 249-249/? I/hwcomposer: [PLTC] planeAlpha = 3
2019-11-12 15:30:57.363 8792-10690/? D/CRLSetManager: getInstance binder: android.os.BinderProxy@f025f87
2019-11-12 15:30:57.368 249-249/? I/hwcomposer: [PLTC] planeAlpha = 1
2019-11-12 15:30:57.368 249-249/? I/hwcomposer: [PLTC] planeAlpha = 1
2019-11-12 15:30:57.385 249-249/? I/hwcomposer: [PLTC] planeAlpha = 0
2019-11-12 15:30:57.385 249-249/? I/hwcomposer: [PLTC] planeAlpha = 0
2019-11-12 15:30:57.406 249-2666/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:57.408 249-2666/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:57.408 249-2666/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:57.408 249-2666/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:57.408 249-2666/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:58.070 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 0; audio level: 0.167
2019-11-12 15:30:58.070 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 1; audio level: 0.182
2019-11-12 15:30:58.070 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 0; audio level: 0.984
2019-11-12 15:30:58.070 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 1; audio level: 0.984
2019-11-12 15:30:58.165 8792-10690/? D/WrappingPersistenceCallback: Logging state to mozilla.components.service.fxa.manager.FxaAccountManager$FxaStatePersistenceCallback@3a085ee
2019-11-12 15:30:58.165 8792-10690/? D/FxaStatePersistenceCallback: Persisting account state into mozilla.components.service.fxa.SharedPrefAccountStorage@1888c79
2019-11-12 15:30:58.169 8792-10690/? I/FxaDeviceConstellation: Successfully executed: polling for device events
2019-11-12 15:30:58.173 8792-8792/? I/DeviceEventsIntegration: Received events, notifying listeners
2019-11-12 15:30:58.173 8792-8792/? D/DeviceObserver: Showing 1 tab(s) received from deviceID=e368c270f0648eedf6d9f1febb5b8f63
2019-11-12 15:30:58.215 8792-8792/? W/OpenGLRenderer: Points are too far apart 4.000000
2019-11-12 15:30:58.263 8792-8936/? E/chromium: [ERROR:gl_context.cc(126)] Not implemented reached in virtual void gl::GLContext::SetUnbindFboOnMakeCurrent()
2019-11-12 15:30:58.291 249-307/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:58.292 249-307/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:58.292 249-307/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:58.292 249-307/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:58.292 249-307/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:58.304 249-307/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:58.305 249-307/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:58.305 249-307/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:58.305 249-307/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:58.305 249-307/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:58.336 249-748/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:58.338 249-748/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:58.339 249-748/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:58.339 249-748/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:58.339 249-748/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:58.357 249-748/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:58.358 249-583/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:58.359 249-583/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:58.359 249-583/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:58.359 249-583/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:58.361 616-638/? I/AmazonPowerManagerVendorCallback: Wakelock acquired UID:1000 PID:616 Tag:WindowManager
2019-11-12 15:30:58.386 249-249/? I/hwcomposer: [PLTC] planeAlpha = 17
2019-11-12 15:30:58.386 249-249/? I/hwcomposer: [PLTC] planeAlpha = 17
2019-11-12 15:30:58.403 249-249/? I/hwcomposer: [PLTC] planeAlpha = 32
2019-11-12 15:30:58.403 249-249/? I/hwcomposer: [PLTC] planeAlpha = 32
2019-11-12 15:30:58.420 249-249/? I/hwcomposer: [PLTC] planeAlpha = 48
2019-11-12 15:30:58.420 249-249/? I/hwcomposer: [PLTC] planeAlpha = 48
2019-11-12 15:30:58.436 249-249/? I/hwcomposer: [PLTC] planeAlpha = 63
2019-11-12 15:30:58.436 249-249/? I/hwcomposer: [PLTC] planeAlpha = 63
2019-11-12 15:30:58.453 249-249/? I/hwcomposer: [PLTC] planeAlpha = 78
2019-11-12 15:30:58.453 249-249/? I/hwcomposer: [PLTC] planeAlpha = 78
2019-11-12 15:30:58.470 249-249/? I/hwcomposer: [PLTC] planeAlpha = 91
2019-11-12 15:30:58.470 249-249/? I/hwcomposer: [PLTC] planeAlpha = 91
2019-11-12 15:30:58.486 249-249/? I/hwcomposer: [PLTC] planeAlpha = 105
2019-11-12 15:30:58.486 249-249/? I/hwcomposer: [PLTC] planeAlpha = 105
2019-11-12 15:30:58.503 249-249/? I/hwcomposer: [PLTC] planeAlpha = 118
2019-11-12 15:30:58.503 249-249/? I/hwcomposer: [PLTC] planeAlpha = 118
2019-11-12 15:30:58.520 249-249/? I/hwcomposer: [PLTC] planeAlpha = 130
2019-11-12 15:30:58.520 249-249/? I/hwcomposer: [PLTC] planeAlpha = 130
2019-11-12 15:30:58.536 249-249/? I/hwcomposer: [PLTC] planeAlpha = 141
2019-11-12 15:30:58.536 249-249/? I/hwcomposer: [PLTC] planeAlpha = 141
2019-11-12 15:30:58.553 249-249/? I/hwcomposer: [PLTC] planeAlpha = 153
2019-11-12 15:30:58.553 249-249/? I/hwcomposer: [PLTC] planeAlpha = 153
2019-11-12 15:30:58.570 249-249/? I/hwcomposer: [PLTC] planeAlpha = 163
2019-11-12 15:30:58.570 249-249/? I/hwcomposer: [PLTC] planeAlpha = 163
2019-11-12 15:30:58.586 249-249/? I/hwcomposer: [PLTC] planeAlpha = 173
2019-11-12 15:30:58.586 249-249/? I/hwcomposer: [PLTC] planeAlpha = 173
2019-11-12 15:30:58.603 249-249/? I/hwcomposer: [PLTC] planeAlpha = 182
2019-11-12 15:30:58.603 249-249/? I/hwcomposer: [PLTC] planeAlpha = 182
2019-11-12 15:30:58.620 249-249/? I/hwcomposer: [PLTC] planeAlpha = 191
2019-11-12 15:30:58.620 249-249/? I/hwcomposer: [PLTC] planeAlpha = 191
2019-11-12 15:30:58.637 249-249/? I/hwcomposer: [PLTC] planeAlpha = 199
2019-11-12 15:30:58.637 249-249/? I/hwcomposer: [PLTC] planeAlpha = 199
2019-11-12 15:30:58.653 249-249/? I/hwcomposer: [PLTC] planeAlpha = 207
2019-11-12 15:30:58.653 249-249/? I/hwcomposer: [PLTC] planeAlpha = 207
2019-11-12 15:30:58.670 249-249/? I/hwcomposer: [PLTC] planeAlpha = 214
2019-11-12 15:30:58.670 249-249/? I/hwcomposer: [PLTC] planeAlpha = 214
2019-11-12 15:30:58.675 1331-1790/? I/kso.PM.GenericPlacement: reportEventsToService - reporting 4 events to service.;
2019-11-12 15:30:58.686 249-249/? I/hwcomposer: [PLTC] planeAlpha = 220
2019-11-12 15:30:58.687 249-249/? I/hwcomposer: [PLTC] planeAlpha = 220
2019-11-12 15:30:58.703 249-249/? I/hwcomposer: [PLTC] planeAlpha = 226
2019-11-12 15:30:58.703 249-249/? I/hwcomposer: [PLTC] planeAlpha = 226
2019-11-12 15:30:58.720 249-249/? I/hwcomposer: [PLTC] planeAlpha = 232
2019-11-12 15:30:58.720 249-249/? I/hwcomposer: [PLTC] planeAlpha = 232
2019-11-12 15:30:58.737 249-249/? I/hwcomposer: [PLTC] planeAlpha = 236
2019-11-12 15:30:58.737 249-249/? I/hwcomposer: [PLTC] planeAlpha = 236
2019-11-12 15:30:58.753 249-249/? I/hwcomposer: [PLTC] planeAlpha = 241
2019-11-12 15:30:58.753 249-249/? I/hwcomposer: [PLTC] planeAlpha = 241
2019-11-12 15:30:58.770 249-249/? I/hwcomposer: [PLTC] planeAlpha = 245
2019-11-12 15:30:58.770 249-249/? I/hwcomposer: [PLTC] planeAlpha = 245
2019-11-12 15:30:58.787 249-249/? I/hwcomposer: [PLTC] planeAlpha = 247
2019-11-12 15:30:58.787 249-249/? I/hwcomposer: [PLTC] planeAlpha = 247
2019-11-12 15:30:58.803 249-249/? I/hwcomposer: [PLTC] planeAlpha = 250
2019-11-12 15:30:58.803 249-249/? I/hwcomposer: [PLTC] planeAlpha = 250
2019-11-12 15:30:58.820 249-249/? I/hwcomposer: [PLTC] planeAlpha = 252
2019-11-12 15:30:58.820 249-249/? I/hwcomposer: [PLTC] planeAlpha = 252
2019-11-12 15:30:58.837 249-249/? I/hwcomposer: [PLTC] planeAlpha = 253
2019-11-12 15:30:58.837 249-249/? I/hwcomposer: [PLTC] planeAlpha = 253
2019-11-12 15:30:58.853 249-249/? I/hwcomposer: [PLTC] planeAlpha = 254
2019-11-12 15:30:58.853 249-249/? I/hwcomposer: [PLTC] planeAlpha = 254
2019-11-12 15:30:58.873 249-309/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:58.874 249-309/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:58.874 249-309/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:58.875 249-309/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:58.875 249-309/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:59.283 249-309/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:59.284 249-309/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:59.284 249-309/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:59.284 249-309/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:59.284 249-309/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:30:59.285 616-638/? I/AmazonPowerManagerVendorCallback: Wakelock released UID:1000 PID:616 Tag:WindowManager
2019-11-12 15:30:59.320 249-249/? I/hwcomposer: [PLTC] planeAlpha = 254
2019-11-12 15:30:59.320 249-249/? I/hwcomposer: [PLTC] planeAlpha = 254
2019-11-12 15:30:59.337 249-249/? I/hwcomposer: [PLTC] planeAlpha = 253
2019-11-12 15:30:59.337 249-249/? I/hwcomposer: [PLTC] planeAlpha = 253
2019-11-12 15:30:59.354 249-249/? I/hwcomposer: [PLTC] planeAlpha = 252
2019-11-12 15:30:59.354 249-249/? I/hwcomposer: [PLTC] planeAlpha = 252
2019-11-12 15:30:59.370 249-249/? I/hwcomposer: [PLTC] planeAlpha = 250
2019-11-12 15:30:59.370 249-249/? I/hwcomposer: [PLTC] planeAlpha = 250
2019-11-12 15:30:59.387 249-249/? I/hwcomposer: [PLTC] planeAlpha = 247
2019-11-12 15:30:59.387 249-249/? I/hwcomposer: [PLTC] planeAlpha = 247
2019-11-12 15:30:59.404 249-249/? I/hwcomposer: [PLTC] planeAlpha = 244
2019-11-12 15:30:59.404 249-249/? I/hwcomposer: [PLTC] planeAlpha = 244
2019-11-12 15:30:59.420 249-249/? I/hwcomposer: [PLTC] planeAlpha = 241
2019-11-12 15:30:59.421 249-249/? I/hwcomposer: [PLTC] planeAlpha = 241
2019-11-12 15:30:59.437 249-249/? I/hwcomposer: [PLTC] planeAlpha = 236
2019-11-12 15:30:59.437 249-249/? I/hwcomposer: [PLTC] planeAlpha = 236
2019-11-12 15:30:59.454 249-249/? I/hwcomposer: [PLTC] planeAlpha = 231
2019-11-12 15:30:59.454 249-249/? I/hwcomposer: [PLTC] planeAlpha = 231
2019-11-12 15:30:59.470 249-249/? I/hwcomposer: [PLTC] planeAlpha = 226
2019-11-12 15:30:59.471 249-249/? I/hwcomposer: [PLTC] planeAlpha = 226
2019-11-12 15:30:59.487 249-249/? I/hwcomposer: [PLTC] planeAlpha = 220
2019-11-12 15:30:59.487 249-249/? I/hwcomposer: [PLTC] planeAlpha = 220
2019-11-12 15:30:59.504 249-249/? I/hwcomposer: [PLTC] planeAlpha = 213
2019-11-12 15:30:59.504 249-249/? I/hwcomposer: [PLTC] planeAlpha = 213
2019-11-12 15:30:59.520 249-249/? I/hwcomposer: [PLTC] planeAlpha = 206
2019-11-12 15:30:59.520 249-249/? I/hwcomposer: [PLTC] planeAlpha = 206
2019-11-12 15:30:59.537 249-249/? I/hwcomposer: [PLTC] planeAlpha = 199
2019-11-12 15:30:59.537 249-249/? I/hwcomposer: [PLTC] planeAlpha = 199
2019-11-12 15:30:59.544 616-639/? I/PackageRecency: Matched blackListPattern: .*
2019-11-12 15:30:59.554 249-249/? I/hwcomposer: [PLTC] planeAlpha = 190
2019-11-12 15:30:59.554 249-249/? I/hwcomposer: [PLTC] planeAlpha = 190
2019-11-12 15:30:59.571 249-249/? I/hwcomposer: [PLTC] planeAlpha = 182
2019-11-12 15:30:59.571 249-249/? I/hwcomposer: [PLTC] planeAlpha = 182
2019-11-12 15:30:59.575 283-411/? I/DolbyAudioStreamOut: standby()
2019-11-12 15:30:59.576 616-626/? I/AmazonPowerManagerVendorCallback: Wakelock released UID:1041 PID:0 Tag:AudioMix
2019-11-12 15:30:59.587 249-249/? I/hwcomposer: [PLTC] planeAlpha = 172
2019-11-12 15:30:59.587 249-249/? I/hwcomposer: [PLTC] planeAlpha = 172
2019-11-12 15:30:59.604 249-249/? I/hwcomposer: [PLTC] planeAlpha = 162
2019-11-12 15:30:59.604 249-249/? I/hwcomposer: [PLTC] planeAlpha = 162
2019-11-12 15:30:59.621 249-249/? I/hwcomposer: [PLTC] planeAlpha = 152
2019-11-12 15:30:59.621 249-249/? I/hwcomposer: [PLTC] planeAlpha = 152
2019-11-12 15:30:59.637 249-249/? I/hwcomposer: [PLTC] planeAlpha = 141
2019-11-12 15:30:59.637 249-249/? I/hwcomposer: [PLTC] planeAlpha = 141
2019-11-12 15:30:59.654 249-249/? I/hwcomposer: [PLTC] planeAlpha = 129
2019-11-12 15:30:59.654 249-249/? I/hwcomposer: [PLTC] planeAlpha = 129
2019-11-12 15:30:59.671 249-249/? I/hwcomposer: [PLTC] planeAlpha = 117
2019-11-12 15:30:59.671 249-249/? I/hwcomposer: [PLTC] planeAlpha = 117
2019-11-12 15:30:59.687 249-249/? I/hwcomposer: [PLTC] planeAlpha = 104
2019-11-12 15:30:59.687 249-249/? I/hwcomposer: [PLTC] planeAlpha = 104
2019-11-12 15:30:59.704 249-249/? I/hwcomposer: [PLTC] planeAlpha = 90
2019-11-12 15:30:59.704 249-249/? I/hwcomposer: [PLTC] planeAlpha = 90
2019-11-12 15:30:59.721 249-249/? I/hwcomposer: [PLTC] planeAlpha = 77
2019-11-12 15:30:59.721 249-249/? I/hwcomposer: [PLTC] planeAlpha = 77
2019-11-12 15:30:59.737 249-249/? I/hwcomposer: [PLTC] planeAlpha = 62
2019-11-12 15:30:59.737 249-249/? I/hwcomposer: [PLTC] planeAlpha = 62
2019-11-12 15:30:59.754 249-249/? I/hwcomposer: [PLTC] planeAlpha = 47
2019-11-12 15:30:59.754 249-249/? I/hwcomposer: [PLTC] planeAlpha = 47
2019-11-12 15:30:59.771 249-249/? I/hwcomposer: [PLTC] planeAlpha = 31
2019-11-12 15:30:59.771 249-249/? I/hwcomposer: [PLTC] planeAlpha = 31
2019-11-12 15:30:59.787 249-249/? I/hwcomposer: [PLTC] planeAlpha = 16
2019-11-12 15:30:59.787 249-249/? I/hwcomposer: [PLTC] planeAlpha = 16
2019-11-12 15:30:59.806 249-309/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:30:59.806 249-309/? I/qdqservice: QService::getAutoMode
2019-11-12 15:30:59.806 249-309/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:30:59.806 249-309/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:30:59.806 249-309/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:31:00.045 616-639/? I/PackageRecency: Matched blackListPattern: .*
2019-11-12 15:31:00.318 1181-1596/? D/Vizzini:MediaSessionLib.MediaSessionControllerStateMgr: Playback State Changed org.mozilla.tv.firefox
2019-11-12 15:31:00.318 1181-1596/? D/Vizzini_2.0.2362.0:MediaSessionObserver: Playback State Changed. New State: 2
2019-11-12 15:31:00.318 751-915/? V/Avrcp: MediaController playback changed: PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=3157053, actions=822, custom actions=[], active item id=-1, error=null}
2019-11-12 15:31:00.318 1181-1460/? D/Vizzini:MediaSessionLib.MediaSessionControllerStateMgr: Playback State Changed org.mozilla.tv.firefox
2019-11-12 15:31:00.319 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Media State Update: ANDROID_MEDIA_SESSION is ACTIVE_SUSPENDED
2019-11-12 15:31:00.319 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Getting media context for source ANDROID_MEDIA_SESSION
2019-11-12 15:31:00.322 1181-1596/? I/Vizzini_2.0.2362.0:AlexaContextClient: Adding device context namespace: LocalMediaPlayer, name: PlaybackState
2019-11-12 15:31:00.324 1181-1565/? I/SPCH-SIM_SimStateMachine: Got DeviceContextEvent in ReadyState
2019-11-12 15:31:00.324 1181-1596/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Releasing audio content focus
2019-11-12 15:31:00.325 1181-1596/? I/DeviceEvent: CSMSIM#alexa_focus_release#0#LocalMediaPlayer#content
2019-11-12 15:31:00.326 1181-1596/? D/AlexaFocusContract: releaseFocus namespace (LocalMediaPlayer) channel (content) package (com.amazon.vizzini)
2019-11-12 15:31:00.326 1181-1596/? D/AlexaFocusChannelSet: releaseFocus namespace (LocalMediaPlayer) channel (content) client (com.amazon.vizzini) type (Binder)
2019-11-12 15:31:00.326 1181-1596/? D/AlexaFocusFilter: Namespace (LocalMediaPlayer) accepted as Local Application interface
2019-11-12 15:31:00.326 1181-1596/? D/AlexaFocusContentChannel: releaseFocus namespace (LocalMediaPlayer) channel (content) client (com.amazon.vizzini) type (Binder)
2019-11-12 15:31:00.326 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Focus changed. Reason: FOCUS_RELEASED
2019-11-12 15:31:00.326 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Getting media context for source ANDROID_MEDIA_SESSION
2019-11-12 15:31:00.327 1181-1565/? I/SPCH-SIM_SimStateMachine: Got CapabilityCheckEvent in ReadyState
2019-11-12 15:31:00.328 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: We aren't associated with another device, don't send an event.
2019-11-12 15:31:00.495 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 161
2019-11-12 15:31:00.497 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 163
2019-11-12 15:31:00.498 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 165
2019-11-12 15:31:00.500 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 169
2019-11-12 15:31:00.515 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 167
2019-11-12 15:31:00.544 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 171
2019-11-12 15:31:00.545 616-639/? I/PackageRecency: Matched blackListPattern for [com.amazon.tv.launcher/com.amazon.tv.launcher.util.AppRecencyReceiver] on com.amazon.venezia/.*|com.amazon.vizzini/.*|com.amazon.tv.settings/.*|com.amazon.tv.launcher/.*
2019-11-12 15:31:00.652 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 173
2019-11-12 15:31:00.659 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 175
2019-11-12 15:31:00.660 8792-8892/? W/chromium: [WARNING:spdy_session.cc(3105)] Received HEADERS for invalid stream 177
2019-11-12 15:31:01.516 1648-1773/? I/kso.NetworkTaskSchedule: scheduleNext - Account manager has account info? Y;
2019-11-12 15:31:01.517 1648-1773/? I/kso.NetworkTaskSchedule: scheduleNext - Account manager has account info? Y;
2019-11-12 15:31:01.526 1648-5549/? I/kso.EventsDataSourceImp: SavedFirstPartyEvent - dfbdb659-0a7b-40f6-8322-dca916b2490f;
2019-11-12 15:31:01.531 1648-1839/? I/kso.EventsDataSourceImp: SavedFirstPartyEvent - 24a1bee6-b0b5-49cf-b85f-7365cdfa78e3;
2019-11-12 15:31:01.702 249-307/? D/qdqservice: QService::getSupportedResolutions: u4res_ntsc=0xb, u4res_pal=0x2c00, u4res_4k=0x0, u4res=0x2c0b
2019-11-12 15:31:01.703 249-309/? I/qdqservice: QService::getAutoMode
2019-11-12 15:31:01.703 249-309/? D/qdqservice: QService::getAutoMode: hdmiMode 13
2019-11-12 15:31:01.703 249-309/? D/qdqservice: QService::convertOutputResCode: Found match: returning vic=16
2019-11-12 15:31:01.703 249-309/? D/qdqservice: QService::getAutoMode: vicMode 16
2019-11-12 15:31:01.706 616-616/? W/WindowManager: Attempted to remove non-existing token: android.os.Binder@78e12fc
2019-11-12 15:31:01.709 616-626/? W/WindowManager: Failed looking up window
    java.lang.IllegalArgumentException: Requested window android.os.BinderProxy@c2f0d85 does not exist
        at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:9514)
        at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:9505)
        at com.android.server.wm.WindowManagerService.removeWindow(WindowManagerService.java:2417)
        at com.android.server.wm.Session.remove(Session.java:202)
        at android.view.IWindowSession$Stub.onTransact(IWindowSession.java:242)
        at com.android.server.wm.Session.onTransact(Session.java:145)
        at android.os.Binder.execTransact(Binder.java:565)
2019-11-12 15:31:01.794 616-639/? I/PackageRecency: Matched blackListPattern: .*
2019-11-12 15:31:05.326 1181-1181/? I/Vizzini_2.0.2362.0:ExternalPlaybackStateManager: Getting media context for source ANDROID_MEDIA_SESSION
2019-11-12 15:31:05.327 1181-1181/? I/Vizzini_2.0.2362.0:AlexaContextClient: Adding device context namespace: LocalMediaPlayer, name: PlaybackState
2019-11-12 15:31:05.329 1181-1565/? I/SPCH-SIM_SimStateMachine: Got DeviceContextEvent in ReadyState
2019-11-12 15:31:05.330 1181-1565/? I/SPCH-SIM_SimStateMachine: Got CapabilityCheckEvent in ReadyState
2019-11-12 15:31:05.817 3471-3546/? I/JmDNSImpl: updateRecord() name=P6285 typeSubType=_nomachine._tcp.local. op=Remove #listeners=0
2019-11-12 15:31:05.819 3471-3546/? I/JmDNSImpl: updateRecord() name=Svrodk00220 typeSubType=_nomachine._tcp.local. op=Remove #listeners=0
2019-11-12 15:31:06.004 1331-1331/? I/WeblabFeature: Refreshing treatment for: FTV_ENGAGEMENT_AR_FR_187881
2019-11-12 15:31:06.007 1331-1331/? I/AsyncWeblabTreatmentTas: Received a treatment of C for : FTV_ENGAGEMENT_AR_FR_187881
2019-11-12 15:31:06.794 616-639/? I/PackageRecency: Matched blackListPattern: .*
2019-11-12 15:31:07.296 616-639/? I/PackageRecency: Intent triggered to package: ComponentInfo{com.amazon.tv.launcher/com.amazon.tv.launcher.util.AppRecencyReceiver} for Intent { act=android.intent.action.MAIN cat=[android.intent.category.LEANBACK_LAUNCHER] flg=0x10400000 pkg=org.mozilla.tv.firefox cmp=org.mozilla.tv.firefox/.MainActivity (has extras) }
2019-11-12 15:31:07.301 616-628/? W/IMGMemtrackHAL: hal_get_memory: memtrack cache rebuild was required
2019-11-12 15:31:07.306 1331-11267/? D/RecentRefreshManager: Recent App Package was: org.mozilla.tv.firefox
2019-11-12 15:31:07.306 1331-11267/? I/F_C.DefaultConsumerClie: getCards
2019-11-12 15:31:07.307 1331-4580/? I/F_C.DefaultConsumerClie: Querying CDA: uri=ParsedUri(), selection=card_id=?, selectionArgs= [...] , orderBy=create_time desc
2019-11-12 15:31:07.307 1331-4580/? I/F_C.DefaultConsumerClie: Using non-local ContentProvider
2019-11-12 15:31:07.308 1331-4580/? I/F_C.DefaultConsumerClie: Acquiring ContentProviderClient
2019-11-12 15:31:07.308 1331-4580/? I/F_C.CardProvider: CDA:CardProvider:query(ParsedUri(), projection:null, selection:card_id=?, selectionArgs: [...] , sortOrder:create_time desc)
2019-11-12 15:31:07.312 1331-4580/? I/F_C.DefaultConsumerClie: 1 results
2019-11-12 15:31:07.314 1331-4632/? I/F_C.CardProvider: CDA:CardProvider:bulkInsert(ParsedUri(PRODUCER=com.amazon.tv.launcher), contentValues:[(com.amazon.tv.launcher/1049294958/1573565467312)][size=1])
2019-11-12 15:31:07.322 1318-1318/? I/AmazonAppstore.LibraryItemLaunchedReceiver: Pushing library item to Recent.
2019-11-12 15:31:07.323 1318-1318/? D/JobIntentService: Starting service for work: Intent { cmp=com.amazon.venezia/.card.producer.VeneziaCardMakerService (has extras) }
2019-11-12 15:31:07.327 1331-4632/? I/F_C.ProducerRefreshServ: Performing Producer refresh.
2019-11-12 15:31:07.334 1318-1318/? D/JobIntentService: ensureProcessorRunningLocked
2019-11-12 15:31:07.335 1318-1318/? D/JobIntentService: Starting handler with WAKE_LOCK: Handler (com.amazon.android.service.JobIntentService$CommandProcessor) {d3ec264}
2019-11-12 15:31:07.339 1318-1318/? I/AmazonAppstore.ArcherFeatureCheck: Received control, library rotation disabled.
2019-11-12 15:31:07.339 1318-1318/? I/AmazonAppstore.LibraryItemReceiver: Did not update library order, library rotation is disabled.
2019-11-12 15:31:07.342 1318-11268/? I/AmazonAppstore.VeneziaCardMaker/VeneziaCardMaker: Beginning refreshType: PUSH_TO_RECENT
2019-11-12 15:31:07.343 1318-11268/? E/AmazonAppstore.VeneziaCardMaker/VeneziaCardMaker: Could not find [org.mozilla.tv.firefox] to push to Recent for type [APP].
2019-11-12 15:31:07.343 1318-11268/? I/AmazonAppstore.VeneziaCardMaker/VeneziaCardMaker: Completed refreshType: PUSH_TO_RECENT
2019-11-12 15:31:07.351 1331-1643/? I/F_C.DefaultConsumerClie: Using non-local ContentProvider
2019-11-12 15:31:07.351 1331-1643/? I/F_C.DefaultConsumerClie: Acquiring ContentProviderClient
2019-11-12 15:31:07.351 1331-1643/? I/F_C.CardProvider: CDA:CardProvider:query(ParsedUri(PRODUCER=com.amazon.tv.launcher, CARD= [...] ), projection:null, selection:null, selectionArgs: [...] , sortOrder:null)
2019-11-12 15:31:07.507 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: Processing add update for 1
2019-11-12 15:31:07.507 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: Unique # of cards = 1
2019-11-12 15:31:07.507 1331-1331/? I/MobileWeblabClientManag: Feature treatment for feature FTV_ENGAGEMENT_ARCHER_185425 is : C_DEFAULT
2019-11-12 15:31:07.508 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: replacing home @@ recents @@ org.mozilla.tv.firefox from recent cda items
2019-11-12 15:31:07.508 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: removing 
    ---CARD INFO---
      version: 0
      card ID: home @@ recents @@ org.mozilla.tv.firefox
      producer ID: com.amazon.tv.launcher
      group ID: Item
      instance ID: 1573564700217
      expiry: null
      rank: 1
      target: home @@ recents
      extras: null
     create time: Tue Nov 12 15:18:20 GMT+02:00 2019
2019-11-12 15:31:07.508 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: adding 
    ---CARD INFO---
      version: 0
      card ID: home @@ recents @@ org.mozilla.tv.firefox
      producer ID: com.amazon.tv.launcher
      group ID: Item
      instance ID: 1573565467312
      expiry: null
      rank: 1
      target: home @@ recents
      extras: null
     create time: Tue Nov 12 15:31:07 GMT+02:00 2019
2019-11-12 15:31:07.508 1331-1331/? I/CdaBasedRecentDataContr: FTV-CDA: replacing existing 
    ---CARD INFO---
      version: 0
      card ID: home @@ recents @@ org.mozilla.tv.firefox
      producer ID: com.amazon.tv.launcher
      group ID: Item
      instance ID: 1573564700217
      expiry: null
      rank: 1
      target: home @@ recents
      extras: null
     create time: Tue Nov 12 15:18:20 GMT+02:00 2019 with 
    ---CARD INFO---
      version: 0
      card ID: home @@ recents @@ org.mozilla.tv.firefox
      producer ID: com.amazon.tv.launcher
      group ID: Item
      instance ID: 1573565467312
      expiry: null
      rank: 1
      target: home @@ recents
      extras: null
     create time: Tue Nov 12 15:31:07 GMT+02:00 2019
2019-11-12 15:31:07.609 1331-1331/? I/DisplayControllerDeboun: FTV-CDA: DisplayControllerDebouncer executing Operation List with 1 operations
2019-11-12 15:31:07.609 1331-1331/? I/FilteredArrayObjectAdap: FTV-CDA: # of items deleted = 1
2019-11-12 15:31:07.609 1331-1331/? I/FilteredArrayObjectAdap: FTV-CDA: Added 1 items at index 0
2019-11-12 15:31:07.612 1331-1331/? D/ItemCardViewDataExtract: Missing StateBadge, treating as default
2019-11-12 15:31:07.612 1331-1331/? D/ItemCardViewDataExtract: Missing StateBadge, treating as default
2019-11-12 15:31:07.612 1331-1331/? D/ItemCardViewDataExtract: Missing StateBadge, treating as default
2019-11-12 15:31:07.613 1331-1331/? I/MobileWeblabClientManag: Feature treatment for feature FTV_ENGAGEMENT_CONTEXT_MENU_HINTS_181031 is : C
2019-11-12 15:31:07.816 1331-1535/? I/kso.PM.BaseEventHandler: Received an event from Launcher of typeScreenVisibilityEvent
2019-11-12 15:31:08.085 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 0; audio level: 0.000
2019-11-12 15:31:08.085 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 1; audio level: 0.000
2019-11-12 15:31:08.085 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 0; audio level: 0.000
2019-11-12 15:31:08.085 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 1; audio level: 0.000
2019-11-12 15:31:09.590 3265-11116/? I/DiscoveryService: SsdpNanoHTTPD - Method:GET;
2019-11-12 15:31:09.590 3265-11116/? I/DiscoveryService: SsdpDialRestService - serve: Method: GET URI = /apps/YouTube;
2019-11-12 15:31:13.290 3265-11047/? W/DiscoveryService: ReceivingSearch - Non-implemented search request target: class com.amazon.whisperlink.cling.model.message.header.AmazonDeviceInfoHeader;
2019-11-12 15:31:13.856 3265-10691/? W/DiscoveryService: ReceivingSearch - Non-implemented search request target: class com.amazon.whisperlink.cling.model.message.header.AmazonDeviceInfoHeader;
2019-11-12 15:31:18.101 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 0; audio level: 0.000
2019-11-12 15:31:18.101 283-2634/? I/Node: dolby-audio-meter, AdapterPcmSystem adapter audio meter: channel 1; audio level: 0.000
2019-11-12 15:31:18.101 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 0; audio level: 0.000
2019-11-12 15:31:18.101 283-2634/? I/Node: dolby-audio-meter, AdapterPcmOut adapter audio meter: channel 1; audio level: 0.000
abodea commented 4 years ago

Maybe this is a regression.

athomasmoz commented 4 years ago

Could this be an issue on debug only? On debug builds, there is an extra step to have push work. See https://github.com/mozilla-mobile/firefox-tv/#adm-api-key

mcomella commented 4 years ago

I was unable to reproduce: @abodea, did you set up the debug API key when you created your build? https://github.com/mozilla-mobile/firefox-tv/#adm-api-key

abodea commented 4 years ago

Hello @mcomella please note that this was the build from @Baron-Severin. I do believe it was set as I was able to receive the tabs after resuming FFFTV. @Baron-Severin can you please let us know if that build included the API key?

severinrudie commented 4 years ago

@abodea I don't know, tbh. If @mcomella is unable to reproduce I'd be fine closing it, if that's alright with you.

mcomella commented 4 years ago

I'd be fine closing it, if that's alright with you.

@Baron-Severin Can you elaborate on why you don't think this might indicative of a larger problem? Perhaps I'm being paranoid but I'd like to eliminate the possibilities.

Do we still have access to the build? Can you send it to me? We can open the APK to see if the key is present.

Hello @mcomella please note that this was the build from @Baron-Severin. I do believe it was set as I was able to receive the tabs after resuming FFFTV.

afaik, there are two different mechanisms to receive tabs, only one of which requires an API key: 1) Push events, which can occur at any time. This requires the API key. 2) Poll events, which occur only immediately when resuming the app. This does not require the API key.

@abodea When you say you are able to receive the tabs after resuming FFTV, were you able to receive them at any time or only immediately when resuming the app (case 2)?

abodea commented 4 years ago

@abodea When you say you are able to receive the tabs after resuming FFTV, were you able to receive them at any time or only immediately when resuming the app (case 2)?

If I send a tab while FFTV is open, I don't receive it but if I go to Fire TV home screen and then resume the FFTV, I will always receive the tab. The issue I consider important here is that the tabs are not immediately opened if FFTV is open, but when I go to home screen and then resume FFTV, the tabs are loaded.

severinrudie commented 4 years ago

I'd be fine closing it, if that's alright with you.

@Baron-Severin Can you elaborate on why you don't think this might indicative of a larger problem? Perhaps I'm being paranoid but I'd like to eliminate the possibilities.

Do we still have access to the build? Can you send it to me? We can open the APK to see if the key is present.

Hello @mcomella please note that this was the build from @Baron-Severin. I do believe it was set as I was able to receive the tabs after resuming FFFTV.

afaik, there are two different mechanisms to receive tabs, only one of which requires an API key:

1. Push events, which can occur at any time. This requires the API key.

2. Poll events, which occur only immediately when resuming the app. This does not require the API key.

@abodea When you say you are able to receive the tabs after resuming FFTV, were you able to receive them at any time or only immediately when resuming the app (case 2)?

@mcomella I've never been able to get push to work consistently on my own builds. I don't know what is wrong with my environment, but they've been working for everyone else. If this were a blocker, push never would have gone out.

We'll test the release build before it goes out, we can verify behavior then. Until that point I'm not concerned.

mcomella commented 4 years ago

We'll test the release build before it goes out, we can verify behavior then.

Sounds like a plan: I'll close this bug and add it to the QA needed column for it to get extra scrutiny.

QA: please verify this issue doesn't happen on our LAT.

abodea commented 4 years ago

Verified as fixed on v4.6-LAT1 using Fire TV Stick 4k. Note that everything works as expected, I've sent more than 30 tabs.