
========== beginning of new [logcat] output ==========
--------- beginning of system
04-30 11:53:52.675   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.leanbackjank flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.backgroundimpl.BackgroundPackageMonitorReceiverImpl$RegisteredReceiver
04-30 11:53:52.675   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.leanbackjank flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.instantapps.appmanagement.InstantAppRemoveMonitor
04-30 11:53:52.675   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.leanbackjank flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
04-30 11:53:52.675   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.google.android.leanbackjank flg=0x4000010 (has extras) } to com.vasott.leanbacklauncher.partnercustomizer/.PartnerReceiver
--------- beginning of main
04-30 11:53:52.688  6329 15420 I MediaProvider: Begin Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.leanbackjank flg=0x5000010 cmp=com.android.providers.media/.MediaService (has extras) }
04-30 11:53:52.689  6329 15420 I MediaProvider: End Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.google.android.leanbackjank flg=0x5000010 cmp=com.android.providers.media/.MediaService (has extras) }
04-30 11:53:52.700 18487 18521 D WM-WorkConstraintsTrack: NetworkRequestConstraintController unregister callback
04-30 11:53:52.713 18487 18487 D WM-WorkerWrapper: Starting work for com.google.apps.tiktok.contrib.work.TikTokListenableWorker
04-30 11:53:52.715 18487 18487 I katniss_search_HomeGraphUpdateWorker: #startWork
04-30 11:53:52.716 18487 18535 I katniss_search_CastServiceInfoImpl: startLoadingCastInfo
04-30 11:53:52.716 18487 18535 I katniss_search_CastServiceInfoImpl: Cast info is already loaded.
04-30 11:53:52.716 18487 18535 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 11:53:52.716 18487 18535 I katniss_search_HomeGraphUpdateWorker: HomeGraphUpdateWorker succeeded.
04-30 11:53:52.717 15303 15366 I Finsky  : [1819] SCH: Satisfied jobs for 9105 are: 12-1
04-30 11:53:52.723 18487 18521 D WM-WorkConstraintsTrack: NetworkRequestConstraintController register callback
04-30 11:53:52.725 18487 27131 D WM-WorkConstraintsTrack: NetworkRequestConstraintController onCapabilitiesChanged callback
04-30 11:53:52.727 18487 18521 D WM-GreedyScheduler: Cancelling work ID 53652eb3-6246-443f-8daf-9b067c563d64
04-30 11:53:52.729 15303 15366 I Finsky  : [1819] SCH: Satisfied jobs for 9108 are: 20-23232323
04-30 11:53:52.732 15303 15395 I Finsky  : [1836] SCH: Job 10-3 starting
04-30 11:53:52.735 15303 15366 I Finsky  : [1819] [ContentSync] finished, scheduled=false
04-30 11:53:52.735 15303 15303 I Finsky  : [2] qud - Received: android.intent.action.PACKAGE_FULLY_REMOVED, [0rwZ0W7wBmYSdfnPYCWrG3iLP718quftzmcgPrR0nkE]
04-30 11:53:52.740 15303 15303 I Finsky  : [2] Clearing split related stale data.
04-30 11:53:52.741 15303 15347 I Finsky  : [1809] AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=0, cacheMissCount=1. Missed  in cache (limit 10) : [com.google.android.leanbackjank]
04-30 11:53:52.741 15303 15303 I Finsky  : [2] AIM: AppInfoCacheUpdater -> invalidating apps: [com.google.android.leanbackjank]
04-30 11:53:52.742 15303 15366 I Finsky  : [1819] [ContentSync] finished, scheduled=false
04-30 11:53:52.742 15303 15347 I Finsky  : [1809] AIM: Got app ownership map. App counts: . Unique apps: 0
04-30 11:53:52.742 15303 15347 E Finsky  : [1809] [Counters] attempted to use a non-positive increment for: 4752
04-30 11:53:52.744 15303 15303 I Finsky  : [2] AIM: AppInfoManager-Perf > getApps > called for 1 apps
04-30 11:53:52.746 15303 15353 I Finsky  : [1812] AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=0, cacheMissCount=1. Missed  in cache (limit 10) : [com.google.android.leanbackjank]
04-30 11:53:52.746 15303 15353 I Finsky  : [1812] AIM: Got app ownership map. App counts: . Unique apps: 0
04-30 11:53:52.746 15303 15353 E Finsky  : [1812] [Counters] attempted to use a non-positive increment for: 4752
04-30 11:53:52.748 15303 15353 I Finsky  : [1812] AIM: AppInfoManager-Perf > ItemModel > CacheSize=0, cacheHitCount=0, cacheMissCount=0, total appsWithNoServerDataCount=0. Missed  in cache (limit 10) : []
04-30 11:53:52.750 15430 15430 D TradefedEventsTag: ==================== com.google.android.lmk.gtv.GtvLmkCujTest RUN STARTED: Thu Apr 30 11:53:53 KST 2026 ====================
04-30 11:53:52.752 15303 15350 I Finsky  : [1811] AIM: AppInfoManager-Perf > ItemModel > CacheSize=0, cacheHitCount=0, cacheMissCount=0, total appsWithNoServerDataCount=0. Missed  in cache (limit 10) : []
04-30 11:53:52.756 18487 18521 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=d6893c8d-9980-4917-9b91-31f36dae462d, tags={ com.google.apps.tiktok.contrib.work.TikTokListenableWorker,TikTokWorker#com.google.android.apps.tvsearch.homegraph.HomeGraphUpdateWorker } ]
04-30 11:53:52.760 15303 15395 I Finsky  : [1836] SCH: Job 10-8 starting
04-30 11:53:52.762 15303 15395 I Finsky  : [1836] SCH: Job 10-87 starting
04-30 11:53:52.763 15303 15395 I Finsky  : [1836] SCH: Job 10-132 starting
04-30 11:53:52.766 15303 15384 I Finsky  : [1830] Asset module storage cleared for package com.google.android.leanbackjank.
04-30 11:53:52.769 15303 15328 I Finsky  : [1798] RECEIVER_PACKAGE_MONITOR#logWorkEndAndFinishGoAsync: SUCCESS
04-30 11:53:52.770 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-3
04-30 11:53:52.773 14637 14637 I Finsky:background: [2] qtt - Received: android.intent.action.PACKAGE_FULLY_REMOVED, [0rwZ0W7wBmYSdfnPYCWrG3iLP718quftzmcgPrR0nkE]
04-30 11:53:52.777 14637 14637 I Finsky:background: [2] Package no longer installed: com.google.android.leanbackjank
04-30 11:53:52.779 14637 14637 I Finsky:background: [2] qtt - Deduping intent android.intent.action.PACKAGE_FULLY_REMOVED
04-30 11:53:52.780 14637 15277 I Finsky:background: [1841] Frosting DB delete succeeded: false
04-30 11:53:52.780 18487 18487 D WM-Processor: cnl d6893c8d-9980-4917-9b91-31f36dae462d executed; reschedule = false
04-30 11:53:52.780 14637 14676 I Finsky:background: [1803] RECEIVER_PACKAGE_MONITOR_BACKGROUND#logWorkEndAndFinishGoAsync: SUCCESS
04-30 11:53:52.780 18487 18487 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=d6893c8d-9980-4917-9b91-31f36dae462d, generation=0)
04-30 11:53:52.781 18487 18487 D WM-SystemJobService: d6893c8d-9980-4917-9b91-31f36dae462d executed on JobScheduler
04-30 11:53:52.786 15303 15303 W Finsky  : [2] STU: Failed to get storage stats for package 'com.google.android.leanbackjank' (1601: Error getting stats: android.content.pm.PackageManager.NameNotFoundException)
04-30 11:53:52.787 15303 15389 I PlayCore: UID: [10053]  PID: [15303] StandardIntegrity : warmUpIntegrityToken(815409574534)
04-30 11:53:52.787 14637 14679 I Finsky:background: [1806] RECEIVER_ENGAGE_PACKAGE_CHANGED#logWorkEndAndFinishGoAsync: SUCCESS
04-30 11:53:52.787 15303 15434 I PlayCore: UID: [10053]  PID: [15303] StandardIntegrity : Initiate binding to the service.
04-30 11:53:52.788 15303 15350 I Finsky  : [1811] AIM: AppInfoManager-Perf > getApps > data collection finished
04-30 11:53:52.788 15303 15350 I Finsky  : [1811] AIM: AppInfoManager-Perf > maybeDestroyAppInfoManager is called. actives = 0
04-30 11:53:52.789 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-8
04-30 11:53:52.795 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-87
04-30 11:53:52.797 15303 15303 I Finsky  : [2] [PLUS] Hygiene sync job starting...
04-30 11:53:52.799 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-132
04-30 11:53:52.801 15303 15345 I Finsky  : [1808] [UpdateFallbackAuth] Updating fallback purchase auth method...
04-30 11:53:52.809  4627 15301 W AppInstallOperation: FDL Migration::InstallIntentOperation by Appinvite Module [CONTEXT service_id=77 ]
04-30 11:53:52.809 15303 15303 I PlayCore: UID: [10053]  PID: [15303] StandardIntegrity : ServiceConnectionImpl.onServiceConnected(ComponentInfo{com.android.vending/com.google.android.finsky.expressintegrityservice.ExpressIntegrityService})
04-30 11:53:52.810 18487 18521 D WM-GreedyScheduler: Cancelling work ID d6893c8d-9980-4917-9b91-31f36dae462d
04-30 11:53:52.813 15303 15389 I Finsky  : [1832] Running CheckAppUpdatesTask
04-30 11:53:52.817  4397  4397 D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true
04-30 11:53:52.819 15303 15350 I Finsky  : [1811] VerifyApps: CheckAppUpdatesTask was run.
04-30 11:53:52.828 15303 15345 I Finsky  : [1808] [UpdateFallbackAuth] Successfully updated fallback purchase auth method.
04-30 11:53:52.829 15303 15345 I Finsky  : [1808] SCH: jobFinished: 10-132. TimeElapsed: 31ms.
04-30 11:53:52.829 15303 15345 I Finsky  : [1808] WM::SCH: Logging work end for 10-132
04-30 11:53:52.830 15303 15434 I PlayCore: UID: [10053]  PID: [15303] StandardIntegrity : linkToDeath
04-30 11:53:52.842 15303 15345 I Finsky  : [1808] VerifyApps: CheckAppUpdatesTask found 0 Play-installed PHAs with available updates
04-30 11:53:52.842 18487 18521 D WM-WorkConstraintsTrack: NetworkRequestConstraintController unregister callback
04-30 11:53:52.847 15303 15434 I Finsky  : [1855] playLoggingServerUrl: https://play.googleapis.com/play/log
04-30 11:53:52.847 15303 15434 I Finsky  : [1855] playLoggingServerTimestampUrl: https://play.googleapis.com/play/log/timestamp
04-30 11:53:52.859 15303 15353 I Finsky  : [1812] VerifyApps: Verifying installed packages (AutoScan)
04-30 11:53:52.861 15303 15331 I Finsky  : [1801] [PLUS] Adding payload case=22
04-30 11:53:52.868 15303 15442 I Finsky  : [1859] SM: There are no stale sessions to be pruned
04-30 11:53:52.888 15303 15303 I Finsky  : [2] SCH: Job 10-135 starting
04-30 11:53:52.893 15303 15303 I Finsky  : [2] Connecting InstallListener to SplitInstallService broadcaster...
04-30 11:53:52.906 15303 15347 I Finsky  : [1809] System was updated or GL extensions were already cached.
04-30 11:53:52.913 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-135
04-30 11:53:52.914 15303 15303 I Finsky  : [2] SUHJ: self-update hygiene task started
04-30 11:53:52.915 15303 15327 I Finsky  : [1797] RECEIVER_SINGLE_USER_SETTINGS#logWorkEndAndFinishGoAsync: SUCCESS
04-30 11:53:52.928 15303 15347 W Finsky  : [1809] Phonesky doesn't have read phone state permission
04-30 11:53:52.953 29895 29895 D BoundBrokerSvc: onRebind: Intent { act=com.google.android.gms.apperrors.service.START_APP_ERROR dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService }
04-30 11:53:52.986 15303 15347 I Finsky  : [1809] UChk: sending requests to /autoUpdate
04-30 11:53:53.028 15303 15347 I Finsky  : [1809] Set UnhibernateActivity enabled state to 1
04-30 11:53:53.032 15303 15347 I Finsky  : [1809] SysCUA: Set {com.android.vending/com.google.android.finsky.systemcomponentupdateui.common.SystemComponentUpdateActivity} enabled state to 0
04-30 11:53:53.052 29895 29959 W PhBaseOp: Phenotype API error. Event: # dlhp@9c0ef7c3, EventCode: GET_STORAGE_INFO [CONTEXT service_id=51 ]
04-30 11:53:53.052 29895 29959 W PhBaseOp: bykj: 29514: Storage info not created for Play Store.
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at byos.b(:com.google.android.gms@261533115@26.15.33 (080306-900762463):1407)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at bynh.i(:com.google.android.gms@261533115@26.15.33 (080306-900762463):13)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at bymd.h(:com.google.android.gms@261533115@26.15.33 (080306-900762463):32)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at bymd.f(:com.google.android.gms@261533115@26.15.33 (080306-900762463):14)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at bbtn.eY(:com.google.android.gms@261533115@26.15.33 (080306-900762463):1)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at bbtv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):115)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at csgt.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):23)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at aixi.c(:com.google.android.gms@261533115@26.15.33 (080306-900762463):50)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at aixi.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):66)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at ajcv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):8)
04-30 11:53:53.052 29895 29959 W PhBaseOp: 	at java.lang.Thread.run(Thread.java:919)
04-30 11:53:53.057 29895 29959 W AsyncOperation: operation=GetStorageInfoGmsOperation, opStatusCode=29514 [CONTEXT service_id=51 ]
04-30 11:53:53.057 29895 29959 W AsyncOperation: OperationException[Status{statusCode=Storage info not created for Play Store., resolution=null}]
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at bymd.h(:com.google.android.gms@261533115@26.15.33 (080306-900762463):74)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at bymd.f(:com.google.android.gms@261533115@26.15.33 (080306-900762463):14)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at bbtn.eY(:com.google.android.gms@261533115@26.15.33 (080306-900762463):1)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at bbtv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):115)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at csgt.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):23)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at aixi.c(:com.google.android.gms@261533115@26.15.33 (080306-900762463):50)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at aixi.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):66)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at ajcv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):8)
04-30 11:53:53.057 29895 29959 W AsyncOperation: 	at java.lang.Thread.run(Thread.java:919)
04-30 11:53:53.079 29895 29895 D BoundBrokerSvc: onRebind: Intent { act=com.google.android.gms.common.telemetry.service.START dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService }
04-30 11:53:53.083 15303 15350 I Finsky  : [1811] Created new item store.
04-30 11:53:53.091 29895 29959 W PhBaseOp: Phenotype API error. Event: # dlhp@eef8b9b7, EventCode: COMMIT_CONFIG [CONTEXT service_id=51 ]
04-30 11:53:53.091 29895 29959 W PhBaseOp: bykk: 29542: Stale snapshot for com.google.android.gms.droidguardclient#com.android.vending(new configuration available)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bymi.d(:com.google.android.gms@261533115@26.15.33 (080306-900762463):338)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bymi.b(:com.google.android.gms@261533115@26.15.33 (080306-900762463):44)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bymh.i(:com.google.android.gms@261533115@26.15.33 (080306-900762463):3)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bymd.h(:com.google.android.gms@261533115@26.15.33 (080306-900762463):32)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bymd.f(:com.google.android.gms@261533115@26.15.33 (080306-900762463):14)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bbtn.eY(:com.google.android.gms@261533115@26.15.33 (080306-900762463):1)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at bbtv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):115)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at csgt.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):23)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at aixi.c(:com.google.android.gms@261533115@26.15.33 (080306-900762463):50)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at aixi.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):66)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at ajcv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):8)
04-30 11:53:53.091 29895 29959 W PhBaseOp: 	at java.lang.Thread.run(Thread.java:919)
04-30 11:53:53.092 29895 29959 W AsyncOperation: operation=CommitToConfigurationOperationCall, opStatusCode=29542 [CONTEXT service_id=51 ]
04-30 11:53:53.092 29895 29959 W AsyncOperation: OperationException[Status{statusCode=Stale snapshot for com.google.android.gms.droidguardclient#com.android.vending(new configuration available), resolution=null}]
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at bymd.h(:com.google.android.gms@261533115@26.15.33 (080306-900762463):62)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at bymd.f(:com.google.android.gms@261533115@26.15.33 (080306-900762463):14)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at bbtn.eY(:com.google.android.gms@261533115@26.15.33 (080306-900762463):1)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at bbtv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):115)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at csgt.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):23)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at aixi.c(:com.google.android.gms@261533115@26.15.33 (080306-900762463):50)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at aixi.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):66)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at ajcv.run(:com.google.android.gms@261533115@26.15.33 (080306-900762463):8)
04-30 11:53:53.092 29895 29959 W AsyncOperation: 	at java.lang.Thread.run(Thread.java:919)
04-30 11:53:53.182   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.183   530   530 I keystore: del USRSKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.183   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.183   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() length=5
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() params[0].tag = KM_TAG_KEY_SIZE keysize=0x100
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() params[1].tag = KM_TAG_ALGORITHM, algo = 0x3
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() params[2].tag = KM_TAG_PURPOSE, purpose=0x2
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() invalid params[3].tag = 0x20000005
04-30 11:53:53.185   245   245 D SYNAKeyMaster: ValidateKeyParam() params[4].tag = KM_TAG_NO_AUTH_REQUIRED
04-30 11:53:53.218 15303 15410 D NetworkSecurityConfig: No Network Security Config specified, using platform default
04-30 11:53:53.244   530 15472 D DropBoxManager: About to call service->add()
04-30 11:53:53.244   745 23512 I DropBoxManagerService: add tag=keymaster isTagEnabled=true flags=0x0
04-30 11:53:53.249   530 15472 D DropBoxManager: service->add returned No error
04-30 11:53:53.299   245   245 D SYNAKeyMaster: ValidateKeyParam() length=3
04-30 11:53:53.299   245   245 D SYNAKeyMaster: ValidateKeyParam() params[0].tag = KM_TAG_ATTESTATION_CHALLENGE(blob.data_length=0x10)
04-30 11:53:53.299   245   245 D SYNAKeyMaster: ValidateKeyParam() invalid params[1].tag = 0x700003ec
04-30 11:53:53.299   245   245 D SYNAKeyMaster: ValidateKeyParam() params[2].tag = KM_TAG_ATTESTATION_APPLICATION_ID (blob.data_length=0x91)
04-30 11:53:53.347   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.349   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.351   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3701001979799185126 10044
04-30 11:53:53.352   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.B3701001979799185126 10044
04-30 11:53:53.352   530   530 I keystore: del USRSKEY_unstable.c7202ece89390c490b1b94d5b71225e1.B3701001979799185126 10044
04-30 11:53:53.352   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.B3701001979799185126 10044
04-30 11:53:53.353   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.B3701001979799185126 10044
04-30 11:53:53.403 15487 15487 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 11:53:54 KST 2026 ====================
04-30 11:53:53.490 15303 15303 I Finsky  : [2] UCtl: autoUpdateItems with size=0 is fulfilled from responseFuture
04-30 11:53:53.491 15303 15303 I Finsky  : [2] UChk: Mainline update is disabled
04-30 11:53:53.492 15303 15303 I Finsky  : [2] SCH: jobFinished: 10-135. TimeElapsed: 578ms.
04-30 11:53:53.492 15303 15303 I Finsky  : [2] WM::SCH: Logging work end for 10-135
04-30 11:53:53.499 15303 15303 I Finsky  : [2] SCH: Job 10-139 starting
04-30 11:53:53.500 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-139
04-30 11:53:53.500 15303 15303 I Finsky  : [2] [WM] Starting Hygiene job.
04-30 11:53:53.546 15303 15492 I Finsky  : [1872] [WM] Flush complete with 2 successes and 0 failures.
04-30 11:53:53.559 15303 15492 I Finsky  : [1872] SCH: jobFinished: 10-139. TimeElapsed: 60ms.
04-30 11:53:53.563 15303 15492 I Finsky  : [1872] WM::SCH: Logging work end for 10-139
04-30 11:53:53.591 15303 15303 I Finsky  : [2] SCH: Job 10-146 starting
04-30 11:53:53.592 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-146
04-30 11:53:53.653   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
04-30 11:53:53.654   496   496 W adbd    : timeout expired while flushing socket, closing
04-30 11:53:53.708 15303 15350 I Finsky  : [1811] AU2: scheduling auto update v2 with 0 apps
04-30 11:53:53.776 15303 15347 I Finsky  : [1809] SCH: jobFinished: 10-146. TimeElapsed: 184ms.
04-30 11:53:53.778 15303 15347 I Finsky  : [1809] WM::SCH: Logging work end for 10-146
04-30 11:53:53.847 15303 15303 I Finsky  : [2] SCH: Job 10-162 starting
04-30 11:53:53.847 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 10-162
04-30 11:53:53.847 15303 15303 I Finsky  : [2] IQ::HLD: Check if we have an active install holdoff we should resolve.
04-30 11:53:53.847 15303 15303 I Finsky  : [2] IQ::HLD: Get the following profiles from PauseUpdatesProfilesValueStore [].
04-30 11:53:53.848 15303 15303 I Finsky  : [2] IQ::HLD: No holdoff check needed - no pausing profiles on device.
04-30 11:53:53.859 15303 15303 I Finsky  : [2] setup::P2P: P2P cache cleanup skipped: no finish timestamp or delta not elapsed.
04-30 11:53:53.912 15303 15513 I Finsky  : [1873] setup::RES: Restore dumpsys cleanup succeeded.
04-30 11:53:53.914 15303 15513 I Finsky  : [1873] SCH: jobFinished: 10-162. TimeElapsed: 68ms.
04-30 11:53:53.915 15303 15513 I Finsky  : [1873] WM::SCH: Logging work end for 10-162
04-30 11:53:53.924 15303 15303 I Finsky  : [2] SCH: Job 20-23232323 starting
04-30 11:53:53.924 15303 15303 I Finsky  : [2] WM::SCH: Logging work start for 20-23232323
04-30 11:53:53.930 15303 15303 I Finsky  : [2] Running ArtProfilesUploadJob
04-30 11:53:54.002 15303 15514 I Finsky  : [1874] PSR: Package cache is empty, setup cache.
04-30 11:53:54.144 15521 15521 D TradefedEventsTag: ==================== com.google.android.gtv.scorecard.LauncherXLaunchTimeHostTest RUN STARTED: Thu Apr 30 11:53:55 KST 2026 ====================
04-30 11:53:54.156   745   759 I system_server: Background young concurrent copying GC freed 60763(5086KB) AllocSpace objects, 31(1464KB) LOS objects, 31% free, 13MB/19MB, paused 283us total 138.813ms
04-30 11:53:54.702 13127 13922 W SystemServiceRegistry: No service published for: persistent_data_block
04-30 11:53:54.703   496   496 W adbd    : timeout expired while flushing socket, closing
04-30 11:53:54.724   398 15564 E ResolverController: No valid NAT64 prefix (100, <unspecified>/0)
04-30 11:53:54.819 15303 15514 W Finsky  : [1874] SLM: no metadata property com.android.vending.derived.apk.id found for shared library org.chromium.trichromelibrary:
04-30 11:53:54.819 15303 15514 W Finsky  : [1874] SLM: no metadata property com.android.vending.sdk.version.patch found for shared library org.chromium.trichromelibrary:
04-30 11:53:54.837 15303 15514 W Finsky  : [1874] SLM: no metadata property com.android.vending.derived.apk.id found for shared library com.google.android.trichromelibrary_604519330:
04-30 11:53:54.837 15303 15514 W Finsky  : [1874] SLM: no metadata property com.android.vending.sdk.version.patch found for shared library com.google.android.trichromelibrary_604519330:
--------- beginning of events
04-30 11:53:55.021   745   788 I sysui_multi_action: [757,1717,758,1,759,0]
04-30 11:53:55.024   496   496 W adbd    : timeout expired while flushing socket, closing
04-30 11:53:55.027   745   788 I sysui_multi_action: [757,1717,758,2,759,0]
04-30 11:53:55.028   745   788 I sysui_multi_action: [757,1717,758,3,759,0]
04-30 11:53:55.123 15303 15353 W Settings: Setting install_non_market_apps has moved from android.provider.Settings.Global to android.provider.Settings.Secure, returning read-only value.
04-30 11:53:55.218   441   441 D PIX_MCM : STB ID is Invalid!!!!!!!!!!!!!!!!!!!
04-30 11:53:55.356 15607 15607 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 11:53:56 KST 2026 ====================
04-30 11:53:55.421 15610 15610 D TradefedEventsTag: ==================== com.google.android.gtv.scorecard.LauncherXJankPercentHostTest RUN STARTED: Thu Apr 30 11:53:56 KST 2026 ====================
04-30 11:53:55.441  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: invalid ip & invalid Stbid file: SmartRCUAgent.java >> LINE: 529
04-30 11:53:55.441  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getMacAddress start file: SmartRcuAgentEnvironment.java >> LINE: 449
04-30 11:53:55.442  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getMacAddress btv_home/config/mac_addr : a8:93:4a:fe:b9:67 file: SmartRcuAgentEnvironment.java >> LINE: 463
04-30 11:53:55.442  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getStbId start file: SmartRcuAgentEnvironment.java >> LINE: 281
04-30 11:53:55.442  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getStbId file not exists file: SmartRcuAgentEnvironment.java >> LINE: 316
04-30 11:53:55.442  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getFirmwareVersion start file: SmartRcuAgentEnvironment.java >> LINE: 236
04-30 11:53:55.443  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getFirmwareVersion btv_home/config/version.txt : 19.561.113 file: SmartRcuAgentEnvironment.java >> LINE: 251
04-30 11:53:55.443  2171  2250 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : start  >> file: SmartRcuAgentEnvironment.java >> LINE: 322>> TID: 171
04-30 11:53:55.444  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : start isStbId : false, isStbMac : true, isStbCode : true, isStbPatchVersion : true, isStbIp : false file: SmartRcuAgentEnvironment.java >> LINE: 351
04-30 11:53:55.447  2171  2250 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : start /data/btv_home/config/tvservice-system-properties.xml >> file: SmartRcuAgentEnvironment.java >> LINE: 359>> TID: 171
04-30 11:53:55.447  2171  2250 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : childeren.length : 409 >> file: SmartRcuAgentEnvironment.java >> LINE: 366>> TID: 171
04-30 11:53:55.447  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : CHILDRENSEELIMIT, value : 18 file: SmartRcuAgentEnvironment.java >> LINE: 378
04-30 11:53:55.447  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : STBID, value : , isStbId : false file: SmartRcuAgentEnvironment.java >> LINE: 396
04-30 11:53:55.447  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : STB_SW_VERSION, value : 19.543.70, isStbPatchVersion : true file: SmartRcuAgentEnvironment.java >> LINE: 417
04-30 11:53:55.447  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : vendor.skb.dhcp.eth0.ipaddress, value : 192.168.2.2, isStbIp : false file: SmartRcuAgentEnvironment.java >> LINE: 424
04-30 11:53:55.448  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : ADULTMOVIEMENU, value : 1 file: SmartRcuAgentEnvironment.java >> LINE: 384
04-30 11:53:55.448  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : EROSMENU, value : 0 file: SmartRcuAgentEnvironment.java >> LINE: 390
04-30 11:53:55.448  2171  2250 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : get property all. so break;  >> file: SmartRcuAgentEnvironment.java >> LINE: 434>> TID: 171
04-30 11:53:55.448  2171  2250 I System.out: Site Local Address: 192.168.2.2
04-30 11:53:55.448  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName error NameNotFoundException packageName : com.skb.zemkids file: Utils.java >> LINE: 147
04-30 11:53:55.449  2171  2250 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName error NameNotFoundException packageName : com.skb.kidsbooks file: Utils.java >> LINE: 147
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName found manager : PackageInfo{5acfe6e com.skb.btv.smartrcuagent}, manager.packageName : com.skb.btv.smartrcuagent, manager.versionName3.5.10 file: Utils.java >> LINE: 142
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: start getFirmwareVersion: 19.561.113 file: SmartRcuAgentEnvironment.java >> LINE: 222
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_STBID = '' file: SmartRcuAgentEnvironment.java >> LINE: 223
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: Model = 'BFX-UA300' file: SmartRcuAgentEnvironment.java >> LINE: 224
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_CHILDRENSEELIMIT = '18' file: SmartRcuAgentEnvironment.java >> LINE: 225
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_ADULTMOVIEMENU = '1' file: SmartRcuAgentEnvironment.java >> LINE: 226
04-30 11:53:55.450  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_EROSMENU = '0' file: SmartRcuAgentEnvironment.java >> LINE: 227
04-30 11:53:55.451  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: MAC Address = 'a8:93:4a:fe:b9:67' file: SmartRcuAgentEnvironment.java >> LINE: 228
04-30 11:53:55.451  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: IP Address = '192.168.2.2' file: SmartRcuAgentEnvironment.java >> LINE: 229
04-30 11:53:55.451  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: stbPatchVersion = '19.561.113' file: SmartRcuAgentEnvironment.java >> LINE: 230
04-30 11:53:55.451  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: kidsBookVersion = '' file: SmartRcuAgentEnvironment.java >> LINE: 231
04-30 11:53:55.451  2171  2250 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: srcAgentVersion = '3.5.10' file: SmartRcuAgentEnvironment.java >> LINE: 232
04-30 11:53:55.829 15303 15432 I Finsky  : [1853] playLoggingServerUrl: https://play.googleapis.com/play/log
04-30 11:53:55.829 15303 15432 I Finsky  : [1853] playLoggingServerTimestampUrl: https://play.googleapis.com/play/log/timestamp
04-30 11:53:55.900 15647 15647 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 11:53:56 KST 2026 ====================
04-30 11:53:56.037   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.037   530   530 I keystore: del USRSKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.038   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.039   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.040   245   245 D SYNAKeyMaster: ValidateKeyParam() length=5
04-30 11:53:56.041   245   245 D SYNAKeyMaster: ValidateKeyParam() params[0].tag = KM_TAG_KEY_SIZE keysize=0x100
04-30 11:53:56.041   245   245 D SYNAKeyMaster: ValidateKeyParam() params[1].tag = KM_TAG_ALGORITHM, algo = 0x3
04-30 11:53:56.041   245   245 D SYNAKeyMaster: ValidateKeyParam() params[2].tag = KM_TAG_PURPOSE, purpose=0x2
04-30 11:53:56.041   245   245 D SYNAKeyMaster: ValidateKeyParam() invalid params[3].tag = 0x20000005
04-30 11:53:56.041   245   245 D SYNAKeyMaster: ValidateKeyParam() params[4].tag = KM_TAG_NO_AUTH_REQUIRED
04-30 11:53:56.099   530 15662 D DropBoxManager: About to call service->add()
04-30 11:53:55.250   745   789 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
04-30 11:53:56.100   745 23512 I DropBoxManagerService: add tag=keymaster isTagEnabled=true flags=0x0
04-30 11:53:56.102   530 15662 D DropBoxManager: service->add returned No error
04-30 11:53:56.151   245   245 D SYNAKeyMaster: ValidateKeyParam() length=3
04-30 11:53:56.151   245   245 D SYNAKeyMaster: ValidateKeyParam() params[0].tag = KM_TAG_ATTESTATION_CHALLENGE(blob.data_length=0x10)
04-30 11:53:56.151   245   245 D SYNAKeyMaster: ValidateKeyParam() invalid params[1].tag = 0x700003ec
04-30 11:53:56.151   245   245 D SYNAKeyMaster: ValidateKeyParam() params[2].tag = KM_TAG_ATTESTATION_APPLICATION_ID (blob.data_length=0x91)
04-30 11:53:56.198   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.199   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.200   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.A3398161851458369597 10044
04-30 11:53:56.201   530   530 I keystore: del USRPKEY_unstable.c7202ece89390c490b1b94d5b71225e1.B3398161851458369597 10044
04-30 11:53:56.202   530   530 I keystore: del USRSKEY_unstable.c7202ece89390c490b1b94d5b71225e1.B3398161851458369597 10044
04-30 11:53:56.202   530   530 I keystore: del USRCERT_unstable.c7202ece89390c490b1b94d5b71225e1.B3398161851458369597 10044
04-30 11:53:56.202   530   530 I keystore: del CACERT_unstable.c7202ece89390c490b1b94d5b71225e1.B3398161851458369597 10044
