
========== beginning of new [logcat] output ==========
--------- beginning of main
04-30 17:06:58.800  2999  3106 I Finsky  : [273] WM::SCH: Logging work initialize for 12-1
04-30 17:06:58.813  2999  3124 I Finsky  : [277] SCH: Scheduling phonesky job Id: 12-1, CT: 1777536418737, Constraints: [{ L: 15000, D: 86400000, C: CHARGING_NONE, I: IDLE_NONE, N: NET_ANY, B: BATTERY_ANY }]
04-30 17:06:58.843  2999  3127 I Finsky  : [278] SCH: Scheduling 1 system job(s)
04-30 17:06:58.843  2999  3127 I Finsky  : [278] SCH: Scheduling system job Id: 9408, L: 14894, D: 76085196, C: false, I: false, N: 1
04-30 17:06:58.863  2999  3096 I Finsky  : [271] [ContentSync] finished, scheduled=true
04-30 17:06:58.865  6134  6189 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=ecbe261d-fd44-4d9e-887f-fdb0bda731e5, generation=0)
04-30 17:06:58.866  6134  6134 D WM-SystemJobService: ecbe261d-fd44-4d9e-887f-fdb0bda731e5 executed on JobScheduler
04-30 17:06:58.867  6134  6189 D WM-StopWorkRunnable: StopWorkRunnable for ecbe261d-fd44-4d9e-887f-fdb0bda731e5; Processor.stopWork = false
04-30 17:06:58.898  1437  1501 I Icing   : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=AppsCorpus serviceId=36
04-30 17:06:58.916   731   791 I system_server: Explicit concurrent copying GC freed 32382(2487KB) AllocSpace objects, 16(1016KB) LOS objects, 30% free, 13MB/19MB, paused 341us total 374.198ms
--------- beginning of system
04-30 17:06:58.921   731   791 I ActivityManager: Force stopping com.google.android.rvis.tvts appid=10067 user=0: pkg removed
04-30 17:06:58.926   731   791 E HistoricalRegistry: Interaction before persistence initialized
04-30 17:06:58.933  3866  3908 I Finsky:background: [338] RECEIVER_ENGAGE_PACKAGE_CHANGED#logWorkEndAndFinishGoAsync: SUCCESS
04-30 17:06:58.935  1544  1544 I katniss_interactor_PackageCache: Queueing package cache update for Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) }
04-30 17:06:58.936  1544  1829 I katniss_interactor_PackageCache: Clearing package com.google.android.rvis.tvts from cache
04-30 17:06:58.937  6134  6134 I katniss_search_PackageCache: Queueing package cache update for Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) }
04-30 17:06:58.938  6134  6180 I katniss_search_PackageCache: Clearing package com.google.android.rvis.tvts from cache
04-30 17:06:58.946  3866  3866 I Finsky:background: [2] qtt - Received: android.intent.action.PACKAGE_REMOVED, [VtrAa0lu_NybIid-od-G5EsC8hfTPTz0g-whbz0qQcE]
04-30 17:06:58.949  2999  2999 I Finsky  : [2] qud - Received: android.intent.action.PACKAGE_REMOVED, [VtrAa0lu_NybIid-od-G5EsC8hfTPTz0g-whbz0qQcE]
04-30 17:06:58.950  6134  6134 I katniss_search_PackageCache: Queueing package cache update for Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.rvis.tvts flg=0x5000010 (has extras) }
04-30 17:06:58.951  3866  3866 I Finsky:background: [2] Package no longer installed: com.google.android.rvis.tvts
04-30 17:06:58.956  2999  2999 I Finsky  : [2] Clearing split related stale data.
04-30 17:06:58.956  2999  2999 I Finsky  : [2] AIM: AppInfoCacheUpdater -> invalidating apps: [com.google.android.rvis.tvts]
04-30 17:06:58.956  2999  2999 I Finsky  : [2] AIM: AppInfoManager-Perf > getApps > called for 1 apps
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: TvSystemAudioManager isn't available
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: vwg: Library is not available: library either is not installed or was not loaded (AndroidManifest.xml is missing <uses-library>) or does not contain the class com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at vwm.<init>(PG:20)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at vwh.a(PG:3)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at fdy.bz(PG:125)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at fdy.aN(PG:36)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at gjv.a(PG:3)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at xwn.a(PG:3)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at yjw.a(PG:17)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at abjj.e(PG:3)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at abjl.run(PG:47)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at abkz.execute(PG:1)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at abmn.d(PG:7)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at xwr.onReceive(PG:698)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.LoadedApk$ReceiverDispatcher$Args.lambda$getRunnable$0$LoadedApk$ReceiverDispatcher$Args(LoadedApk.java:1550)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.-$$Lambda$LoadedApk$ReceiverDispatcher$Args$_BumDX2UKsnxLVrE6UJsJZkotuA.run(Unknown Source:2)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Handler.handleCallback(Handler.java:883)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Handler.dispatchMessage(Handler.java:100)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Looper.loop(Looper.java:214)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.ActivityThread.main(ActivityThread.java:7356)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.reflect.Method.invoke(Native Method)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:492)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:930)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: Caused by: java.lang.ClassNotFoundException: com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.classForName(Native Method)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.forName(Class.java:454)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.forName(Class.java:379)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	at vwm.<init>(PG:6)
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	... 20 more
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: Caused by: java.lang.ClassNotFoundException: com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.956  1544  1544 I katniss_interactor_SingletonModule: 	... 24 more
04-30 17:06:58.956  1544  1544 I katniss_interactor_PackageCache: Queueing package cache update for Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.rvis.tvts flg=0x5000010 (has extras) }
04-30 17:06:58.957  1544  1829 I katniss_interactor_PackageCache: Clearing package com.google.android.rvis.tvts from cache
04-30 17:06:58.957  1544  1829 I katniss_interactor_HomeGraphUpdaterImpl: #update
04-30 17:06:58.958  2999  3132 I Finsky  : [283] AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=0, cacheMissCount=1. Missed  in cache (limit 10) : [com.google.android.rvis.tvts]
04-30 17:06:58.960  2999  3124 I Finsky  : [277] AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=1, cacheMissCount=0. Missed  in cache (limit 10) : []
04-30 17:06:58.961  2999  3127 I Finsky  : [278] AIM: Got app ownership map. App counts: . Unique apps: 0
04-30 17:06:58.961  2999  3127 E Finsky  : [278] [Counters] attempted to use a non-positive increment for: 4752
04-30 17:06:58.961  2999  3127 E Finsky  : [278] [Counters] attempted to use a non-positive increment for: 4753
04-30 17:06:58.963   731   882 I InputReader: Reconfiguring input devices.  changes=0x00000010
--------- beginning of events
04-30 17:06:58.970   731   889 I commit_sys_config_file: [net-policy,4]
04-30 17:06:58.971  1437  7651 W AppInstallOperation: FDL Migration::InstallIntentOperation by Appinvite Module [CONTEXT service_id=77 ]
04-30 17:06:58.972  2999  6967 I Finsky  : [356] Asset module storage cleared for package com.google.android.rvis.tvts.
04-30 17:06:58.973  3866  3866 I Finsky:background: [2] Package no longer installed: com.google.android.rvis.tvts
04-30 17:06:58.976  2999  3124 I Finsky  : [277] AIM: Got app ownership map. App counts: . Unique apps: 0
04-30 17:06:58.976   731   772 I commit_sys_config_file: [roles-0,23]
04-30 17:06:58.976   731   772 I RoleUserState: Wrote roles.xml successfully
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: TvSystemAudioManager isn't available
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: vwg: Library is not available: library either is not installed or was not loaded (AndroidManifest.xml is missing <uses-library>) or does not contain the class com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at vwm.<init>(PG:20)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at vwh.a(PG:3)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at fdy.bz(PG:125)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at fdy.aN(PG:36)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at gjv.a(PG:3)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at xwn.a(PG:3)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at yjw.a(PG:17)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at abjj.e(PG:3)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at abjl.run(PG:47)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at abkz.execute(PG:1)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at abmn.d(PG:7)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at xwr.onReceive(PG:698)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.LoadedApk$ReceiverDispatcher$Args.lambda$getRunnable$0$LoadedApk$ReceiverDispatcher$Args(LoadedApk.java:1550)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.-$$Lambda$LoadedApk$ReceiverDispatcher$Args$_BumDX2UKsnxLVrE6UJsJZkotuA.run(Unknown Source:2)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Handler.handleCallback(Handler.java:883)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Handler.dispatchMessage(Handler.java:100)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.os.Looper.loop(Looper.java:214)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at android.app.ActivityThread.main(ActivityThread.java:7356)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.reflect.Method.invoke(Native Method)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:492)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:930)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: Caused by: java.lang.ClassNotFoundException: com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.classForName(Native Method)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.forName(Class.java:454)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at java.lang.Class.forName(Class.java:379)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	at vwm.<init>(PG:6)
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	... 20 more
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: Caused by: java.lang.ClassNotFoundException: com.android.libraries.tv.tvsystem.media.TvAudioManager
04-30 17:06:58.982  1544  1544 I katniss_interactor_SingletonModule: 	... 24 more
04-30 17:06:58.983  1544  1829 I katniss_interactor_HomeGraphUpdaterImpl: #update
04-30 17:06:58.987   731   731 W TelecomManager: Telecom Service not found.
04-30 17:06:58.987   731   731 I RoleManagerService: Granting default permissions...
04-30 17:06:58.991  3493  6102 W ExclusiveDefaultHolderMixin: Cannot get ApplicationInfo for default holder, config: config_defaultSms, package: com.android.messaging
04-30 17:06:58.991  2999  3124 E Finsky  : [277] [Counters] attempted to use a non-positive increment for: 4752
04-30 17:06:59.019   354   354 I netd    : trafficSwapActiveStatsMap() <51.14ms>
04-30 17:06:59.026   354   354 I netd    : tetherGetStats() <4.21ms>
04-30 17:06:59.028  2999  2999 W Finsky  : [2] STU: Failed to get storage stats for package 'com.google.android.rvis.tvts' (1601: Error getting stats: android.content.pm.PackageManager.NameNotFoundException)
04-30 17:06:59.030   731   731 I notification_cancel_all: [1000,731,com.google.android.rvis.tvts,0,0,0,5,NULL]
04-30 17:06:59.031   731   731 I ConditionProviders: Disallowing condition provider com.google.android.rvis.tvts
04-30 17:06:59.036   731   766 I commit_sys_config_file: [notification-policy,4]
04-30 17:06:59.038  1437  7652 I Blockstore: [PackageIntentOperation] Feature flags not enabled, skipping. [CONTEXT service_id=258 ]
04-30 17:06:59.038   731   772 W TextServicesManagerService: no available spell checker services found
04-30 17:06:59.039   731   888 I netstats_mobile_sample: [0,0,0,0,0,0,0,0,0,0,0,0,1777536419635]
04-30 17:06:59.039   731   888 I netstats_wifi_sample: [0,0,0,0,0,0,0,0,0,0,0,0,1777536419635]
04-30 17:06:59.043   731   894 D WifiConfigStore: Writing to stores completed in 15 ms.
04-30 17:06:59.043   731   894 E PasspointManager: No app ops listener found for com.google.android.rvis.tvts
04-30 17:06:59.043   731   894 I am_wtf  : [0,731,system_server,-1,PasspointManager,No app ops listener found for com.google.android.rvis.tvts]
04-30 17:06:59.046   731   894 I DropBoxManagerService: add tag=system_server_wtf isTagEnabled=true flags=0x2
04-30 17:06:59.055  1437  7656 W RWIPackageIntentOps: Registries cleared for package: com.google.android.rvis.tvts
04-30 17:06:59.058   731   772 W Searchables: No web search activity found
04-30 17:06:59.062  6134  6180 I katniss_search_SystemSearchableSources: createSources
04-30 17:06:59.068   731   911 I service_manager_slow: [32,telecom]
04-30 17:06:59.068   731   911 W TelecomManager: Telecom Service not found.
04-30 17:06:59.069  6134  6180 I katniss_search_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.VodSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.vod
04-30 17:06:59.069  6134  6180 I katniss_search_SystemSearchableSources: Not using source null
04-30 17:06:59.069  1544  1846 I katniss_interactor_SystemSearchableSources: createSources
04-30 17:06:59.070  6134  6180 I katniss_search_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.LiveSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.live
04-30 17:06:59.070  6134  6180 I katniss_search_SystemSearchableSources: Not using source null
04-30 17:06:59.071  6134  6180 I katniss_search_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.PeopleSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.people
04-30 17:06:59.071  1544  1846 I katniss_interactor_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.VodSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.vod
04-30 17:06:59.071  1544  1846 I katniss_interactor_SystemSearchableSources: Not using source null
04-30 17:06:59.071  6134  6180 I katniss_search_SystemSearchableSources: Not using source null
04-30 17:06:59.071  1544  1846 I katniss_interactor_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.LiveSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.live
04-30 17:06:59.072  1544  1846 I katniss_interactor_SystemSearchableSources: Not using source null
04-30 17:06:59.072  1544  1846 I katniss_interactor_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.PeopleSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.people
04-30 17:06:59.072  1544  1846 I katniss_interactor_SystemSearchableSources: Not using source null
04-30 17:06:59.073  1544  1846 I katniss_interactor_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.RelationSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.relation
04-30 17:06:59.073  1544  1846 I katniss_interactor_SystemSearchableSources: Not using source null
04-30 17:06:59.073   731   911 W TelecomManager: Telecom Service not found.
04-30 17:06:59.075   731   766 I commit_sys_config_file: [notification-policy,37]
04-30 17:06:59.077  6134  6180 I katniss_search_SystemSearchableSources:  com.skb.tv/com.skb.google.tv.searcherble.RelationSearcherbleActivity has bad suggestion authority com.tvstorm.tv.searchprovider.relation
04-30 17:06:59.077  6134  6180 I katniss_search_SystemSearchableSources: Not using source null
04-30 17:06:59.097  3866  6106 I Finsky:background: [370] Frosting DB delete succeeded: true
04-30 17:06:59.111  6134  6180 I katniss_search_PackageCache: Clearing package com.google.android.rvis.tvts from cache
04-30 17:06:59.113  3866  3904 I Finsky:background: [334] IQ:PSL: skipping onPackageRemoved(replacing=false) for untracked package=com.google.android.rvis.tvts
04-30 17:06:59.159  3866  6106 I Finsky:background: [370] Frosting DB delete succeeded: false
04-30 17:06:59.175  6134  6189 D WM-Processor: cnl: processing WorkGenerationalId(workSpecId=5d9af626-a1db-4a5a-aa49-96efc1a8cb7b, generation=0)
04-30 17:06:59.196  1544  1849 D WM-Processor: Processor cancelling 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b
04-30 17:06:59.196  1544  1849 D WM-Processor: WorkerWrapper could not be found for 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b
04-30 17:06:59.198  1544  1849 I WM-GreedyScheduler: Ignoring schedule request in non-main process
04-30 17:06:59.198  6134  6134 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=5d9af626-a1db-4a5a-aa49-96efc1a8cb7b, generation=0)
04-30 17:06:59.202  2999  5919 I Finsky  : [346] [ContentSync] finished, scheduled=false
04-30 17:06:59.220  1544  1849 D WM-SystemJobScheduler: Scheduling work ID 17afb41b-c338-4dfc-9b13-4d2995746d52Job ID 765
04-30 17:06:59.223  1544  1849 I WM-GreedyScheduler: Ignoring schedule request in a secondary process
04-30 17:06:59.230  1544  1849 D WM-Processor: Processor cancelling 17afb41b-c338-4dfc-9b13-4d2995746d52
04-30 17:06:59.230  1544  1849 D WM-Processor: WorkerWrapper could not be found for 17afb41b-c338-4dfc-9b13-4d2995746d52
04-30 17:06:59.231  1544  1849 I WM-GreedyScheduler: Ignoring schedule request in non-main process
04-30 17:06:59.246  6134  6134 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=17afb41b-c338-4dfc-9b13-4d2995746d52, generation=0)
04-30 17:06:59.247  6134  6134 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=17afb41b-c338-4dfc-9b13-4d2995746d52, generation=0)
04-30 17:06:59.259  1544  1849 D WM-SystemJobScheduler: Scheduling work ID 511197ad-d80c-4990-bb5d-e63a03bf06a5Job ID 766
04-30 17:06:59.266  1544  1849 I WM-GreedyScheduler: Ignoring schedule request in a secondary process
04-30 17:06:59.267  2999  3124 I Finsky  : [277] AIM: AppInfoManager-Perf > ItemModel > CacheSize=16, cacheHitCount=0, cacheMissCount=0, total appsWithNoServerDataCount=2. Missed  in cache (limit 10) : []
04-30 17:06:59.269  6134  6134 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=511197ad-d80c-4990-bb5d-e63a03bf06a5, generation=0)
04-30 17:06:59.318  6134  6169 D WM-GreedyScheduler: Cancelling work ID ecbe261d-fd44-4d9e-887f-fdb0bda731e5
04-30 17:06:59.326  1437  2254 I Icing   : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=null serviceId=30
04-30 17:06:59.337  1437  3531 I Icing   : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0
04-30 17:06:59.338  1437  3531 I Icing   : doRemovePackageData com.google.android.rvis.tvts
04-30 17:06:59.342  2999  3128 I Finsky  : [279] AIM: AppInfoManager-Perf > ItemModel > CacheSize=16, cacheHitCount=0, cacheMissCount=0, total appsWithNoServerDataCount=2. Missed  in cache (limit 10) : []
04-30 17:06:59.346   731  7175 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.impl.PackageMonitorReceiverImpl$RegisteredReceiver
04-30 17:06:59.350   731   773 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.backgroundimpl.BackgroundPackageMonitorReceiverImpl$RegisteredReceiver
04-30 17:06:59.350   731   773 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.instantapps.appmanagement.InstantAppRemoveMonitor
04-30 17:06:59.350   731   773 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
04-30 17:06:59.350   731   773 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.rvis.tvts flg=0x4000010 (has extras) } to com.vasott.leanbacklauncher.partnercustomizer/.PartnerReceiver
04-30 17:06:59.366  1718  7700 I MediaProvider: Begin Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.rvis.tvts flg=0x5000010 cmp=com.android.providers.media/.MediaService (has extras) }
04-30 17:06:59.366  1718  7700 I MediaProvider: End Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.rvis.tvts flg=0x5000010 cmp=com.android.providers.media/.MediaService (has extras) }
04-30 17:06:59.375  2999  3128 I Finsky  : [279] AIM: AppInfoManager-Perf > getApps > data collection finished
04-30 17:06:59.375  2999  3128 I Finsky  : [279] AIM: AppInfoManager-Perf > maybeDestroyAppInfoManager is called. actives = 0
04-30 17:06:59.376  1437  3531 I Icing   : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0
04-30 17:06:59.380  1437  3531 I Icing   : Indexing com.google.android.gms-apps from com.google.android.gms
04-30 17:06:59.386  1437  1451 I gle.android.gm: Background young concurrent copying GC freed 23329(1656KB) AllocSpace objects, 7(140KB) LOS objects, 15% free, 6980KB/8212KB, paused 5.651ms total 249.954ms
04-30 17:06:59.392  1437  3531 I Icing   : Indexing done com.google.android.gms-apps
04-30 17:06:59.394  1437  3531 I Icing   : Indexing com.google.android.gms-apps from com.google.android.gms
04-30 17:06:59.394  2999  2999 I Finsky  : [2] qud - Received: android.intent.action.PACKAGE_FULLY_REMOVED, [VtrAa0lu_NybIid-od-G5EsC8hfTPTz0g-whbz0qQcE]
04-30 17:06:59.396  2999  2999 I Finsky  : [2] qud - Deduping intent android.intent.action.PACKAGE_FULLY_REMOVED
04-30 17:06:59.396  1437  3531 I Icing   : Indexing done com.google.android.gms-apps
04-30 17:06:59.396  2999  3086 I Finsky  : [266] RECEIVER_PACKAGE_MONITOR#logWorkEndAndFinishGoAsync: SUCCESS
04-30 17:06:59.399  3866  3866 I Finsky:background: [2] qtt - Received: android.intent.action.PACKAGE_FULLY_REMOVED, [VtrAa0lu_NybIid-od-G5EsC8hfTPTz0g-whbz0qQcE]
04-30 17:06:59.401  3866  3866 I Finsky:background: [2] Package no longer installed: com.google.android.rvis.tvts
04-30 17:06:59.402  3866  3866 I Finsky:background: [2] qtt - Deduping intent android.intent.action.PACKAGE_FULLY_REMOVED
04-30 17:06:59.403  3866  3901 I Finsky:background: [331] RECEIVER_PACKAGE_MONITOR_BACKGROUND#logWorkEndAndFinishGoAsync: SUCCESS
04-30 17:06:59.407  3866  6106 I Finsky:background: [370] Frosting DB delete succeeded: false
04-30 17:06:59.425  3866  3908 I Finsky:background: [338] RECEIVER_ENGAGE_PACKAGE_CHANGED#logWorkEndAndFinishGoAsync: SUCCESS
04-30 17:06:59.435  1437  7656 W AppInstallOperation: FDL Migration::InstallIntentOperation by Appinvite Module [CONTEXT service_id=77 ]
04-30 17:06:59.439   731  3912 I am_uid_active: 10053
04-30 17:06:59.442  6134  6169 D WM-GreedyScheduler: Starting tracking for 511197ad-d80c-4990-bb5d-e63a03bf06a5
04-30 17:06:59.450  2999  3012 I android.vendin: Background concurrent copying GC freed 44504(2491KB) AllocSpace objects, 1(20KB) LOS objects, 49% free, 5453KB/10MB, paused 3.897ms total 1.216s
04-30 17:06:59.452  2999  3014 E System  : Uncaught exception thrown by finalizer
04-30 17:06:59.457   731   772 I commit_sys_config_file: [roles-0,3]
04-30 17:06:59.457   731   772 I RoleUserState: Wrote roles.xml successfully
04-30 17:06:59.460  2999  3014 E System  : java.lang.NullPointerException: Attempt to invoke virtual method 'void android.content.res.StringBlock.close()' on a null object reference
04-30 17:06:59.460  2999  3014 E System  : 	at android.content.res.ApkAssets.close(ApkAssets.java:195)
04-30 17:06:59.460  2999  3014 E System  : 	at android.content.res.ApkAssets.finalize(ApkAssets.java:185)
04-30 17:06:59.460  2999  3014 E System  : 	at java.lang.Daemons$FinalizerDaemon.doFinalize(Daemons.java:289)
04-30 17:06:59.460  2999  3014 E System  : 	at java.lang.Daemons$FinalizerDaemon.runInternal(Daemons.java:276)
04-30 17:06:59.460  2999  3014 E System  : 	at java.lang.Daemons$Daemon.run(Daemons.java:137)
04-30 17:06:59.460  2999  3014 E System  : 	at java.lang.Thread.run(Thread.java:919)
04-30 17:06:59.467  6134  6189 D WM-Processor: WorkerWrapper interrupted for 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b
04-30 17:06:59.467  6134  6189 D WM-StopWorkRunnable: StopWorkRunnable for 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b; Processor.stopWork = true
04-30 17:06:59.488  6134  6189 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=17afb41b-c338-4dfc-9b13-4d2995746d52, generation=0)
04-30 17:06:59.488  6134  6134 D WM-SystemJobService: 17afb41b-c338-4dfc-9b13-4d2995746d52 executed on JobScheduler
04-30 17:06:59.491  6134  6189 D WM-StopWorkRunnable: StopWorkRunnable for 17afb41b-c338-4dfc-9b13-4d2995746d52; Processor.stopWork = false
04-30 17:06:59.501  6134  6189 D WM-Processor: cnl: processing WorkGenerationalId(workSpecId=511197ad-d80c-4990-bb5d-e63a03bf06a5, generation=0)
04-30 17:06:59.519  6134  6189 D WM-GreedyScheduler: Cancelling work ID 17afb41b-c338-4dfc-9b13-4d2995746d52
04-30 17:06:59.549  6134  6189 D WM-GreedyScheduler: Starting tracking for 511197ad-d80c-4990-bb5d-e63a03bf06a5
04-30 17:06:59.571  6134  6189 D WM-WorkerWrapper: Status for 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b is null ; not doing any work
04-30 17:06:59.587  6134  6189 D WM-WorkConstraintsTrack: NetworkRequestConstraintController register callback
04-30 17:06:59.589  6134  6134 D WM-Processor: cnl 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b executed; reschedule = false
04-30 17:06:59.590  6134  6134 D WM-SystemJobService: 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b executed on JobScheduler
04-30 17:06:59.592  7711  7711 D TradefedEventsTag: ==================== com.google.android.smoketests.tvts.TvtsSmokeTest RUN STARTED: Thu Apr 30 17:07:00 KST 2026 ====================
04-30 17:06:59.593  6134  6253 D WM-WorkConstraintsTrack: NetworkRequestConstraintController onCapabilitiesChanged callback
04-30 17:06:59.621  6134  6184 D WM-GreedyScheduler: Cancelling work ID 5d9af626-a1db-4a5a-aa49-96efc1a8cb7b
04-30 17:06:59.668  6134  6134 D WM-WorkerWrapper: Starting work for com.google.apps.tiktok.contrib.work.TikTokListenableWorker
04-30 17:06:59.670  6134  6134 I katniss_search_HomeGraphUpdateWorker: #startWork
04-30 17:06:59.671  6134  6180 I katniss_search_CastServiceInfoImpl: startLoadingCastInfo
04-30 17:06:59.671  6134  6180 I katniss_search_CastServiceInfoImpl: Cast info is already loaded.
04-30 17:06:59.672  6134  6180 I katniss_search_FcmDeviceSettingsHelperImpl: No need to update Assistant settings as user is not signed in or is not opted in to assistant.
04-30 17:06:59.672  6134  6180 I katniss_search_HomeGraphUpdateWorker: HomeGraphUpdateWorker succeeded.
04-30 17:06:59.673  6134  6184 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=511197ad-d80c-4990-bb5d-e63a03bf06a5, generation=0)
04-30 17:06:59.694  6134  6184 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=511197ad-d80c-4990-bb5d-e63a03bf06a5, tags={ com.google.apps.tiktok.contrib.work.TikTokListenableWorker,TikTokWorker#com.google.android.apps.tvsearch.homegraph.HomeGraphUpdateWorker } ]
04-30 17:06:59.729  6134  6184 D WM-Processor: Work WorkGenerationalId(workSpecId=511197ad-d80c-4990-bb5d-e63a03bf06a5, generation=0) is already enqueued for processing
04-30 17:06:59.749  6134  6134 D WM-Processor: cnl 511197ad-d80c-4990-bb5d-e63a03bf06a5 executed; reschedule = false
04-30 17:06:59.749  6134  6134 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=511197ad-d80c-4990-bb5d-e63a03bf06a5, generation=0)
04-30 17:06:59.749  6134  6134 D WM-SystemJobService: 511197ad-d80c-4990-bb5d-e63a03bf06a5 executed on JobScheduler
04-30 17:06:59.753  6134  6188 D WM-GreedyScheduler: Cancelling work ID 511197ad-d80c-4990-bb5d-e63a03bf06a5
04-30 17:06:59.785  6134  6188 D WM-WorkConstraintsTrack: NetworkRequestConstraintController unregister callback
04-30 17:06:59.850  7717  7717 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 17:07:00 KST 2026 ====================
04-30 17:07:00.002  4313  4313 I AmpService: currentHour=17
04-30 17:07:00.002  4313  4313 I AmpService: currentDate=20260430
04-30 17:07:00.002  4313  4313 I AmpService: UpdateDate=
04-30 17:07:00.002  4313  4313 I commFunc: ===>get_property: sys.stb.on
04-30 17:07:00.002  4313  4313 I commFunc: [/system/bin/sh, -c, getprop sys.stb.on]
04-30 17:07:00.026  4313  4313 I commFunc: CMD REP:1
04-30 17:07:00.027  4313  4313 I commFunc: getOut=1, length=1
04-30 17:07:00.027  4313  4313 I AmpService: screenOn=true
