
========== beginning of new [logcat] output ==========
--------- beginning of main
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.
--------- beginning of events
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) : []
--------- beginning of system
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
04-30 17:07:00.332  7748  7748 D TradefedEventsTag: ==================== com.google.android.tracerdarts.tvts.CombinedCoreCujsTest RUN STARTED: Thu Apr 30 17:07:00 KST 2026 ====================
04-30 17:07:00.500  7754  7754 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 17:07:01 KST 2026 ====================
04-30 17:07:00.501   405   405 W adbd    : timeout expired while flushing socket, closing
04-30 17:07:00.604   731   773 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 17:07:00.607  1544  7579 W native  : W0000 00:00:1777536420.607444    7579 publisher.cc:36] [FTL] No subscribers for event "hvc.api.events.MetricEvent" from publisher "RecordCastEventImpl"
04-30 17:07:00.609  1544  7579 W native  : W0000 00:00:1777536420.609759    7579 publisher.cc:36] [FTL] No subscribers for event "hvc.api.events.ConversationEvent" from publisher "S3Session"
04-30 17:07:00.610  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.89780548S, EVENT_TYPE_MILESTONE, LIBAS_S3_CONNECTION_UP, libAssistantRequestId=4e0c6a12-b4b3-4a8b-aa0a-70dd3f82517f, assistantServerEventId=-1
04-30 17:07:00.611  1544  1846 W katniss_interactor_AppFlowEventHandlerInteractor: interactionId is could not be successfully retrieved for event LIBAS_S3_CONNECTION_UP
04-30 17:07:00.611  1544  1846 I katniss_interactor_AppFlowEventHandlerInteractor: AppFlowEvent: PT2M49.89780548S, 2, LIBAS_S3_CONNECTION_UP, interactionId=null
04-30 17:07:00.619  1544  7579 W native  : W0000 00:00:1777536420.619796    7579 phenotype_impl.cc:1843] GetServerTriggerTokens: Some packages' data could not be read
04-30 17:07:00.620  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.89748948S, EVENT_TYPE_MILESTONE, LIBAS_CLIENT_OPS_RECEIVED, libAssistantRequestId=4e0c6a12-b4b3-4a8b-aa0a-70dd3f82517f, assistantServerEventId=-1
04-30 17:07:00.622  1544  1846 W katniss_interactor_AppFlowEventHandlerInteractor: interactionId is could not be successfully retrieved for event LIBAS_CLIENT_OPS_RECEIVED
04-30 17:07:00.622  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.897631S, EVENT_TYPE_MILESTONE, LIBAS_ASSISTANT_OUTPUT_RECEIVED, libAssistantRequestId=4e0c6a12-b4b3-4a8b-aa0a-70dd3f82517f, assistantServerEventId=ow3zaeGKHvu2rfcPvce_4AY
04-30 17:07:00.622  1544  1846 I katniss_interactor_AppFlowEventHandlerInteractor: AppFlowEvent: PT2M49.89748948S, 2, LIBAS_CLIENT_OPS_RECEIVED, interactionId=null
04-30 17:07:00.623  1544  1846 W katniss_interactor_AppFlowEventHandlerInteractor: interactionId is could not be successfully retrieved for event LIBAS_ASSISTANT_OUTPUT_RECEIVED
04-30 17:07:00.623  1544  1846 I katniss_interactor_AppFlowEventHandlerInteractor: AppFlowEvent: PT2M49.897631S, 2, LIBAS_ASSISTANT_OUTPUT_RECEIVED, interactionId=null
04-30 17:07:00.673  1544  7579 W native  : W0000 00:00:1777536420.673261    7579 phenotype_impl.cc:1843] GetServerTriggerTokens: Some packages' data could not be read
04-30 17:07:00.675  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.89965564S, EVENT_TYPE_MILESTONE, LIBAS_CLIENT_OPS_EXECUTED, libAssistantRequestId=4e0c6a12-b4b3-4a8b-aa0a-70dd3f82517f, assistantServerEventId=ow3zaeGKHvu2rfcPvce_4AY
04-30 17:07:00.675  1544  1846 W katniss_interactor_AppFlowEventHandlerInteractor: interactionId is could not be successfully retrieved for event LIBAS_CLIENT_OPS_EXECUTED
04-30 17:07:00.675  1544  1846 I katniss_interactor_AppFlowEventHandlerInteractor: AppFlowEvent: PT2M49.89965564S, 2, LIBAS_CLIENT_OPS_EXECUTED, interactionId=null
04-30 17:07:00.675  1544  7579 I katniss_interactor_VolumeManagerImpl: Device doesn't have TvSystemAudioManager. Defaulting to SW volume.
04-30 17:07:00.676   381  7176 V APM::AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400
04-30 17:07:00.676  1544  7579 I katniss_interactor_VolumeManagerImpl: Device doesn't have TvSystemAudioManager. Defaulting to SW volume.
04-30 17:07:00.677   381  7176 V APM::AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400
04-30 17:07:00.679  1544  6494 I katniss_interactor_KatnissAppFlowLoggerInteractor: AppFlowEvent: PT2M49.97081404S, EVENT_TYPE_START, OPERATOR_API_START, internalRequestId=4138648135503369897
04-30 17:07:00.680  1544  6494 I katniss_interactor_KatnissAppFlowLoggerInteractor: AppFlowEvent: PT2M49.97116984S, EVENT_TYPE_MILESTONE, OPERATOR_API_CONNECTION_ESTABLISHED_START, internalRequestId=4138648135503369897
04-30 17:07:00.680  1544  6494 I katniss_interactor_KatnissAppFlowLoggerInteractor: AppFlowEvent: PT2M49.97130268S, EVENT_TYPE_MILESTONE, OPERATOR_API_CONNECTION_ESTABLISHED_END, internalRequestId=4138648135503369897
04-30 17:07:00.680  1544  6494 I katniss_interactor_KatnissAppFlowLoggerInteractor: AppFlowEvent: PT2M49.97141116S, EVENT_TYPE_END, OPERATOR_API_END, internalRequestId=4138648135503369897
04-30 17:07:00.680  1544  1846 I katniss_interactor_UiClientOpHandler: GetConversationParams starts
04-30 17:07:00.681  1544  1846 I katniss_interactor_FindForegroundAppUtils: Foreground app name from running tasks: com.skb.tv
04-30 17:07:00.681  1544  1846 I katniss_interactor_UiClientOpHandler: Fetched foregroundAppPackage: com.skb.tv
04-30 17:07:00.691  1544  1846 I katniss_interactor_UiClientOpHandler: GetConversationParams ends
04-30 17:07:00.692  1544  7579 W native  : W0000 00:00:1777536420.692223    7579 publisher.cc:36] [FTL] No subscribers for event "hvc.api.events.ConversationEvent" from publisher "S3Session"
04-30 17:07:00.696  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.98414256S, EVENT_TYPE_MILESTONE, LIBAS_S3_TRANSPORT_CREATED, libAssistantRequestId=266ff914-e79a-4d91-878f-de51bd60cf5b, assistantServerEventId=-1
04-30 17:07:00.696  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: LibAssistant AppFlow event will be ignored: LIBAS_S3_TRANSPORT_CREATED. Failed to get corresponding Katniss AppFlow event
04-30 17:07:00.697  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.98518776S, EVENT_TYPE_MILESTONE, LIBAS_S3_TRANSPORT_STARTED, libAssistantRequestId=266ff914-e79a-4d91-878f-de51bd60cf5b, assistantServerEventId=-1
04-30 17:07:00.697  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: LibAssistant AppFlow event will be ignored: LIBAS_S3_TRANSPORT_STARTED. Failed to get corresponding Katniss AppFlow event
04-30 17:07:00.697  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.98579984S, EVENT_TYPE_MILESTONE, LIBAS_S3_INITIAL_REQUEST_BUILT, libAssistantRequestId=266ff914-e79a-4d91-878f-de51bd60cf5b, assistantServerEventId=-1
04-30 17:07:00.697  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: LibAssistant AppFlow event will be ignored: LIBAS_S3_INITIAL_REQUEST_BUILT. Failed to get corresponding Katniss AppFlow event
04-30 17:07:00.697  1544  7570 I katniss_interactor_LibAssistantAppFlowLogger: AppFlowEvent: PT2M49.98612112S, EVENT_TYPE_MILESTONE, LIBAS_S3_INITIAL_REQUEST_SENT, libAssistantRequestId=266ff914-e79a-4d91-878f-de51bd60cf5b, assistantServerEventId=-1
04-30 17:07:00.698  1544  1846 W katniss_interactor_AppFlowEventHandlerInteractor: interactionId is could not be successfully retrieved for event LIBAS_S3_INITIAL_REQUEST_SENT
04-30 17:07:00.698  1544  1846 I katniss_interactor_AppFlowEventHandlerInteractor: AppFlowEvent: PT2M49.98612112S, 2, LIBAS_S3_INITIAL_REQUEST_SENT, interactionId=null
04-30 17:07:00.986  7779  7779 D TradefedEventsTag: ==================== com.google.android.tracerdarts.tvts.CombinedSystemCujsTest RUN STARTED: Thu Apr 30 17:07:01 KST 2026 ====================
04-30 17:07:00.987   405   405 W adbd    : timeout expired while flushing socket, closing
04-30 17:07:01.093  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: invalid ip & invalid Stbid file: SmartRCUAgent.java >> LINE: 529
04-30 17:07:01.093  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getMacAddress start file: SmartRcuAgentEnvironment.java >> LINE: 449
04-30 17:07:01.094  2155  2243 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 17:07:01.094  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getStbId start file: SmartRcuAgentEnvironment.java >> LINE: 281
04-30 17:07:01.094  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getStbId file not exists file: SmartRcuAgentEnvironment.java >> LINE: 316
04-30 17:07:01.094  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getFirmwareVersion start file: SmartRcuAgentEnvironment.java >> LINE: 236
04-30 17:07:01.095  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getFirmwareVersion btv_home/config/version.txt : 19.561.113 file: SmartRcuAgentEnvironment.java >> LINE: 251
04-30 17:07:01.095  2155  2243 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : start  >> file: SmartRcuAgentEnvironment.java >> LINE: 322>> TID: 171
04-30 17:07:01.095  2155  2243 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 17:07:01.099  2155  2243 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 17:07:01.100  2155  2243 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : childeren.length : 409 >> file: SmartRcuAgentEnvironment.java >> LINE: 366>> TID: 171
04-30 17:07:01.101  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : CHILDRENSEELIMIT, value : 18 file: SmartRcuAgentEnvironment.java >> LINE: 378
04-30 17:07:01.102  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : STBID, value : , isStbId : false file: SmartRcuAgentEnvironment.java >> LINE: 396
04-30 17:07:01.102  2155  2243 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 17:07:01.103  2155  2243 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 17:07:01.104  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : ADULTMOVIEMENU, value : 1 file: SmartRcuAgentEnvironment.java >> LINE: 384
04-30 17:07:01.104  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty attribute key : EROSMENU, value : 0 file: SmartRcuAgentEnvironment.java >> LINE: 390
04-30 17:07:01.105  2155  2243 D TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getHomeUiProperty : get property all. so break;  >> file: SmartRcuAgentEnvironment.java >> LINE: 434>> TID: 171
04-30 17:07:01.105  2155  2243 I System.out: Site Local Address: 192.168.2.2
04-30 17:07:01.107  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName error NameNotFoundException packageName : com.skb.zemkids file: Utils.java >> LINE: 147
04-30 17:07:01.108  2155  2243 E TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName error NameNotFoundException packageName : com.skb.kidsbooks file: Utils.java >> LINE: 147
04-30 17:07:01.111  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: getPackageVersionName found manager : PackageInfo{9f9bd27 com.skb.btv.smartrcuagent}, manager.packageName : com.skb.btv.smartrcuagent, manager.versionName3.5.10 file: Utils.java >> LINE: 142
04-30 17:07:01.111  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: start getFirmwareVersion: 19.561.113 file: SmartRcuAgentEnvironment.java >> LINE: 222
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_STBID = '' file: SmartRcuAgentEnvironment.java >> LINE: 223
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: Model = 'BFX-UA300' file: SmartRcuAgentEnvironment.java >> LINE: 224
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_CHILDRENSEELIMIT = '18' file: SmartRcuAgentEnvironment.java >> LINE: 225
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_ADULTMOVIEMENU = '1' file: SmartRcuAgentEnvironment.java >> LINE: 226
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: PROPERTY_EROSMENU = '0' file: SmartRcuAgentEnvironment.java >> LINE: 227
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: MAC Address = 'a8:93:4a:fe:b9:67' file: SmartRcuAgentEnvironment.java >> LINE: 228
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: IP Address = '192.168.2.2' file: SmartRcuAgentEnvironment.java >> LINE: 229
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: stbPatchVersion = '19.561.113' file: SmartRcuAgentEnvironment.java >> LINE: 230
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: kidsBookVersion = '' file: SmartRcuAgentEnvironment.java >> LINE: 231
04-30 17:07:01.112  2155  2243 W TVSmartRCUAgentAPK 8.2.4 [26.01.22]: setSmartRcuAgentEnvironment: srcAgentVersion = '3.5.10' file: SmartRcuAgentEnvironment.java >> LINE: 232
04-30 17:07:01.126  7786  7786 D TradefedEventsTag: ==================== RUN ENDED: Thu Apr 30 17:07:01 KST 2026 ====================
