04-25 10:23:42 D/Configuration: Resolve and download remote files from @Option
04-25 10:23:42 I/TestInvocation: Invocation was started with cmd: tvts-cert --enable-token-sharding --max-testcase-run-count 2 --retry-strategy RETRY_ANY_FAILURE --shard-count 2 --invocation-data mtt=1 --serial SKBFXYZO0C1V --serial SKBFXYZO0DTQ
04-25 10:23:42 D/InvocationExecution: Skip linking external directory as FileProperty was set.
04-25 10:23:42 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
04-25 10:23:42 D/TestInvocation: Fetch build duration: 7 ms
04-25 10:23:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.sdk] with timeout: 45s
04-25 10:23:42 D/GlobalTestFilter: Global filters already set.
04-25 10:23:42 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYZO0C1V.
04-25 10:23:42 D/InvocationExecution: IShardHelper selected: com.android.tradefed.invoker.shard.StrictShardHelper@b870e04
04-25 10:23:42 I/TestInvocation: Starting invocation for 'tvts' with '[ DeviceBuildInfo{bid=P66353907, serial=SKBFXYZO0C1V} on device 'SKBFXYZO0C1V'] 
04-25 10:23:42 D/FileUtil: Creating temp directory at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts with prefix "inv_"
04-25 10:23:42 D/RunUtil: Running command [chmod, ug+rwx, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250] with timeout: 10s
04-25 10:23:42 D/FileSystemLogSaver: Using log file directory /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250
04-25 10:23:42 D/FileUtil: Creating temp directory at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250 with prefix "inv_"
04-25 10:23:42 I/LogFileSaver: Using log file directory /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950
04-25 10:23:42 D/TestInvocation: Skipping expanded config log for shard.
04-25 10:23:43 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYZO0C1V'
04-25 10:23:43 D/DefaultConnection: Instance type for connection: GCE
04-25 10:23:43 D/NativeDevice: Using connection: com.android.tradefed.device.connection.DefaultConnection@1045ffe9 (SKBFXYZO0C1V)
04-25 10:23:43 I/CommandInterrupter: Interrupt allowed
04-25 10:23:43 D/InvocationExecution: Starting setup for device: 'SKBFXYZO0C1V'
04-25 10:23:43 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@28226c73' on device: 'SKBFXYZO0C1V'
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, rm, -rf, /storage/emulated/0/Download/failure-screenshots] with timeout: 2m 0s
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, rm, -rf, /sdcard/perfetto-traces] with timeout: 2m 0s
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, rm, -rf, /data/local/tmp/files/audio] with timeout: 2m 0s
04-25 10:23:43 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@28226c73' on device: 'SKBFXYZO0C1V' in 862 ms
04-25 10:23:43 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@b476a90' on device: 'SKBFXYZO0C1V'
04-25 10:23:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.model] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.device] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.id] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 45s
04-25 10:23:44 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.manufacturer] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.serialno] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.security_patch] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.cpu.abilist32] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.reference.fingerprint] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.cpu.abi] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.cpu.abilist64] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.name] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.tags] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.incremental] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.brand] with timeout: 45s
04-25 10:23:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.fingerprint] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.vendor.build.fingerprint] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.base_os] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.cpu.abilist] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.board] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.type] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.release] with timeout: 45s
04-25 10:23:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.product.cpu.abi2] with timeout: 45s
04-25 10:23:45 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-25 10:23:45 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-25 10:23:45 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
04-25 10:23:46 I/NativeDeviceStateMonitor: Waiting 359730 ms for device SKBFXYZO0C1V external store
04-25 10:23:46 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
04-25 10:23:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.codename] with timeout: 45s
04-25 10:23:46 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
04-25 10:23:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:46 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
04-25 10:23:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:46 D/TvtsDeviceInfo.apk: Uploading TvtsDeviceInfo.apk onto device 'SKBFXYZO0C1V'
04-25 10:23:46 D/Device: Uploading file onto device 'SKBFXYZO0C1V'
04-25 10:23:47 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0C1V online before starting.
04-25 10:23:47 D/BackgroundDeviceAction: Device SKBFXYZO0C1V now online.
04-25 10:23:47 D/BackgroundDeviceAction: Starting logcat for SKBFXYZO0C1V.
04-25 10:23:53 D/RunUtil: Running command [aapt, dump, badging, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInfo.apk] with timeout: 1m 0s
04-25 10:23:53 D/RunUtil: Running command [aapt, dump, xmltree, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
04-25 10:23:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, appops, write-settings] with timeout: 2m 0s
04-25 10:23:54 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
04-25 10:23:54 D/ListInstrumentationParser: instrumentation:com.google.android.tvts.hdmi/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.tvts.hdmi)
04-25 10:23:54 D/ListInstrumentationParser: 
04-25 10:23:54 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
04-25 10:23:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:23:54 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
04-25 10:23:54 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
04-25 10:23:54 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage   -e newRunListenerMode true -e timeout_msec 300000 com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0C1V
04-25 10:24:23 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
04-25 10:24:23 D/ApkInstrumentationPreparer: Target preparation successful
04-25 10:24:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:23 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/CpuDeviceInfo.deviceinfo.json_7343388432801470138.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/ScreenDeviceInfo.deviceinfo.json_309572221454121307.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "InputDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/InputDeviceInfo.deviceinfo.json_8879662963087338241.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/ClientIdDeviceInfo.deviceinfo.json_2592032868504824144.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/UserDeviceInfo.deviceinfo.json_42346510743001620.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/LocaleDeviceInfo.deviceinfo.json_12834041288266388244.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/MediaDeviceInfo.deviceinfo.json_16065329948413845253.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/MemoryDeviceInfo.deviceinfo.json_6309364321235649027.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "GnssDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/GnssDeviceInfo.deviceinfo.json_15715992716933517701.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "ConnectivityDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/ConnectivityDeviceInfo.deviceinfo.json_14516756108783227170.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/VintfDeviceInfo.deviceinfo.json_3274249113918484446.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/PackageDeviceInfo.deviceinfo.json_5312325055279912386.txt.gz. [size=22770, elapsed=9ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "device_manifest.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_manifest.xml_12027044891213987272.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_manifest.xml_12027044891213987272.txt.gz
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "device_manifest.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/device_manifest.xml_2057311443802406946.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/framework_compatibility_matrix.xml_16058817968651503894.txt.gz. [size=10, elapsed=6ms]
04-25 10:24:25 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/framework_compatibility_matrix.xml_16058817968651503894.txt.gz
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/framework_compatibility_matrix.xml_14228233166937586981.txt.gz. [size=10, elapsed=7ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/GraphicsDeviceInfo.deviceinfo.json_6497606008008164434.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "BiometricsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/BiometricsDeviceInfo.deviceinfo.json_4936834705828251351.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "KeystoreAttestationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/KeystoreAttestationDeviceInfo.deviceinfo.json_6730184640628471830.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "MediaOutputDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/MediaOutputDeviceInfo.deviceinfo.json_109812000730837619.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/StorageDeviceInfo.deviceinfo.json_2319680014021118720.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/GenericDeviceInfo.deviceinfo.json_24794564464655344.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "framework_manifest.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/framework_manifest.xml_9963859131035535576.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/framework_manifest.xml_9963859131035535576.txt.gz
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "framework_manifest.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/framework_manifest.xml_728276126863425981.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "HapticsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/HapticsDeviceInfo.deviceinfo.json_6510774161783053528.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "AppStandbyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/AppStandbyDeviceInfo.deviceinfo.json_13146075238114025857.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "DeviceIdleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/DeviceIdleDeviceInfo.deviceinfo.json_10897794482540176924.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/FeatureDeviceInfo.deviceinfo.json_5802945262815446925.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/ConfigurationDeviceInfo.deviceinfo.json_1330690127749292164.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_compatibility_matrix.xml_7130832961812805087.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:25 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_compatibility_matrix.xml_7130832961812805087.txt.gz
04-25 10:24:25 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
04-25 10:24:25 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/device_compatibility_matrix.xml_12211598732930802583.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:25 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@b476a90' on device: 'SKBFXYZO0C1V' in 41s
04-25 10:24:25 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.DeviceReliabilityVerifier@ee5a024' on device: 'SKBFXYZO0C1V'
04-25 10:24:25 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.DeviceReliabilityVerifier@ee5a024' on device: 'SKBFXYZO0C1V' in 281 ms
04-25 10:24:25 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@15d1a87f' on device: 'SKBFXYZO0C1V'
04-25 10:24:25 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@15d1a87f' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:25 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2eab1f42' on device: 'SKBFXYZO0C1V'
04-25 10:24:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pkill, memhog] with timeout: 2m 0s
04-25 10:24:25 D/RunCommandTargetPreparer: cmd: 'pkill memhog' failed, returned:
stdout:
stderr:
04-25 10:24:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pkill, cpuhog] with timeout: 2m 0s
04-25 10:24:25 D/RunCommandTargetPreparer: cmd: 'pkill cpuhog' failed, returned:
stdout:
stderr:
04-25 10:24:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pkill, adb_marker] with timeout: 2m 0s
04-25 10:24:25 D/RunCommandTargetPreparer: cmd: 'pkill adb_marker' failed, returned:
stdout:
stderr:
04-25 10:24:25 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2eab1f42' on device: 'SKBFXYZO0C1V' in 252 ms
04-25 10:24:25 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.FilePusher@1e88e3ed' on device: 'SKBFXYZO0C1V'
04-25 10:24:25 D/PushFilePreparer: Trying to push local 'memhog' to remote '/data/local/tmp/memhog'
04-25 10:24:25 D/PushFilePreparer: Trying to push local 'cpuhog' to remote '/data/local/tmp/cpuhog'
04-25 10:24:25 D/PushFilePreparer: Trying to push local 'adb_marker' to remote '/data/local/tmp/adb_marker'
04-25 10:24:25 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.FilePusher@1e88e3ed' on device: 'SKBFXYZO0C1V' in 210 ms
04-25 10:24:25 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3d4f8498' on device: 'SKBFXYZO0C1V'
04-25 10:24:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, chmod, +x, /data/local/tmp/memhog] with timeout: 2m 0s
04-25 10:24:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, chmod, +x, /data/local/tmp/cpuhog] with timeout: 2m 0s
04-25 10:24:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, chmod, +x, /data/local/tmp/adb_marker] with timeout: 2m 0s
04-25 10:24:26 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3d4f8498' on device: 'SKBFXYZO0C1V' in 177 ms
04-25 10:24:26 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@2545411e' on device: 'SKBFXYZO0C1V'
04-25 10:24:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, persist.sys.locale] with timeout: 45s
04-25 10:24:26 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@2545411e' on device: 'SKBFXYZO0C1V' in 45 ms
04-25 10:24:26 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53620bfb' on device: 'SKBFXYZO0C1V'
04-25 10:24:26 D/NativeDevice: Using property ro.build.type=user from cache.
04-25 10:24:26 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53620bfb' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:26 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.suite.SuiteApkInstaller@aac0df1' on device: 'SKBFXYZO0C1V'
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
04-25 10:24:26 D/RunUtil: Running command [aapt2, dump, badging, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInformationTests.apk] with timeout: 1m 0s
04-25 10:24:26 D/RunUtil: Running command [aapt2, dump, xmltree, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInformationTests.apk, --file, AndroidManifest.xml] with timeout: 1m 0s
04-25 10:24:26 D/TestAppInstallSetup: Installing apk com.google.android.resolution.tvts with [/data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInformationTests.apk] ...
04-25 10:24:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:26 D/TvtsDeviceInformationTests.apk: Uploading TvtsDeviceInformationTests.apk onto device 'SKBFXYZO0C1V'
04-25 10:24:26 D/Device: Uploading file onto device 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [aapt, dump, badging, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInformationTests.apk] with timeout: 1m 0s
04-25 10:24:27 D/RunUtil: Running command [aapt, dump, xmltree, /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/testcases/TvtsDeviceInformationTests.apk, AndroidManifest.xml] with timeout: 1m 0s
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, appops, write-settings] with timeout: 2m 0s
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.suite.SuiteApkInstaller@aac0df1' on device: 'SKBFXYZO0C1V' in 1s
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.TvtsSetupAndTearDown@4b863378' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.TvtsSetupAndTearDown@4b863378' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@4444d6a7' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.youtube.tv|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.youtube.tv:  431300320
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@4444d6a7' on device: 'SKBFXYZO0C1V' in 101 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@7ba3007' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.katniss|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.katniss:  15000621
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@7ba3007' on device: 'SKBFXYZO0C1V' in 98 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@6d936684' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.play.games|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.play.games:  462434070
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@6d936684' on device: 'SKBFXYZO0C1V' in 58 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@177485bd' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.videos|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.videos:  44015257
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@177485bd' on device: 'SKBFXYZO0C1V' in 59 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@75e7881a' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.tvlauncher|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: Property "com.google.android.tvlauncher" not found on device, cannot verify value "1010914257" 
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@75e7881a' on device: 'SKBFXYZO0C1V' in 54 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@238ba4a9' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.tvrecommendations|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.tvrecommendations:  1010911441
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@238ba4a9' on device: 'SKBFXYZO0C1V' in 75 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@55da511a' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.backdrop|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.backdrop:  1007000
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@55da511a' on device: 'SKBFXYZO0C1V' in 59 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@66b06f71' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.inputmethod.latin|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:27 W/ApkChecker: The newest VersionCode found for the Package com.google.android.inputmethod.latin:  65075977
04-25 10:24:27 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@66b06f71' on device: 'SKBFXYZO0C1V' in 57 ms
04-25 10:24:27 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@2fde9ce5' on device: 'SKBFXYZO0C1V'
04-25 10:24:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.webview|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:28 W/ApkChecker: The newest VersionCode found for the Package com.google.android.webview:  567213130
04-25 10:24:28 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@2fde9ce5' on device: 'SKBFXYZO0C1V' in 61 ms
04-25 10:24:28 D/InvocationExecution: starting preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@518ecb5b' on device: 'SKBFXYZO0C1V'
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, package, com.google.android.gms|, grep, versionCode] with timeout: 2m 0s
04-25 10:24:28 W/ApkChecker: The newest VersionCode found for the Package com.google.android.gms:  231015112
04-25 10:24:28 D/InvocationExecution: done with preparer 'com.google.android.perfverify.tvts.prep.ApkChecker@518ecb5b' on device: 'SKBFXYZO0C1V' in 114 ms
04-25 10:24:28 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.BuildFingerPrintPreparer@565b12f1' on device: 'SKBFXYZO0C1V'
04-25 10:24:28 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.542.34-20240411:user/release-keys from cache.
04-25 10:24:28 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.542.34-20240411:user/release-keys from cache.
04-25 10:24:28 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.BuildFingerPrintPreparer@565b12f1' on device: 'SKBFXYZO0C1V' in 1 ms
04-25 10:24:28 D/InvocationExecution: Done with setup of device: 'SKBFXYZO0C1V'
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, uname, -a] with timeout: 2m 0s
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.system.build.fingerprint] with timeout: 45s
04-25 10:24:28 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.542.34-20240411:user/release-keys from cache.
04-25 10:24:28 D/InvocationExecution: Total setup duration: 45s'
04-25 10:24:28 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:28 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "device_logcat_setup_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:28 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_setup_SKBFXYZO0C1V_250990912969896719.txt. [size=2341987, elapsed=10ms]
04-25 10:24:28 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYZO0C1V in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_setup_SKBFXYZO0C1V_250990912969896719.txt
04-25 10:24:28 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:28 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "device_logcat_setup_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:28 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/device_logcat_setup_SKBFXYZO0C1V_15587073950004761198.txt. [size=2341987, elapsed=10ms]
04-25 10:24:28 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
04-25 10:24:28 D/TestsPoolPoller: Applying global filters to BaseTestSuite
04-25 10:24:28 D/Configuration: Resolve and download remote files from @Option
04-25 10:24:28 D/BaseTestSuite: Filters for 'armeabi-v7a TvtsSmokeTestCases': null
04-25 10:24:28 D/ITestSuite: com.google.android.perfverify.tvts.collectors.DebugHostLogCollector@553abb22 isn't applicable at module level. Skipping.
04-25 10:24:28 D/ITestSuite: Overriding '--merge-attempts' to false for auto-retry.
04-25 10:24:28 D/ModuleDefinition: Running module armeabi-v7a TvtsSmokeTestCases
04-25 10:24:28 I/TvtsSmokeTest: collectSmokeTests for: smoke
04-25 10:24:28 I/TvtsSmokeTest: Reading Smoke test file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json
04-25 10:24:28 I/TvtsSmokeTest: Smoke test results file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json not found
04-25 10:24:28 D/ModuleListener: ModuleListener.testRunStarted(com.android.compatibility.common.tradefed.testtype.JarHostTest, 1, 0) on SKBFXYZO0C1V
04-25 10:24:28 I/TvtsSmokeTest: collectSmokeTests for: smoke
04-25 10:24:28 I/TvtsSmokeTest: Reading Smoke test file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json
04-25 10:24:28 I/TvtsSmokeTest: Smoke test results file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json not found
04-25 10:24:28 D/JarHostTest: HostTestListener.testRunStarted(com.google.android.smoketests.tvts.TvtsSmokeTest, 1)
04-25 10:24:28 D/PrettyTestEventLogger: 
==================== com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] STARTED: Thu Apr 25 10:24:28 KST 2024 ====================
04-25 10:24:28 D/ModuleListener: ModuleListener.testStarted(com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists]) on SKBFXYZO0C1V
04-25 10:24:28 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.542.34-20240411:user/release-keys from cache.
04-25 10:24:28 I/TvtsSmokeTest: Copying smoke test files...
04-25 10:24:28 E/TvtsSmokeTest: Error copying smokeTestsResults.json: smokeTestsResults.json
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:28 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:28 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:28 I/SmokeTestKpiBase: [34mPercent Pass:     0%[0m
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:28 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:28 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:28 I/SmokeTestTotalKpi: [34mTotal:     0[0m
04-25 10:24:28 I/TestFailureListener: FailureListener.testFailed com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] false
04-25 10:24:28 D/PrettyTestEventLogger: 
==================== com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] ENDED: Thu Apr 25 10:24:28 KST 2024 ====================
04-25 10:24:28 I/ModuleListener: [1/1] SKBFXYZO0C1V com.android.compatibility.common.tradefed.testtype.JarHostTest com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] FAILURE: java.lang.AssertionError: smoke-json-file-exists
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

04-25 10:24:28 D/JarHostTest: HostTestListener.testRunEnded(681, {})
04-25 10:24:28 D/ModuleListener: ModuleListener.testRunEnded(688) on SKBFXYZO0C1V
04-25 10:24:28 D/BaseRetryDecision: Using updated filtering logic.
04-25 10:24:28 D/GranularRetriableTestWrapper: Starting intra-module retry.
04-25 10:24:28 D/GranularRetriableTestWrapper: Intra-module retry attempt number 1
04-25 10:24:28 D/ModuleListener: ModuleListener.testRunStarted(com.android.compatibility.common.tradefed.testtype.JarHostTest, 1, 1) on SKBFXYZO0C1V
04-25 10:24:28 I/TvtsSmokeTest: collectSmokeTests for: smoke
04-25 10:24:28 I/TvtsSmokeTest: Reading Smoke test file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json
04-25 10:24:28 I/TvtsSmokeTest: Smoke test results file: /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/smokeTestsResults.json not found
04-25 10:24:29 D/JarHostTest: HostTestListener.testRunStarted(com.google.android.smoketests.tvts.TvtsSmokeTest, 1)
04-25 10:24:29 D/PrettyTestEventLogger: 
==================== com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] STARTED: Thu Apr 25 10:24:29 KST 2024 ====================
04-25 10:24:29 D/ModuleListener: ModuleListener.testStarted(com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists]) on SKBFXYZO0C1V
04-25 10:24:29 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.542.34-20240411:user/release-keys from cache.
04-25 10:24:29 I/TvtsSmokeTest: Copying smoke test files...
04-25 10:24:29 E/TvtsSmokeTest: Error copying smokeTestsResults.json: smokeTestsResults.json
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:29 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:29 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:29 I/SmokeTestKpiBase: [34mPercent Pass:     0%[0m
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:29 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.config.low_ram] with timeout: 45s
04-25 10:24:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-25 10:24:29 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-25 10:24:29 I/SmokeTestTotalKpi: [34mTotal:     0[0m
04-25 10:24:29 I/TestFailureListener: FailureListener.testFailed com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] false
04-25 10:24:29 D/PrettyTestEventLogger: 
==================== com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] ENDED: Thu Apr 25 10:24:29 KST 2024 ====================
04-25 10:24:29 I/ModuleListener: [1/1] SKBFXYZO0C1V com.android.compatibility.common.tradefed.testtype.JarHostTest com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] FAILURE: java.lang.AssertionError: smoke-json-file-exists
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

04-25 10:24:29 D/JarHostTest: HostTestListener.testRunEnded(591, {})
04-25 10:24:29 D/ModuleListener: ModuleListener.testRunEnded(602) on SKBFXYZO0C1V
04-25 10:24:29 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-25 10:24:29 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-25 10:24:29 I/DeviceStateMonitor: Waiting 359958 ms for device SKBFXYZO0C1V package manager
04-25 10:24:29 I/NativeDeviceStateMonitor: Waiting 359891 ms for device SKBFXYZO0C1V external store
04-25 10:24:29 I/ShardListener: Sharded test completed: armeabi-v7a TvtsSmokeTestCases
04-25 10:24:29 I/ShardListener: Sharded test completed: armeabi-v7a TvtsSmokeTestCases
04-25 10:24:29 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/results/2024.04.25_10.23.41/proto with prefix "tmp-proto" suffix ""
04-25 10:24:29 I/ConsoleReporter: [SKBFXYZO0C1V] Starting armeabi-v7a TvtsSmokeTestCases with 1 test
04-25 10:24:29 I/ConsoleReporter: [1/1 armeabi-v7a TvtsSmokeTestCases SKBFXYZO0C1V] com.google.android.smoketests.tvts.TvtsSmokeTest#testSmoke[smoke-json-file-exists] fail: There were 2 failures:
  java.lang.AssertionError: smoke-json-file-exists
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

  java.lang.AssertionError: smoke-json-file-exists
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

04-25 10:24:29 I/ConsoleReporter: [SKBFXYZO0C1V] armeabi-v7a TvtsSmokeTestCases completed in 602 ms. 0 passed, 1 failed, 0 not executed
04-25 10:24:29 D/Configuration: Resolve and download remote files from @Option
04-25 10:24:29 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-25 10:24:29 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-25 10:24:30 I/DeviceStateMonitor: Waiting 359937 ms for device SKBFXYZO0C1V package manager
04-25 10:24:30 I/NativeDeviceStateMonitor: Waiting 359871 ms for device SKBFXYZO0C1V external store
04-25 10:24:30 D/ResultsPlayer: Start replaying the previous results. Please wait this can take a few minutes.
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsBootToBootCompletedHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsPiaHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvScorecardMediaPlaybackErrorTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a CtsEdiHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvLauncherXLaunchStateHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvLmkCujHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvScorecardMediaCodecErrorTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvAttestationHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsAssistant4TestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsCpuHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsApkTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a AssistantPluginHandler
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsHomeJankCujHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsPerfCujTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a AssistantLatencyTestBench
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a TvtsMediaLaunchTimesHostTestCases
04-25 10:24:30 I/ShardListener: Sharded test completed: armeabi-v7a GtvScorecardHostTestCases
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AC3-20.2.5.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level4.18-bitBT.709Long-FormCodec)-20.2.16.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level4.18-bitBT.709Short-FormCodec)-20.2.7.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level5.110-bitHLG)-20.2.12.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level5.110-bitPQ)-20.2.13.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level5.18-bitBT.709Long-FormCodec)-20.2.17.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level5.18-bitBT.709Short-FormCodec)-20.2.8.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level6.010-bitHLG)-20.2.14.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level6.010-bitPQ)-20.2.15.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level6.08-bitBT.709Long-FormCodec)-20.2.19.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+AV1(Level6.08-bitBT.709Short-FormCodec)-20.2.10.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+EAC3-20.2.6.1]-debug-hostlog
04-25 10:24:30 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioMP4+H.264-20.2.1.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+Opus-20.2.4.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Long-Form-20.2.25.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Medium-Form-20.2.24.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Profile2HLGLong-Form-20.2.21.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Profile2HLGMedium-Form-20.2.3.2]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Profile2PQLong-Form-20.2.23.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Profile2PQMedium-Form-20.2.22.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[FormatSupportTestsVideo/AudioWebM+VP9Short-Form-20.2.2.2]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[MSEConformanceTestsMediaHE-AAC/ExplicitSBR-1.8.3.1]-debug-hostlog
04-25 10:24:31 W/DebugHostLogCollector: Cannot copy host log for zero-bytes-com.google.android.apps.yts.tvts.YtsReport#yts[MSEConformanceTestsMediaHE-AAC/ImplicitSBR-1.8.4.1]-debug-hostlog
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsYouTubeTS
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsRvisTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsMemoryScoreTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsReportTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a GtvComplianceTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsAttestationTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsYouTubeHostTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsAppStartTimesHostTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsMemoryScoreNewVideoTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsJankHostTestCases
04-25 10:24:31 I/ShardListener: Sharded test completed: armeabi-v7a TvtsHdmiHostTestCases
04-25 10:24:31 D/ResultsPlayer: Done replaying results in 1s
04-25 10:24:31 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
04-25 10:24:31 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:31 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "device_logcat_test_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:31 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_test_SKBFXYZO0C1V_2385484216298830941.txt. [size=14758, elapsed=1ms]
04-25 10:24:31 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYZO0C1V in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_test_SKBFXYZO0C1V_2385484216298830941.txt
04-25 10:24:31 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:31 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "device_logcat_test_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:31 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/device_logcat_test_SKBFXYZO0C1V_6292671811976194704.txt. [size=14758, elapsed=0ms]
04-25 10:24:31 I/CommandInterrupter: Interrupt blocked
04-25 10:24:31 D/TestInvocation: Testing minimum responsiveness.
04-25 10:24:31 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-25 10:24:31 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, getprop, ro.build.version.sdk] with timeout: 45s
04-25 10:24:32 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "executeShellCommandLog_SKBFXYZO0C1V_" suffix ".txt.gz"
04-25 10:24:32 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/executeShellCommandLog_SKBFXYZO0C1V_11072016531368943641.txt.gz. [size=10, elapsed=1ms]
04-25 10:24:32 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYZO0C1V in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/executeShellCommandLog_SKBFXYZO0C1V_11072016531368943641.txt.gz
04-25 10:24:32 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "executeShellCommandLog_SKBFXYZO0C1V_" suffix ".txt.gz"
04-25 10:24:32 I/LogFileSaver: Saved gzip log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/executeShellCommandLog_SKBFXYZO0C1V_4419406104663616024.txt.gz. [size=10, elapsed=0ms]
04-25 10:24:32 D/TestInvocation: Checking that devices are online.
04-25 10:24:32 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-25 10:24:32 I/NativeDeviceStateMonitor: Waiting 180000 ms for device SKBFXYZO0C1V boot complete
04-25 10:24:32 I/DeviceStateMonitor: Waiting 179958 ms for device SKBFXYZO0C1V package manager
04-25 10:24:32 I/NativeDeviceStateMonitor: Waiting 179892 ms for device SKBFXYZO0C1V external store
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.BuildFingerPrintPreparer@565b12f1' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.BuildFingerPrintPreparer@565b12f1' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@518ecb5b' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@518ecb5b' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@2fde9ce5' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@2fde9ce5' on device: 'SKBFXYZO0C1V' in 1 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@66b06f71' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@66b06f71' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@55da511a' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@55da511a' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@238ba4a9' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@238ba4a9' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@75e7881a' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@75e7881a' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@177485bd' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@177485bd' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@6d936684' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@6d936684' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@7ba3007' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@7ba3007' on device: 'SKBFXYZO0C1V' in 1 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@4444d6a7' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.ApkChecker@4444d6a7' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.TvtsSetupAndTearDown@4b863378' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:32 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/Download/failure-screenshots)
04-25 10:24:32 E/NativeDevice: Device path /storage/emulated/0/Download/failure-screenshots does not exist to be pulled.
04-25 10:24:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:32 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/files)
04-25 10:24:32 I/NativeDevice: Device path is empty, nothing to do.
04-25 10:24:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:32 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.TvtsSetupAndTearDown@4b863378' on device: 'SKBFXYZO0C1V' in 478 ms
04-25 10:24:32 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.suite.SuiteApkInstaller@aac0df1' on device: 'SKBFXYZO0C1V'
04-25 10:24:32 D/TestDevice: Uninstalling com.google.android.resolution.tvts with extra args 
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.suite.SuiteApkInstaller@aac0df1' on device: 'SKBFXYZO0C1V' in 490 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53620bfb' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53620bfb' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@2545411e' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@2545411e' on device: 'SKBFXYZO0C1V' in 1 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3d4f8498' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3d4f8498' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.FilePusher@1e88e3ed' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.FilePusher@1e88e3ed' on device: 'SKBFXYZO0C1V' in 237 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2eab1f42' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2eab1f42' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@15d1a87f' on device: 'SKBFXYZO0C1V'
04-25 10:24:33 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-25 10:24:33 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
04-25 10:24:34 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
04-25 10:24:34 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@15d1a87f' on device: 'SKBFXYZO0C1V' in 319 ms
04-25 10:24:34 D/InvocationExecution: starting tearDown 'com.google.android.perfverify.tvts.prep.DeviceReliabilityVerifier@ee5a024' on device: 'SKBFXYZO0C1V'
04-25 10:24:34 D/InvocationExecution: done with tearDown 'com.google.android.perfverify.tvts.prep.DeviceReliabilityVerifier@ee5a024' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:34 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@b476a90' on device: 'SKBFXYZO0C1V'
04-25 10:24:34 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@b476a90' on device: 'SKBFXYZO0C1V' in 3 ms
04-25 10:24:34 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@28226c73' on device: 'SKBFXYZO0C1V'
04-25 10:24:34 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@28226c73' on device: 'SKBFXYZO0C1V' in 0 ms
04-25 10:24:34 D/RunUtil: Running command [id, -u, root] with timeout: 1m 0s
04-25 10:24:34 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.0.log] with timeout: 1m 0s
04-25 10:24:34 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
04-25 10:24:34 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "host_adb_log_" suffix ".txt"
04-25 10:24:34 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/host_adb_log_16225156778629821527.txt. [size=1970359, elapsed=8ms]
04-25 10:24:34 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/host_adb_log_16225156778629821527.txt
04-25 10:24:34 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
04-25 10:24:34 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "host_adb_log_" suffix ".txt"
04-25 10:24:34 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/host_adb_log_15486858033349027307.txt. [size=1970359, elapsed=7ms]
04-25 10:24:34 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:34 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984 with prefix "device_logcat_teardown_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:34 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_teardown_SKBFXYZO0C1V_13177769445245564035.txt. [size=46766, elapsed=1ms]
04-25 10:24:34 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYZO0C1V in /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/android-tvts/logs/2024.04.25_10.23.41/inv_9661249066077750984/device_logcat_teardown_SKBFXYZO0C1V_13177769445245564035.txt
04-25 10:24:34 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0C1V is already compressed, skipping compression
04-25 10:24:34 D/FileUtil: Creating temp file at /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950 with prefix "device_logcat_teardown_SKBFXYZO0C1V_" suffix ".txt"
04-25 10:24:34 I/LogFileSaver: Saved log file /data/tmp/4047a72e-8877-4b9d-b1cc-b77725b0701a/tmp/P66353907/tvts/inv_3192683162742271250/inv_9312238349137420950/device_logcat_teardown_SKBFXYZO0C1V_11654374875405044521.txt. [size=46766, elapsed=1ms]
04-25 10:24:34 I/TestInvocation: Done stopping logcat for SKBFXYZO0C1V
04-25 10:24:34 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYZO0C1V(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
04-25 10:24:34 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYZO0C1V shell to be responsive
04-25 10:24:34 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYZO0C1V. May see duplicated content in log.
04-25 10:24:34 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0C1V online before starting.
04-25 10:24:34 I/NativeDeviceStateMonitor: shell ready. id output: uid=2000(shell) gid=2000(shell) groups=2000(shell),1004(input),1007(log),1011(adb),1015(sdcard_rw),1028(sdcard_r),1078(ext_data_rw),1079(ext_obb_rw),3001(net_bt_admin),3002(net_bt),3003(inet),3006(net_bw_stats),3009(readproc),3011(uhid) context=u:r:shell:s0

04-25 10:24:34 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@55bebe7b=AVAILABLE}
04-25 10:24:34 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYZO0C1V
