05-03 11:00:54 D/Configuration: Resolve and download remote files from @Option
05-03 11:00:54 I/TestInvocation: Invocation was started with cmd: gts -s SKBFXYZO0DKW -m WvtsDeviceTestCases -t com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]
05-03 11:00:54 D/InvocationExecution: Skip linking external directory as FileProperty was set.
05-03 11:00:54 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
05-03 11:00:54 D/TestInvocation: Fetch build duration: 17 ms
05-03 11:00:54 D/ArtifactsAnalyzer: Build analysis report: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=false]
05-03 11:00:54 D/ArtifactsAnalyzer: Analysis report after test analysis: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=true]
05-03 11:00:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:54 D/GlobalTestFilter: Setting up global filters
05-03 11:00:54 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYZO0DKW.
05-03 11:00:54 D/InvocationExecution: IShardHelper selected: com.android.tradefed.invoker.shard.StrictShardHelper@7bf51ffd
05-03 11:00:54 I/TestInvocation: Starting invocation for 'gts' with '[ DeviceBuildInfo{bid=11398508, serial=SKBFXYZO0DKW} on device 'SKBFXYZO0DKW'] 
05-03 11:00:54 D/FileUtil: Creating temp directory at /tmp/11398508/gts with prefix "inv_"
05-03 11:00:54 D/RunUtil: Running command [chmod, ug+rwx, /tmp/11398508/gts/inv_9179119033709553168] with timeout: 10s
05-03 11:00:54 D/FileSystemLogSaver: Using log file directory /tmp/11398508/gts/inv_9179119033709553168
05-03 11:00:54 D/FileUtil: Creating temp directory at /tmp/11398508/gts/inv_9179119033709553168 with prefix "inv_"
05-03 11:00:54 I/LogFileSaver: Using log file directory /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528
05-03 11:00:54 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/results/2024.05.03_11.00.54/proto
05-03 11:00:54 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/results/2024.05.03_11.00.54/proto with prefix "tmp-proto" suffix ""
05-03 11:00:54 D/CertificationSuiteResultReporter: Initializing result directory
05-03 11:00:54 D/CertificationSuiteResultReporter: Results Directory: /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/results/2024.05.03_11.00.54
05-03 11:00:54 D/CertificationSuiteResultReporter: Created log dir /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54
05-03 11:00:54 D/FileUtil: Creating temp directory at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54 with prefix "inv_"
05-03 11:00:54 I/LogFileSaver: Using log file directory /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038
05-03 11:00:54 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/results/2024.05.03_11.00.54/proto
05-03 11:00:54 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-03 11:00:54 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "tradefed-expanded-config_" suffix ".xml"
05-03 11:00:54 I/LogFileSaver: Saved log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/tradefed-expanded-config_15357384850386441792.xml. [size=13899, elapsed=1ms]
05-03 11:00:54 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/tradefed-expanded-config_15357384850386441792.xml
05-03 11:00:54 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-03 11:00:54 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "tradefed-expanded-config_" suffix ".xml"
05-03 11:00:54 I/LogFileSaver: Saved log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/tradefed-expanded-config_11257990782851651295.xml. [size=13899, elapsed=1ms]
05-03 11:00:54 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYZO0DKW'
05-03 11:00:54 D/DefaultConnection: Instance type for connection: GCE
05-03 11:00:54 D/DefaultConnection: Instance type for connection: GCE
05-03 11:00:54 D/NativeDevice: Using connection: com.android.tradefed.device.connection.DefaultConnection@7c4551c3 (SKBFXYZO0DKW)
05-03 11:00:54 I/CommandInterrupter: Interrupt allowed
05-03 11:00:54 D/InvocationExecution: Starting setup for device: 'SKBFXYZO0DKW'
05-03 11:00:54 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@792c55da' on device: 'SKBFXYZO0DKW'
05-03 11:00:54 I/UrlReplacement: UrlReplacement file [/home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/UrlReplacement.xml] does not exist
05-03 11:00:55 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@792c55da' on device: 'SKBFXYZO0DKW' in 538 ms
05-03 11:00:55 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@53a3b940' on device: 'SKBFXYZO0DKW'
05-03 11:00:55 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@53a3b940' on device: 'SKBFXYZO0DKW' in 127 ms
05-03 11:00:55 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@1564621e' on device: 'SKBFXYZO0DKW'
05-03 11:00:55 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
05-03 11:00:55 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@1564621e' on device: 'SKBFXYZO0DKW' in 70 ms
05-03 11:00:55 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@52bb0cdd' on device: 'SKBFXYZO0DKW'
05-03 11:00:55 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:00:55 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DKW boot complete
05-03 11:00:55 I/DeviceStateMonitor: Waiting 359960 ms for device SKBFXYZO0DKW package manager
05-03 11:00:55 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DKW external store
05-03 11:00:56 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.gts
05-03 11:00:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.codename] with timeout: 45s
05-03 11:00:56 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
05-03 11:00:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:56 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:00:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:56 D/GtsPreconditions.apk: Uploading GtsPreconditions.apk onto device 'SKBFXYZO0DKW'
05-03 11:00:56 D/Device: Uploading file onto device 'SKBFXYZO0DKW'
05-03 11:00:57 D/RunUtil: Running command [aapt, dump, badging, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/GtsPreconditions.apk] with timeout: 1m 0s
05-03 11:00:57 D/RunUtil: Running command [aapt, dump, xmltree, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/GtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:00:57 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
05-03 11:00:57 D/ListInstrumentationParser: instrumentation:com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.gts)
05-03 11:00:57 D/ListInstrumentationParser: instrumentation:com.android.testutils.connectivitychecker/androidx.test.runner.AndroidJUnitRunner (target=com.android.testutils.connectivitychecker)
05-03 11:00:57 D/ListInstrumentationParser: 
05-03 11:00:57 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
05-03 11:00:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:00:57 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:00:57 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
05-03 11:00:57 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage   -e newRunListenerMode true -e timeout_msec 300000 com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DKW
05-03 11:00:59 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0DKW online before starting.
05-03 11:00:59 D/BackgroundDeviceAction: Device SKBFXYZO0DKW now online.
05-03 11:00:59 D/BackgroundDeviceAction: Starting logcat for SKBFXYZO0DKW.
05-03 11:01:00 D/TestDevice: Uninstalling com.android.preconditions.gts with extra args 
05-03 11:01:01 D/ApkInstrumentationPreparer: Target preparation successful
05-03 11:01:01 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@52bb0cdd' on device: 'SKBFXYZO0DKW' in 5s
05-03 11:01:01 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@a50fca3' on device: 'SKBFXYZO0DKW'
05-03 11:01:01 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:01:01 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
05-03 11:01:01 I/DeviceStateMonitor: Waiting 359837 ms for device SKBFXYZO0DKW package manager
05-03 11:01:01 I/NativeDeviceStateMonitor: Waiting 359664 ms for device SKBFXYZO0DKW external store
05-03 11:01:02 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:02 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:02 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:02 D/WifiUtil.apk5749042850632861629.apk: Uploading WifiUtil.apk5749042850632861629.apk onto device 'SKBFXYZO0DKW'
05-03 11:01:02 D/Device: Uploading file onto device 'SKBFXYZO0DKW'
05-03 11:01:03 D/RunUtil: Running command [aapt, dump, badging, /tmp/WifiUtil.apk5749042850632861629.apk] with timeout: 1m 0s
05-03 11:01:03 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/WifiUtil.apk5749042850632861629.apk, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:01:03 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
05-03 11:01:05 I/WifiCheck: Wifi is connected
05-03 11:01:05 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@a50fca3' on device: 'SKBFXYZO0DKW' in 4s
05-03 11:01:05 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@4c60486f' on device: 'SKBFXYZO0DKW'
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
05-03 11:01:05 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@4c60486f' on device: 'SKBFXYZO0DKW' in 381 ms
05-03 11:01:05 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@207151a7' on device: 'SKBFXYZO0DKW'
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.model] with timeout: 45s
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.device] with timeout: 45s
05-03 11:01:05 D/NativeDevice: Using property ro.build.id=STTC.220724.001 from cache.
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 45s
05-03 11:01:05 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.manufacturer] with timeout: 45s
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.serialno] with timeout: 45s
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.security_patch] with timeout: 45s
05-03 11:01:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist32] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.reference.fingerprint] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abi] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist64] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.name] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.tags] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.incremental] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.brand] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.fingerprint] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.vendor.build.fingerprint] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.base_os] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.board] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.type] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.release] with timeout: 45s
05-03 11:01:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abi2] with timeout: 45s
05-03 11:01:06 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:01:06 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DKW boot complete
05-03 11:01:06 I/DeviceStateMonitor: Waiting 359962 ms for device SKBFXYZO0DKW package manager
05-03 11:01:06 I/NativeDeviceStateMonitor: Waiting 359899 ms for device SKBFXYZO0DKW external store
05-03 11:01:07 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
05-03 11:01:07 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:07 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:07 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
05-03 11:01:07 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:07 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:07 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:07 D/GtsDeviceInfo.apk: Uploading GtsDeviceInfo.apk onto device 'SKBFXYZO0DKW'
05-03 11:01:07 D/Device: Uploading file onto device 'SKBFXYZO0DKW'
05-03 11:01:10 D/RunUtil: Running command [aapt, dump, badging, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/GtsDeviceInfo.apk] with timeout: 1m 0s
05-03 11:01:10 D/RunUtil: Running command [aapt, dump, xmltree, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/GtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:01:10 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
05-03 11:01:10 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
05-03 11:01:10 D/ListInstrumentationParser: instrumentation:com.android.testutils.connectivitychecker/androidx.test.runner.AndroidJUnitRunner (target=com.android.testutils.connectivitychecker)
05-03 11:01:10 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
05-03 11:01:10 D/ListInstrumentationParser: 
05-03 11:01:10 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
05-03 11:01:10 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:10 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:10 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:10 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
05-03 11:01:10 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-SKBFXYZO0DKW
05-03 11:01:38 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
05-03 11:01:39 D/ApkInstrumentationPreparer: Target preparation successful
05-03 11:01:39 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:39 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/CpuDeviceInfo.deviceinfo.json_5960259686801529860.txt.gz. [size=10, elapsed=2ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "WidevineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/WidevineDeviceInfo.deviceinfo.json_10390593589772675773.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/ScreenDeviceInfo.deviceinfo.json_8639806724025007994.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/ClientIdDeviceInfo.deviceinfo.json_1582925271196468340.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/UserDeviceInfo.deviceinfo.json_11042121524311826355.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/LocaleDeviceInfo.deviceinfo.json_12396093611166618801.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/MediaDeviceInfo.deviceinfo.json_10876620867638973367.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/MemoryDeviceInfo.deviceinfo.json_8407188923019292081.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/VintfDeviceInfo.deviceinfo.json_15231573743533491300.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/PackageDeviceInfo.deviceinfo.json_16858226838701865308.txt.gz. [size=22094, elapsed=13ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "TelephonyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/TelephonyDeviceInfo.deviceinfo.json_1642173207353695372.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_manifest.xml_15448561921233642160.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_manifest.xml_15448561921233642160.txt.gz
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/device_manifest.xml_1810546637077952196.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/framework_compatibility_matrix.xml_2711079839179178418.txt.gz. [size=10, elapsed=6ms]
05-03 11:01:40 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/framework_compatibility_matrix.xml_2711079839179178418.txt.gz
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/framework_compatibility_matrix.xml_693982491486817212.txt.gz. [size=10, elapsed=6ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/GraphicsDeviceInfo.deviceinfo.json_1630651595699610730.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "MainlineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/MainlineDeviceInfo.deviceinfo.json_690252732586226926.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/StorageDeviceInfo.deviceinfo.json_8113278809793835492.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/GenericDeviceInfo.deviceinfo.json_3168152412472280172.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "BackupPermissionHoldersDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/BackupPermissionHoldersDeviceInfo.deviceinfo.json_6782028351059925000.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/framework_manifest.xml_18255931844143028311.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/framework_manifest.xml_18255931844143028311.txt.gz
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/framework_manifest.xml_2450099180279344457.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "HapticsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/HapticsDeviceInfo.deviceinfo.json_4102495465912827797.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/FeatureDeviceInfo.deviceinfo.json_15475011781223171085.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "VendorApexDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/VendorApexDeviceInfo.deviceinfo.json_6378182299028616851.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/ConfigurationDeviceInfo.deviceinfo.json_13491785262848465621.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_compatibility_matrix.xml_7321108492691646388.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_compatibility_matrix.xml_7321108492691646388.txt.gz
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/device_compatibility_matrix.xml_14252475976054574460.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:40 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "SecurityDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-03 11:01:40 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/SecurityDeviceInfo.deviceinfo.json_17341034294230918083.txt.gz. [size=10, elapsed=0ms]
05-03 11:01:40 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@207151a7' on device: 'SKBFXYZO0DKW' in 35s
05-03 11:01:40 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@560c9ecc' on device: 'SKBFXYZO0DKW'
05-03 11:01:40 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@560c9ecc' on device: 'SKBFXYZO0DKW' in 1 ms
05-03 11:01:40 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1c336bc3' on device: 'SKBFXYZO0DKW'
05-03 11:01:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
05-03 11:01:40 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1c336bc3' on device: 'SKBFXYZO0DKW' in 66 ms
05-03 11:01:40 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@fd89613' on device: 'SKBFXYZO0DKW'
05-03 11:01:40 W/BusinessLogicPreparer: Option config-filename isn't set. Using empty string instead.
05-03 11:01:40 W/BusinessLogicPreparer: Option version isn't set. Using 'null' instead.
05-03 11:01:40 D/BusinessLogicPreparer: original version: 11_r3, extracted version: 11_r3
05-03 11:01:40 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
05-03 11:01:40 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
05-03 11:01:40 I/DynamicConfigFileReader: config files: {gts=/tmp/gts13000600305926643358.dynamic}
05-03 11:01:42 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
05-03 11:01:42 I/DynamicConfigFileReader: config files: {gts=/tmp/gts13000600305926643358.dynamic}
05-03 11:01:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.hardware.sku] with timeout: 45s
05-03 11:01:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.product.hardware.sku] with timeout: 45s
05-03 11:01:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.product.vendor.sku] with timeout: 45s
05-03 11:01:42 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.512-20240321:user/release-keys from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.product.device=BFX-AT100 from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.build.version.release=12 from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.product.brand=SKB from cache.
05-03 11:01:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.first_api_level] with timeout: 45s
05-03 11:01:42 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.product.model=BFX-AT100 from cache.
05-03 11:01:42 D/NativeDevice: Using property ro.product.name=BFX_AT100 from cache.
05-03 11:01:42 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
05-03 11:01:42 I/DynamicConfigFileReader: config files: {gts=/tmp/gts13000600305926643358.dynamic}
05-03 11:01:42 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
05-03 11:01:42 I/DynamicConfigFileReader: config files: {gts=/tmp/gts13000600305926643358.dynamic}
05-03 11:01:42 D/BusinessLogicPreparer: Built param string: "suite_version=11_r3&module_version=null&oem=Foxconn&features=android.hardware.type.television&features=android.software.leanback&properties=search_client_id%3Ams-android-foxconn2-tv&properties=playax_client_id%3Aplay-ad-ms-android-foxconn2-tv&properties=program_client_id%3Apg-android-foxconn2-tv&properties=base_client_id%3Aandroid-foxconn2-tv&properties=playtx_client_id%3Aplay-ms-android-foxconn2-tv&properties=market_client_id%3Aam-android-foxconn2-tv&properties=voicesearch_client_id%3Ams-android-foxconn2-tv&properties=client_id%3Aandroid-foxconn2-tv&properties=ro.boot.hardware.sku%3Anull&properties=ro.boot.product.hardware.sku%3Anull&properties=ro.boot.product.vendor.sku%3Anull&properties=ro.build.fingerprint%3ASKB%2FBFX_AT100%2FBFX-AT100%3A12%2FSTTC.220724.001%2F15.537.512-20240321%3Auser%2Frelease-keys&properties=ro.product.device%3ABFX-AT100&properties=ro.build.version.release%3A12&properties=ro.build.version.sdk%3A31&properties=ro.product.brand%3ASKB&properties=ro.product.first_api_level%3A28&properties=ro.product.manufacturer%3AFoxconn&properties=ro.product.model%3ABFX-AT100&properties=ro.product.name%3ABFX_AT100&packages=com.google.android.gms&packages=com.android.vending&device_info=MemoryDeviceInfo%3Atotal_memory%3A3083964416"
05-03 11:01:42 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
05-03 11:01:42 I/BusinessLogicPreparer: Attempting to connect to business logic service...
05-03 11:01:42 D/BusinessLogicPreparer: Unable to fetch the service key because neither environment variable APE_API_KEY is set nor the key file is dynamically downloaded.
05-03 11:01:44 D/BusinessLogicPreparer: Business Logic Service Response Code : 200
05-03 11:01:45 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:45 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:45 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:45 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:45 D/TradefedContentProvider.apk10105131939834716784.apk: Uploading TradefedContentProvider.apk10105131939834716784.apk onto device 'SKBFXYZO0DKW'
05-03 11:01:45 D/Device: Uploading file onto device 'SKBFXYZO0DKW'
05-03 11:01:46 D/RunUtil: Running command [aapt, dump, badging, /tmp/TradefedContentProvider.apk10105131939834716784.apk] with timeout: 1m 0s
05-03 11:01:46 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/TradefedContentProvider.apk10105131939834716784.apk, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:01:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
05-03 11:01:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, appops, set, android.tradefed.contentprovider, android:legacy_storage, allow] with timeout: 2m 0s
05-03 11:01:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, appops, get, android.tradefed.contentprovider] with timeout: 2m 0s
05-03 11:01:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:47 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, content, write, --user, 0, --uri, content://android.tradefed.contentprovider/%252Fsdcard%252Fbl] with timeout: 2m 0s
05-03 11:01:48 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@fd89613' on device: 'SKBFXYZO0DKW' in 7s
05-03 11:01:48 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@21a5b666' on device: 'SKBFXYZO0DKW'
05-03 11:01:48 D/NativeDevice: Using property ro.build.type=user from cache.
05-03 11:01:48 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@21a5b666' on device: 'SKBFXYZO0DKW' in 1 ms
05-03 11:01:48 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@75e62330' on device: 'SKBFXYZO0DKW'
05-03 11:01:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.locale] with timeout: 45s
05-03 11:01:48 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
05-03 11:01:48 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@75e62330' on device: 'SKBFXYZO0DKW' in 48 ms
05-03 11:01:48 D/InvocationExecution: Done with setup of device: 'SKBFXYZO0DKW'
05-03 11:01:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, uname, -a] with timeout: 2m 0s
05-03 11:01:48 I/InvocationExecution: Device SKBFXYZO0DKW kernel information: 'Linux localhost 4.9.269-abk000c-537r512-20240321 #267 SMP PREEMPT Thu Mar 21 15:15:48 CST 2024 armv8l'
05-03 11:01:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.system.build.fingerprint] with timeout: 45s
05-03 11:01:48 I/InvocationExecution: Device SKBFXYZO0DKW system image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.512-20240321:user/release-keys'
05-03 11:01:48 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.512-20240321:user/release-keys from cache.
05-03 11:01:48 I/InvocationExecution: Device SKBFXYZO0DKW vendor image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.512-20240321:user/release-keys'
05-03 11:01:48 D/InvocationExecution: Total setup duration: 54s'
05-03 11:01:48 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:01:48 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "device_logcat_setup_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:01:48 I/LogFileSaver: Saved log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_setup_SKBFXYZO0DKW_15085766427910180120.txt. [size=4225444, elapsed=18ms]
05-03 11:01:48 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYZO0DKW in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_setup_SKBFXYZO0DKW_15085766427910180120.txt
05-03 11:01:48 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:01:48 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "device_logcat_setup_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:01:48 I/LogFileSaver: Saved log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/device_logcat_setup_SKBFXYZO0DKW_1369297134053316573.txt. [size=4225444, elapsed=17ms]
05-03 11:01:48 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
05-03 11:01:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, device_state, print-states] with timeout: 2m 0s
05-03 11:01:49 D/NativeDevice: Using property ro.product.cpu.abilist=armeabi-v7a,armeabi from cache.
05-03 11:01:49 D/ITestSuite: abi 'armeabi' is supported by device but not by this suite build ([arm64-v8a, armeabi-v7a, x86_64, x86]), tests will not run against it.
05-03 11:01:49 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[]
Module Args:[]
Includes: {armeabi-v7a WvtsDeviceTestCases=[WvtsDeviceTestCases com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]], armeabi-v7a WvtsDeviceTestCases[instant]=[WvtsDeviceTestCases[instant] com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]], armeabi-v7a WvtsDeviceTestCases[run-on-clone-profile]=[WvtsDeviceTestCases[run-on-clone-profile] com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]], armeabi-v7a WvtsDeviceTestCases[run-on-secondary-user]=[WvtsDeviceTestCases[run-on-secondary-user] com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]], armeabi-v7a WvtsDeviceTestCases[run-on-work-profile]=[WvtsDeviceTestCases[run-on-work-profile] com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]]}

05-03 11:01:49 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
05-03 11:01:50 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
05-03 11:01:50 D/ITestSuite: [Total Unique Modules = 1]
05-03 11:01:50 I/ITestSuite: SKBFXYZO0DKW running 1 modules: [armeabi-v7a WvtsDeviceTestCases]
05-03 11:01:50 D/BaseTestSuite: Filters for 'armeabi-v7a WvtsDeviceTestCases': []
05-03 11:01:50 I/ITestSuite: Running system status checker before module execution: armeabi-v7a WvtsDeviceTestCases
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.type=user from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 I/DeviceBaselineChecker: Enable device baselines disable_os_auto_update,keep_screen_on,disable_usb_app_verification,clear_lock_screen in 1501 millis.
05-03 11:01:52 D/ModuleDefinition: Running module armeabi-v7a WvtsDeviceTestCases
05-03 11:01:52 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, pm, list, features] with timeout: 2m 0s
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:52 D/RunUtil: Running command [aapt2, dump, badging, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/WvtsDeviceTestCases.apk] with timeout: 1m 0s
05-03 11:01:52 D/RunUtil: Running command [aapt2, dump, xmltree, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/WvtsDeviceTestCases.apk, --file, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:01:52 D/TestAppInstallSetup: Installing apk com.google.android.wvts with [./../../android-gts/testcases/WvtsDeviceTestCases.apk] ...
05-03 11:01:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:52 D/WvtsDeviceTestCases.apk: Uploading WvtsDeviceTestCases.apk onto device 'SKBFXYZO0DKW'
05-03 11:01:52 D/Device: Uploading file onto device 'SKBFXYZO0DKW'
05-03 11:01:55 D/RunUtil: Running command [aapt, dump, badging, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/WvtsDeviceTestCases.apk] with timeout: 1m 0s
05-03 11:01:55 D/RunUtil: Running command [aapt, dump, xmltree, /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/testcases/WvtsDeviceTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
05-03 11:01:55 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
05-03 11:01:55 D/ModuleDefinition: Running setup preparer: DynamicConfigPusher
05-03 11:01:55 I/DynamicConfigPusher: More than 2 test-suite-tag are defined. test-suite-tag: [gts, wvts, WvtsDeviceTestCases, ats]
05-03 11:01:55 I/DynamicConfigPusher: Replacing {suite-name} placeholder with GTS from test suite tags in dynamic config url.
05-03 11:01:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, content, write, --user, 0, --uri, content://android.tradefed.contentprovider/%252Fsdcard%252Fdynamic-config-files%252FWvtsDeviceTestCases.dynamic] with timeout: 2m 0s
05-03 11:01:57 D/ModuleDefinition: Running setup preparer: BusinessLogicPreparer
05-03 11:01:57 I/BusinessLogicPreparer: Business logic file already collected, skipping BusinessLogicPreparer.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:57 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
05-03 11:01:57 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
05-03 11:01:57 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:57 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
05-03 11:01:57 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include_" suffix ".txt.gz"
05-03 11:01:57 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include_17010776022531727085.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:57 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include_17010776022531727085.txt.gz
05-03 11:01:57 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include_" suffix ".txt.gz"
05-03 11:01:57 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/filter-armeabi-v7a%20WvtsDeviceTestCases731570289162556039.include_4138750966312220070.txt.gz. [size=10, elapsed=1ms]
05-03 11:01:57 D/ListInstrumentationParser: instrumentation:com.android.testutils.connectivitychecker/androidx.test.runner.AndroidJUnitRunner (target=com.android.testutils.connectivitychecker)
05-03 11:01:57 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
05-03 11:01:57 D/ListInstrumentationParser: instrumentation:com.google.android.wvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.wvts)
05-03 11:01:57 D/ListInstrumentationParser: 
05-03 11:01:57 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
05-03 11:01:57 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:01:57 D/InstrumentationTest: Collecting test info for com.google.android.wvts on device SKBFXYZO0DKW
05-03 11:01:57 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log true -e timeout_msec 1200000 com.google.android.wvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DKW
05-03 11:02:00 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
05-03 11:02:00 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log false -e timeout_msec 1200000 -e listener android.device.loggers.LogFileLogger,com.google.android.wvts.collectors.TopCommandDeviceMetricCollector com.google.android.wvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DKW
05-03 11:02:01 D/ModuleListener: ModuleListener.testRunStarted(com.google.android.wvts, 1, 0) on SKBFXYZO0DKW
05-03 11:02:01 D/ModuleListener: ModuleListener.testStarted(com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1]) on SKBFXYZO0DKW
05-03 11:02:03 I/TestFailureListener: FailureListener.testFailed com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1] false
05-03 11:02:03 I/ModuleListener: [1/1] SKBFXYZO0DKW com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1] FAILURE: android.media.MediaDrm$MediaDrmStateException: Failed to remove offline license: ERROR_DRM_GENERIC_OEM
============================== Beginning of DRM Plugin Log ==============================
  05-03 11:02:02.543 I found instance=clearkey version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.544 I found instance=default version=android.hardware.drm@1.0::IDrmFactory
  05-03 11:02:02.544 I found instance=playready version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.544 I found instance=widevine version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.581 I [(0):] L3 Terminate.
  05-03 11:02:02.581 E [oemcrypto_adapter_dynamic.cpp(831):Level1Terminate] L1 Terminate
  05-03 11:02:02.670 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.671 I [oemcrypto_adapter_dynamic.cpp(783):Initialize] Level 3 Build Info (v16): OEMCrypto Level3 Code 28919 Feb  2 2023 02:50:27
  05-03 11:02:02.673 I [(0):] Level3 Library 28919 Feb  2 2023 02:50:27
  05-03 11:02:02.685 I [oemcrypto_adapter_dynamic.cpp(797):Initialize] L3 Initialized. Trying L1.
  05-03 11:02:02.706 I [oemcrypto_adapter_dynamic.cpp(819):Initialize] Level 1 Build Info (v16): OEMCrypto Ref Code Feb 16 2023 16:36:51
  05-03 11:02:02.706 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.713 I [usage_table_header.cpp(204):RestoreTable] Found usage table to restore: entry_count = 54
  05-03 11:02:02.722 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.723 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.724 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.725 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.726 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.726 I [cdm_engine.cpp(190):OpenSession] New session: session_id = sid13
  05-03 11:02:02.726 I [cdm_engine.cpp(929):QueryOemCryptoSessionId] session_id = sid13
  05-03 11:02:02.732 I [cdm_engine.cpp(235):CloseSession] session_id = sid13
  05-03 11:02:02.739 I [cdm_engine.cpp(201):OpenKeySetSession] key_set_id = ksidDE02CFED
  05-03 11:02:02.740 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.741 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.742 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.742 I [cdm_engine.cpp(190):OpenSession] New session: session_id = sid14
  05-03 11:02:02.742 I [cdm_engine.cpp(277):GenerateKeyRequest] session_id = <empty>, key_set_id = ksidDE02CFED, license_type = Release
  05-03 11:02:02.778 E [crypto_session.cpp(109):MapOEMCryptoResult] Mapping OEMCrypto result: crypto_session_method = PrepareAndSignRenewalRequest, result = 29
  05-03 11:02:02.778 E [cdm_engine.cpp(337):GenerateKeyRequest] CdmSession::GenerateKeyRequest failed: session_id = sid14, status = 323
  05-03 11:02:02.778 E [cdm_engine.cpp(1325):RemoveOfflineLicense] GenerateKeyRequest failed: status = 323
  05-03 11:02:02.778 I [cdm_engine.cpp(246):CloseKeySetSession] key_set_id = ksidDE02CFED
  05-03 11:02:02.778 I [cdm_engine.cpp(235):CloseSession] session_id = sid14
============================== End of DRM Plugin Log ==============================
	at android.media.MediaDrm.removeOfflineLicense(Native Method)
	at com.google.android.wvts.MediaDrmParameterizedTests.clearUsageTable(MediaDrmParameterizedTests.java:406)
	at com.google.android.wvts.MediaDrmParameterizedTests.testUsageTableCapacity(MediaDrmParameterizedTests.java:484)

05-03 11:02:04 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:04 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:04 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290_" suffix ".txt.gz"
05-03 11:02:04 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290_9425023708770236840.txt.gz. [size=10, elapsed=0ms]
05-03 11:02:04 D/CertificationSuiteResultReporter: Saved logs for metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290 in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290_9425023708770236840.txt.gz
05-03 11:02:04 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290_" suffix ".txt.gz"
05-03 11:02:04 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/metrics_widevine_usage-testUsageTableCapacity[L1](com.google.android.wvts_5741707892383702290_797096316689464481.txt.gz. [size=10, elapsed=0ms]
05-03 11:02:04 D/ModuleListener: ModuleListener.testRunEnded(2353) on SKBFXYZO0DKW
05-03 11:02:04 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:04 D/TestDevice: Uninstalling com.google.android.wvts with extra args 
05-03 11:02:05 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:02:05 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
05-03 11:02:05 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0DKW package manager
05-03 11:02:05 I/NativeDeviceStateMonitor: Waiting 359791 ms for device SKBFXYZO0DKW external store
05-03 11:02:06 I/ConsoleReporter: [SKBFXYZO0DKW] Starting armeabi-v7a WvtsDeviceTestCases with 1 test
05-03 11:02:06 I/ConsoleReporter: [1/1 armeabi-v7a WvtsDeviceTestCases SKBFXYZO0DKW] com.google.android.wvts.MediaDrmParameterizedTests#testUsageTableCapacity[L1] fail: android.media.MediaDrm$MediaDrmStateException: Failed to remove offline license: ERROR_DRM_GENERIC_OEM
============================== Beginning of DRM Plugin Log ==============================
  05-03 11:02:02.543 I found instance=clearkey version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.544 I found instance=default version=android.hardware.drm@1.0::IDrmFactory
  05-03 11:02:02.544 I found instance=playready version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.544 I found instance=widevine version=android.hardware.drm@1.4::IDrmFactory
  05-03 11:02:02.581 I [(0):] L3 Terminate.
  05-03 11:02:02.581 E [oemcrypto_adapter_dynamic.cpp(831):Level1Terminate] L1 Terminate
  05-03 11:02:02.670 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.671 I [oemcrypto_adapter_dynamic.cpp(783):Initialize] Level 3 Build Info (v16): OEMCrypto Level3 Code 28919 Feb  2 2023 02:50:27
  05-03 11:02:02.673 I [(0):] Level3 Library 28919 Feb  2 2023 02:50:27
  05-03 11:02:02.685 I [oemcrypto_adapter_dynamic.cpp(797):Initialize] L3 Initialized. Trying L1.
  05-03 11:02:02.706 I [oemcrypto_adapter_dynamic.cpp(819):Initialize] Level 1 Build Info (v16): OEMCrypto Ref Code Feb 16 2023 16:36:51
  05-03 11:02:02.706 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.713 I [usage_table_header.cpp(204):RestoreTable] Found usage table to restore: entry_count = 54
  05-03 11:02:02.722 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.723 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.724 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.725 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.726 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.726 I [cdm_engine.cpp(190):OpenSession] New session: session_id = sid13
  05-03 11:02:02.726 I [cdm_engine.cpp(929):QueryOemCryptoSessionId] session_id = sid13
  05-03 11:02:02.732 I [cdm_engine.cpp(235):CloseSession] session_id = sid13
  05-03 11:02:02.739 I [cdm_engine.cpp(201):OpenKeySetSession] key_set_id = ksidDE02CFED
  05-03 11:02:02.740 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.741 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.742 I [file_utils.cpp(40):Exists] stat failed: ENOENT
  05-03 11:02:02.742 I [cdm_engine.cpp(190):OpenSession] New session: session_id = sid14
  05-03 11:02:02.742 I [cdm_engine.cpp(277):GenerateKeyRequest] session_id = <empty>, key_set_id = ksidDE02CFED, license_type = Release
  05-03 11:02:02.778 E [crypto_session.cpp(109):MapOEMCryptoResult] Mapping OEMCrypto result: crypto_session_method = PrepareAndSignRenewalRequest, result = 29
  05-03 11:02:02.778 E [cdm_engine.cpp(337):GenerateKeyRequest] CdmSession::GenerateKeyRequest failed: session_id = sid14, status = 323
  05-03 11:02:02.778 E [cdm_engine.cpp(1325):RemoveOfflineLicense] GenerateKeyRequest failed: status = 323
  05-03 11:02:02.778 I [cdm_engine.cpp(246):CloseKeySetSession] key_set_id = ksidDE02CFED
  05-03 11:02:02.778 I [cdm_engine.cpp(235):CloseSession] session_id = sid14
============================== End of DRM Plugin Log ==============================
	at android.media.MediaDrm.removeOfflineLicense(Native Method)
	at com.google.android.wvts.MediaDrmParameterizedTests.clearUsageTable(MediaDrmParameterizedTests.java:406)
	at com.google.android.wvts.MediaDrmParameterizedTests.testUsageTableCapacity(MediaDrmParameterizedTests.java:484)

05-03 11:02:06 I/ConsoleReporter: [SKBFXYZO0DKW] armeabi-v7a WvtsDeviceTestCases completed in 8s. 0 passed, 1 failed, 0 not executed
05-03 11:02:06 I/ITestSuite: Running system status checker after module execution: armeabi-v7a WvtsDeviceTestCases
05-03 11:02:06 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:02:06 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
05-03 11:02:06 I/DeviceStateMonitor: Waiting 359928 ms for device SKBFXYZO0DKW package manager
05-03 11:02:06 I/NativeDeviceStateMonitor: Waiting 359811 ms for device SKBFXYZO0DKW external store
05-03 11:02:08 I/MonitoringUtils: Wifi Connectivity: passed check.
05-03 11:02:08 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

05-03 11:02:08 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:08 D/SystemServerFileDescriptorChecker: Skipping system_server fd check on user builds.
05-03 11:02:08 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/results/2024.05.03_11.00.54/proto with prefix "tmp-proto" suffix ""
05-03 11:02:08 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
05-03 11:02:08 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:02:08 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "device_logcat_test_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:02:08 I/LogFileSaver: Saved log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_test_SKBFXYZO0DKW_2141248173498737358.txt. [size=505701, elapsed=2ms]
05-03 11:02:08 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYZO0DKW in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_test_SKBFXYZO0DKW_2141248173498737358.txt
05-03 11:02:08 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:02:08 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "device_logcat_test_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:02:08 I/LogFileSaver: Saved log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/device_logcat_test_SKBFXYZO0DKW_4731128648018970436.txt. [size=505701, elapsed=3ms]
05-03 11:02:08 I/CommandInterrupter: Interrupt blocked
05-03 11:02:08 D/TestInvocation: Testing minimum responsiveness.
05-03 11:02:08 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:02:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.sdk] with timeout: 45s
05-03 11:02:08 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "executeShellCommandLog_SKBFXYZO0DKW_" suffix ".txt.gz"
05-03 11:02:08 I/LogFileSaver: Saved gzip log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/executeShellCommandLog_SKBFXYZO0DKW_2725581924509488840.txt.gz. [size=10, elapsed=1ms]
05-03 11:02:08 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYZO0DKW in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/executeShellCommandLog_SKBFXYZO0DKW_2725581924509488840.txt.gz
05-03 11:02:08 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "executeShellCommandLog_SKBFXYZO0DKW_" suffix ".txt.gz"
05-03 11:02:08 I/LogFileSaver: Saved gzip log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/executeShellCommandLog_SKBFXYZO0DKW_16219353708529945016.txt.gz. [size=10, elapsed=1ms]
05-03 11:02:08 D/TestInvocation: Checking that devices are online.
05-03 11:02:08 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
05-03 11:02:08 I/NativeDeviceStateMonitor: Waiting 180000 ms for device SKBFXYZO0DKW boot complete
05-03 11:02:08 I/DeviceStateMonitor: Waiting 179964 ms for device SKBFXYZO0DKW package manager
05-03 11:02:08 I/NativeDeviceStateMonitor: Waiting 179902 ms for device SKBFXYZO0DKW external store
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@75e62330' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@75e62330' on device: 'SKBFXYZO0DKW' in 1 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@21a5b666' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@21a5b666' on device: 'SKBFXYZO0DKW' in 0 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@fd89613' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@fd89613' on device: 'SKBFXYZO0DKW' in 132 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1c336bc3' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1c336bc3' on device: 'SKBFXYZO0DKW' in 70 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@560c9ecc' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-03 11:02:09 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
05-03 11:02:09 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@560c9ecc' on device: 'SKBFXYZO0DKW' in 133 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@207151a7' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@207151a7' on device: 'SKBFXYZO0DKW' in 3 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@4c60486f' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@4c60486f' on device: 'SKBFXYZO0DKW' in 0 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@a50fca3' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@a50fca3' on device: 'SKBFXYZO0DKW' in 1 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@52bb0cdd' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@52bb0cdd' on device: 'SKBFXYZO0DKW' in 0 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@1564621e' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@1564621e' on device: 'SKBFXYZO0DKW' in 0 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@53a3b940' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@53a3b940' on device: 'SKBFXYZO0DKW' in 1 ms
05-03 11:02:09 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@792c55da' on device: 'SKBFXYZO0DKW'
05-03 11:02:09 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@792c55da' on device: 'SKBFXYZO0DKW' in 0 ms
05-03 11:02:09 D/TestDevice: Uninstalling android.tradefed.contentprovider with extra args 
05-03 11:02:09 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
05-03 11:02:10 D/WifiHelper: Successfully clean up WifiHelper.
05-03 11:02:10 D/RunUtil: Running command [id, -u, dev2-google04] with timeout: 1m 0s
05-03 11:02:10 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
05-03 11:02:11 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-03 11:02:11 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "host_adb_log_" suffix ".txt"
05-03 11:02:11 I/LogFileSaver: Saved log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/host_adb_log_6932224234058546826.txt. [size=27981, elapsed=1ms]
05-03 11:02:11 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/host_adb_log_6932224234058546826.txt
05-03 11:02:11 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-03 11:02:11 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "host_adb_log_" suffix ".txt"
05-03 11:02:11 I/LogFileSaver: Saved log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/host_adb_log_15797287761180958637.txt. [size=27981, elapsed=0ms]
05-03 11:02:11 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:02:11 D/FileUtil: Creating temp file at /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038 with prefix "device_logcat_teardown_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:02:11 I/LogFileSaver: Saved log file /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_teardown_SKBFXYZO0DKW_11030184397043720110.txt. [size=46070, elapsed=0ms]
05-03 11:02:11 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYZO0DKW in /home/dev2-google04/android-gts/android-gts-11-R3(11-14)-11398508/android-gts/tools/./../../android-gts/logs/2024.05.03_11.00.54/inv_3771815289934718038/device_logcat_teardown_SKBFXYZO0DKW_11030184397043720110.txt
05-03 11:02:11 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0DKW is already compressed, skipping compression
05-03 11:02:11 D/FileUtil: Creating temp file at /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528 with prefix "device_logcat_teardown_SKBFXYZO0DKW_" suffix ".txt"
05-03 11:02:11 I/LogFileSaver: Saved log file /tmp/11398508/gts/inv_9179119033709553168/inv_14403684830876340528/device_logcat_teardown_SKBFXYZO0DKW_5465178409545390204.txt. [size=46070, elapsed=0ms]
05-03 11:02:11 I/TestInvocation: Done stopping logcat for SKBFXYZO0DKW
05-03 11:02:11 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYZO0DKW. May see duplicated content in log.
05-03 11:02:11 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0DKW online before starting.
05-03 11:02:11 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYZO0DKW(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
05-03 11:02:11 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYZO0DKW shell to be responsive
05-03 11:02:11 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

05-03 11:02:11 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@1eacf9cd=AVAILABLE}
05-03 11:02:11 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYZO0DKW
