11-29 19:24:40 D/Configuration: Resolve and download remote files from @Option
11-29 19:24:40 I/TestInvocation: Invocation was started with cmd: cts -m CtsViewTestCases -t android.view.cts.DisplayRefreshRateTest#testRefreshRate
11-29 19:24:40 D/InvocationExecution: Skip linking external directory as FileProperty was set.
11-29 19:24:40 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
11-29 19:24:40 D/TestInvocation: Fetch build duration: 5 ms
11-29 19:24:40 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 45s
11-29 19:24:40 D/GlobalTestFilter: Setting up global filters
11-29 19:24:40 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
11-29 19:24:40 I/TestInvocation: Starting invocation for 'cts' with '[ DeviceBuildInfo{bid=10513372, serial=SKBFXYU4133L} on device 'SKBFXYU4133L'] 
11-29 19:24:40 D/FileUtil: Creating temp directory at /tmp/10513372/cts with prefix "inv_"
11-29 19:24:40 D/RunUtil: Running command [chmod, ug+rwx, /tmp/10513372/cts/inv_14631551153566452346] with timeout: 10s
11-29 19:24:40 D/FileSystemLogSaver: Using log file directory /tmp/10513372/cts/inv_14631551153566452346
11-29 19:24:40 D/FileUtil: Creating temp directory at /tmp/10513372/cts/inv_14631551153566452346 with prefix "inv_"
11-29 19:24:40 I/LogFileSaver: Using log file directory /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915
11-29 19:24:40 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.11.29_19.24.40/proto
11-29 19:24:40 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/results/2023.11.29_19.24.40/proto with prefix "tmp-proto" suffix ""
11-29 19:24:40 D/CertificationSuiteResultReporter: Initializing result directory
11-29 19:24:40 D/CertificationSuiteResultReporter: Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.11.29_19.24.40
11-29 19:24:40 D/CertificationSuiteResultReporter: Created log dir /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40
11-29 19:24:40 D/FileUtil: Creating temp directory at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40 with prefix "inv_"
11-29 19:24:40 I/LogFileSaver: Using log file directory /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938
11-29 19:24:40 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.11.29_19.24.40/proto
11-29 19:24:40 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
11-29 19:24:40 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "tradefed-expanded-config_" suffix ".xml"
11-29 19:24:40 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/tradefed-expanded-config_12820542875285331056.xml. [size=32823, elapsed=0ms]
11-29 19:24:40 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/tradefed-expanded-config_12820542875285331056.xml
11-29 19:24:40 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
11-29 19:24:40 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "tradefed-expanded-config_" suffix ".xml"
11-29 19:24:40 I/LogFileSaver: Saved log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/tradefed-expanded-config_9954894812493289168.xml. [size=32823, elapsed=0ms]
11-29 19:24:40 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYU4133L'
11-29 19:24:40 D/DefaultConnection: Instance type for connection: GCE
11-29 19:24:40 D/NativeDevice: Using connection: com.android.tradefed.device.connection.DefaultConnection@7d99bc4a (SKBFXYU4133L)
11-29 19:24:40 I/CommandInterrupter: Interrupt allowed
11-29 19:24:40 D/InvocationExecution: Starting setup for device: 'SKBFXYU4133L'
11-29 19:24:40 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@73efe3bc' on device: 'SKBFXYU4133L'
11-29 19:24:40 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.treble.enabled] with timeout: 45s
11-29 19:24:40 D/NativeDevice: Using 'ls' to check doesFileExist(/sys/fs/selinux/policy)
11-29 19:24:40 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@73efe3bc' on device: 'SKBFXYU4133L' in 246 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@6bb87baf' on device: 'SKBFXYU4133L'
11-29 19:24:41 D/NativeDevice: Using property ro.treble.enabled=true from cache.
11-29 19:24:41 D/NativeDevice: Using 'ls' to check doesFileExist(/proc/config.gz)
11-29 19:24:41 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@6bb87baf' on device: 'SKBFXYU4133L' in 149 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@46197603' on device: 'SKBFXYU4133L'
11-29 19:24:41 I/UrlReplacement: UrlReplacement file [/ssd2/tools/android-cts/tools/UrlReplacement.xml] does not exist
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@46197603' on device: 'SKBFXYU4133L' in 234 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@5b8bd02' on device: 'SKBFXYU4133L'
11-29 19:24:41 D/SettingsPreparer: Changing value for stay_on_while_plugged_in from 0 to 7
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@5b8bd02' on device: 'SKBFXYU4133L' in 150 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@610ca5f3' on device: 'SKBFXYU4133L'
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@610ca5f3' on device: 'SKBFXYU4133L' in 103 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@72d07592' on device: 'SKBFXYU4133L'
11-29 19:24:41 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@72d07592' on device: 'SKBFXYU4133L' in 62 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@675574b0' on device: 'SKBFXYU4133L'
11-29 19:24:41 D/SettingsPreparer: Setting hide_error_dialogs to value 1
11-29 19:24:41 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@675574b0' on device: 'SKBFXYU4133L' in 62 ms
11-29 19:24:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@b69b0c' on device: 'SKBFXYU4133L'
11-29 19:24:41 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:24:41 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYU4133L boot complete
11-29 19:24:41 I/DeviceStateMonitor: Waiting 359938 ms for device SKBFXYU4133L package manager
11-29 19:24:42 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYU4133L external store
11-29 19:24:42 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.cts
11-29 19:24:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 45s
11-29 19:24:42 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-29 19:24:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:42 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:42 D/CtsPreconditions.apk: Uploading CtsPreconditions.apk onto device 'SKBFXYU4133L'
11-29 19:24:42 D/Device: Uploading file onto device 'SKBFXYU4133L'
11-29 19:24:43 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsPreconditions.apk] with timeout: 1m 0s
11-29 19:24:43 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
11-29 19:24:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
11-29 19:24:44 D/ListInstrumentationParser: instrumentation:com.android.preconditions.cts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.cts)
11-29 19:24:44 D/ListInstrumentationParser: 
11-29 19:24:44 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-29 19:24:44 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:44 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:44 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:44 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-29 19:24:44 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage   -e newRunListenerMode true -e timeout_msec 300000 com.android.preconditions.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYU4133L
11-29 19:24:45 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
11-29 19:24:45 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
11-29 19:24:45 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
11-29 19:24:46 D/TestDevice: Uninstalling com.android.preconditions.cts with extra args 
11-29 19:24:46 D/ApkInstrumentationPreparer: Target preparation successful
11-29 19:24:46 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@b69b0c' on device: 'SKBFXYU4133L' in 5s
11-29 19:24:46 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@6d99cfce' on device: 'SKBFXYU4133L'
11-29 19:24:47 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:24:47 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
11-29 19:24:47 I/DeviceStateMonitor: Waiting 359918 ms for device SKBFXYU4133L package manager
11-29 19:24:47 I/NativeDeviceStateMonitor: Waiting 359800 ms for device SKBFXYU4133L external store
11-29 19:24:47 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:47 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:47 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:47 D/WifiUtil.apk11380742617667342764.apk: Uploading WifiUtil.apk11380742617667342764.apk onto device 'SKBFXYU4133L'
11-29 19:24:47 D/Device: Uploading file onto device 'SKBFXYU4133L'
11-29 19:24:48 D/RunUtil: Running command [aapt, dump, badging, /tmp/WifiUtil.apk11380742617667342764.apk] with timeout: 1m 0s
11-29 19:24:48 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/WifiUtil.apk11380742617667342764.apk, AndroidManifest.xml] with timeout: 1m 0s
11-29 19:24:48 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
11-29 19:24:50 I/WifiCheck: Wifi is connected
11-29 19:24:50 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@6d99cfce' on device: 'SKBFXYU4133L' in 3s
11-29 19:24:50 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7e6c4032' on device: 'SKBFXYU4133L'
11-29 19:24:50 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
11-29 19:24:50 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
11-29 19:24:50 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, locksettings, set-disabled, true] with timeout: 2m 0s
11-29 19:24:52 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7e6c4032' on device: 'SKBFXYU4133L' in 1s
11-29 19:24:52 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@554f1d3a' on device: 'SKBFXYU4133L'
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.model] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.device] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 45s
11-29 19:24:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.manufacturer] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.serialno] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.security_patch] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist32] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.reference.fingerprint] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist64] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.name] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.tags] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.incremental] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.brand] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.base_os] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.board] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.type] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.release] with timeout: 45s
11-29 19:24:52 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi2] with timeout: 45s
11-29 19:24:53 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:24:53 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
11-29 19:24:53 I/DeviceStateMonitor: Waiting 359938 ms for device SKBFXYU4133L package manager
11-29 19:24:53 I/NativeDeviceStateMonitor: Waiting 359871 ms for device SKBFXYU4133L external store
11-29 19:24:53 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
11-29 19:24:53 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:53 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:53 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-29 19:24:53 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:53 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:53 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:53 D/CtsDeviceInfo.apk: Uploading CtsDeviceInfo.apk onto device 'SKBFXYU4133L'
11-29 19:24:53 D/Device: Uploading file onto device 'SKBFXYU4133L'
11-29 19:24:56 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsDeviceInfo.apk] with timeout: 1m 0s
11-29 19:24:56 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
11-29 19:24:56 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
11-29 19:24:56 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
11-29 19:24:56 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
11-29 19:24:56 D/ListInstrumentationParser: 
11-29 19:24:56 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-29 19:24:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:56 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:24:56 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:24:56 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-29 19:24:56 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-SKBFXYU4133L
11-29 19:25:16 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
11-29 19:25:16 D/ApkInstrumentationPreparer: Target preparation successful
11-29 19:25:16 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:16 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
11-29 19:25:17 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "framework_manifest.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/framework_manifest.xml_3793819701315192900.txt.gz. [size=10, elapsed=3ms]
11-29 19:25:17 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/framework_manifest.xml_3793819701315192900.txt.gz
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "framework_manifest.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/framework_manifest.xml_4148981004778710462.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/GenericDeviceInfo.deviceinfo.json_6487759350359168171.txt.gz. [size=10, elapsed=1ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "device_manifest.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_manifest.xml_5226146791806131448.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_manifest.xml_5226146791806131448.txt.gz
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "device_manifest.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/device_manifest.xml_18048233373820641573.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/MediaDeviceInfo.deviceinfo.json_9274145390234494997.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/StorageDeviceInfo.deviceinfo.json_15549829569864008116.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "SensorDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/SensorDeviceInfo.deviceinfo.json_17345005906181117838.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "AppStandbyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/AppStandbyDeviceInfo.deviceinfo.json_9983022645984014747.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_compatibility_matrix.xml_1415385334374872632.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_compatibility_matrix.xml_1415385334374872632.txt.gz
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/device_compatibility_matrix.xml_17967185904636453478.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/PackageDeviceInfo.deviceinfo.json_8795759894502802659.txt.gz. [size=25669, elapsed=3ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/framework_compatibility_matrix.xml_3345839888738773620.txt.gz. [size=10, elapsed=2ms]
11-29 19:25:17 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/framework_compatibility_matrix.xml_3345839888738773620.txt.gz
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/framework_compatibility_matrix.xml_23550608173089895.txt.gz. [size=10, elapsed=1ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/UserDeviceInfo.deviceinfo.json_12851592426418354190.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/FeatureDeviceInfo.deviceinfo.json_15558257557085546560.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/ConfigurationDeviceInfo.deviceinfo.json_12018423385601089742.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/VintfDeviceInfo.deviceinfo.json_15150654663017400707.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/LocaleDeviceInfo.deviceinfo.json_2718610238484525311.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "CameraDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/CameraDeviceInfo.deviceinfo.json_9260836597926980618.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/CpuDeviceInfo.deviceinfo.json_8216827560492818173.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "VulkanDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/VulkanDeviceInfo.deviceinfo.json_940444742016295240.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/ScreenDeviceInfo.deviceinfo.json_17665641252858465897.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/GraphicsDeviceInfo.deviceinfo.json_1920753358609722528.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/ClientIdDeviceInfo.deviceinfo.json_763032737642196445.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-29 19:25:17 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/MemoryDeviceInfo.deviceinfo.json_8275371751722949940.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@554f1d3a' on device: 'SKBFXYU4133L' in 25s
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@1532af89' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@1532af89' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@251383a3' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@251383a3' on device: 'SKBFXYU4133L' in 63 ms
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@6dad673' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, settings, put, global, verifier_engprod, 1] with timeout: 2m 0s
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@6dad673' on device: 'SKBFXYU4133L' in 64 ms
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@194eed86' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/NativeDevice: Using property ro.build.type=userdebug from cache.
11-29 19:25:17 W/PropertyCheck: Expected "user" but found "userdebug" for property: ro.build.type
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@194eed86' on device: 'SKBFXYU4133L' in 2 ms
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@1e39215a' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.locale] with timeout: 45s
11-29 19:25:17 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@1e39215a' on device: 'SKBFXYU4133L' in 43 ms
11-29 19:25:17 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ccec7c8' on device: 'SKBFXYU4133L'
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, persist.sys.test_harness] with timeout: 45s
11-29 19:25:17 W/PropertyCheck: Property "persist.sys.test_harness" not found on device, cannot verify value "false" 
11-29 19:25:17 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ccec7c8' on device: 'SKBFXYU4133L' in 60 ms
11-29 19:25:17 D/InvocationExecution: Done with setup of device: 'SKBFXYU4133L'
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, uname, -a] with timeout: 2m 0s
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.system.build.fingerprint] with timeout: 45s
11-29 19:25:17 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.58-20230830:userdebug/release-keys from cache.
11-29 19:25:17 D/InvocationExecution: Total setup duration: 37s'
11-29 19:25:17 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
11-29 19:25:17 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
11-29 19:25:17 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_setup_SKBFXYU4133L_11806595185305679470.txt. [size=3658647, elapsed=4ms]
11-29 19:25:17 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYU4133L in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_setup_SKBFXYU4133L_11806595185305679470.txt
11-29 19:25:17 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
11-29 19:25:17 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
11-29 19:25:17 I/LogFileSaver: Saved log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/device_logcat_setup_SKBFXYU4133L_11776499602278354196.txt. [size=3658647, elapsed=2ms]
11-29 19:25:17 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
11-29 19:25:17 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, cmd, device_state, print-states] with timeout: 2m 0s
11-29 19:25:18 D/NativeDevice: Using property ro.product.cpu.abilist=armeabi-v7a,armeabi from cache.
11-29 19:25:18 D/ITestSuite: abi 'armeabi' is supported by device but not by this suite build ([arm64-v8a, armeabi-v7a]), tests will not run against it.
11-29 19:25:18 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[com.android.tradefed.testtype.AndroidJUnitTest:exclude-annotation:com.android.compatibility.common.util.CtsDownstreamingTest, com.android.compatibility.common.tradefed.testtype.JarHostTest:exclude-annotation:com.android.compatibility.common.util.CtsDownstreamingTest, com.android.tradefed.testtype.HostTest:exclude-annotation:com.android.compatibility.common.util.CtsDownstreamingTest, com.android.tradefed.testtype.AndroidJUnitTest:exclude-annotation:android.platform.test.annotations.AsbSecurityTest, com.android.compatibility.common.tradefed.testtype.JarHostTest:exclude-annotation:android.platform.test.annotations.AsbSecurityTest, com.android.tradefed.testtype.AndroidJUnitTest:exclude-annotation:android.platform.test.annotations.AppModeInstant, com.android.compatibility.common.tradefed.testtype.JarHostTest:exclude-annotation:android.platform.test.annotations.AppModeInstant, com.android.tradefed.testtype.HostTest:exclude-annotation:android.platform.test.annotations.AppModeInstant]
Module Args:[]
Includes: {armeabi-v7a CtsViewTestCases=[CtsViewTestCases android.view.cts.DisplayRefreshRateTest#testRefreshRate], armeabi-v7a CtsViewTestCases[instant]=[CtsViewTestCases[instant] android.view.cts.DisplayRefreshRateTest#testRefreshRate], armeabi-v7a CtsViewTestCases[run-on-secondary-user]=[CtsViewTestCases[run-on-secondary-user] android.view.cts.DisplayRefreshRateTest#testRefreshRate], armeabi-v7a CtsViewTestCases[run-on-work-profile]=[CtsViewTestCases[run-on-work-profile] android.view.cts.DisplayRefreshRateTest#testRefreshRate], armeabi-v7a CtsViewTestCases[run-on-clone-profile]=[CtsViewTestCases[run-on-clone-profile] android.view.cts.DisplayRefreshRateTest#testRefreshRate]}
Excludes: {x86 CtsPerfettoTestCases=[x86 CtsPerfettoTestCases PerfettoTest#TestFtraceProducer], arm64-v8a CtsRenderscriptLegacyTestCases=[arm64-v8a CtsRenderscriptLegacyTestCases], x86_64 CtsRenderscriptLegacyTestCases=[x86_64 CtsRenderscriptLegacyTestCases], mips64 CtsRenderscriptLegacyTestCases=[mips64 CtsRenderscriptLegacyTestCases], x86_64 CtsLiblogTestCases=[x86_64 CtsLiblogTestCases liblog#event_log_tags], arm64-v8a CtsMediaBitstreamsTestCases=[arm64-v8a CtsMediaBitstreamsTestCases], x86_64 CtsMediaBitstreamsTestCases=[x86_64 CtsMediaBitstreamsTestCases], mips64 CtsMediaBitstreamsTestCases=[mips64 CtsMediaBitstreamsTestCases], arm64-v8a CtsWrapWrapDebugMallocDebugTestCases=[arm64-v8a CtsWrapWrapDebugMallocDebugTestCases], armeabi-v7a CtsViewTestCases=[CtsViewTestCases android.view.cts.ViewTest#testUpdateDragShadow]}
11-29 19:25:18 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
11-29 19:25:20 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
11-29 19:25:20 D/ITestSuite: [Total Unique Modules = 1]
11-29 19:25:20 D/BaseTestSuite: Filters for 'armeabi-v7a CtsViewTestCases': [CtsViewTestCases android.view.cts.ViewTest#testUpdateDragShadow]
11-29 19:25:20 I/ITestSuite: Running system status checker before module execution: armeabi-v7a CtsViewTestCases
11-29 19:25:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:20 I/UserChecker: Current user 0 is already user type CURRENT, no action.
11-29 19:25:20 D/DeviceSettingChecker: Begin preExecutionCheck for checking device setting
11-29 19:25:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.type=userdebug from cache.
11-29 19:25:21 I/DeviceBaselineChecker: Enable device baselines  in 0 millis.
11-29 19:25:21 D/ModuleDefinition: Running module armeabi-v7a CtsViewTestCases
11-29 19:25:21 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:25:21 D/RunUtil: Running command [aapt2, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsViewTestCases.apk] with timeout: 1m 0s
11-29 19:25:21 D/RunUtil: Running command [aapt2, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsViewTestCases.apk, --file, AndroidManifest.xml] with timeout: 1m 0s
11-29 19:25:21 D/TestAppInstallSetup: Installing apk android.view.cts with [/home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsViewTestCases.apk] ...
11-29 19:25:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:21 D/CtsViewTestCases.apk: Uploading CtsViewTestCases.apk onto device 'SKBFXYU4133L'
11-29 19:25:21 D/Device: Uploading file onto device 'SKBFXYU4133L'
11-29 19:25:24 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsViewTestCases.apk] with timeout: 1m 0s
11-29 19:25:24 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsViewTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
11-29 19:25:24 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
11-29 19:25:24 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:24 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:25:24 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-29 19:25:24 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
11-29 19:25:24 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
11-29 19:25:24 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
11-29 19:25:25 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include_" suffix ".txt.gz"
11-29 19:25:25 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include_3608881687836095980.txt.gz. [size=10, elapsed=1ms]
11-29 19:25:25 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include_3608881687836095980.txt.gz
11-29 19:25:25 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include_" suffix ".txt.gz"
11-29 19:25:25 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/filter-armeabi-v7a%20CtsViewTestCases7293742555383622959.include_17500867450271724215.txt.gz. [size=10, elapsed=1ms]
11-29 19:25:25 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/excludes.txt
11-29 19:25:25 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude_" suffix ".txt.gz"
11-29 19:25:25 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude_4594893131082320856.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:25 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude_4594893131082320856.txt.gz
11-29 19:25:25 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude_" suffix ".txt.gz"
11-29 19:25:25 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/filter-armeabi-v7a%20CtsViewTestCases6823964574449184230.exclude_6301795490060413213.txt.gz. [size=10, elapsed=0ms]
11-29 19:25:25 D/ListInstrumentationParser: instrumentation:android.view.cts/androidx.test.runner.AndroidJUnitRunner (target=android.view.cts)
11-29 19:25:25 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
11-29 19:25:25 D/ListInstrumentationParser: 
11-29 19:25:25 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-29 19:25:25 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:25:25 D/InstrumentationTest: Collecting test info for android.view.cts on device SKBFXYU4133L
11-29 19:25:25 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --no-isolated-storage --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log true -e notAnnotation android.platform.test.annotations.AsbSecurityTest,android.platform.test.annotations.AppModeInstant,com.android.compatibility.common.util.CtsDownstreamingTest -e notTestFile /data/local/tmp/ajur/excludes.txt -e timeout_msec 300000 android.view.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYU4133L
11-29 19:25:28 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-29 19:25:28 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --no-isolated-storage --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log false -e notAnnotation android.platform.test.annotations.AsbSecurityTest,android.platform.test.annotations.AppModeInstant,com.android.compatibility.common.util.CtsDownstreamingTest -e notTestFile /data/local/tmp/ajur/excludes.txt -e timeout_msec 300000 android.view.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYU4133L
11-29 19:25:29 D/ModuleListener: ModuleListener.testRunStarted(android.view.cts, 1, 0) on SKBFXYU4133L
11-29 19:25:29 D/ModuleListener: ModuleListener.testStarted(android.view.cts.DisplayRefreshRateTest#testRefreshRate) on SKBFXYU4133L
11-29 19:26:29 I/TestFailureListener: FailureListener.testFailed android.view.cts.DisplayRefreshRateTest#testRefreshRate false
11-29 19:26:29 I/ModuleListener: [1/1] SKBFXYU4133L android.view.cts.DisplayRefreshRateTest#testRefreshRate FAILURE: java.lang.AssertionError
	at org.junit.Assert.fail(Assert.java:87)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at org.junit.Assert.assertTrue(Assert.java:53)
	at android.view.cts.DisplayRefreshRateTest.testRefreshRate(DisplayRefreshRateTest.java:187)

11-29 19:26:31 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:31 D/FilePullerDeviceMetricCollector: Could not find a device file associated to pattern 'view_.*'.
11-29 19:26:31 D/ModuleListener: ModuleListener.testRunEnded(61910) on SKBFXYU4133L
11-29 19:26:31 D/TestDevice: Uninstalling android.view.cts with extra args 
11-29 19:26:32 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:26:32 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYU4133L boot complete
11-29 19:26:32 I/DeviceStateMonitor: Waiting 359902 ms for device SKBFXYU4133L package manager
11-29 19:26:32 I/NativeDeviceStateMonitor: Waiting 359689 ms for device SKBFXYU4133L external store
11-29 19:26:32 I/ConsoleReporter: [SKBFXYU4133L] Starting armeabi-v7a CtsViewTestCases with 1 test
11-29 19:26:32 I/ConsoleReporter: [1/1 armeabi-v7a CtsViewTestCases SKBFXYU4133L] android.view.cts.DisplayRefreshRateTest#testRefreshRate fail: java.lang.AssertionError
	at org.junit.Assert.fail(Assert.java:87)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at org.junit.Assert.assertTrue(Assert.java:53)
	at android.view.cts.DisplayRefreshRateTest.testRefreshRate(DisplayRefreshRateTest.java:187)

11-29 19:26:32 I/ConsoleReporter: [SKBFXYU4133L] armeabi-v7a CtsViewTestCases completed in 1m 7s. 0 passed, 1 failed, 0 not executed
11-29 19:26:32 I/ITestSuite: Running system status checker after module execution: armeabi-v7a CtsViewTestCases
11-29 19:26:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:32 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:32 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:26:32 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
11-29 19:26:32 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYU4133L package manager
11-29 19:26:32 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYU4133L external store
11-29 19:26:34 I/MonitoringUtils: Wifi Connectivity: passed check.
11-29 19:26:35 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

11-29 19:26:35 E/LeakedThreadStatusChecker: We have 3 threads instead of 1. List: [Thread[Invocation-SKBFXYU4133L,5,Invocation-SKBFXYU4133L], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-SKBFXYU4133L], Thread[Timer-1,5,Invocation-SKBFXYU4133L]]
11-29 19:26:35 W/ITestSuite: System status checker [com.android.tradefed.suite.checker.LeakedThreadStatusChecker] failed
11-29 19:26:35 D/NativeDevice: Time offset = 138 ms
11-29 19:26:35 D/DeviceSettingChecker: Begin postExecution for checking device setting
11-29 19:26:35 W/DeviceSettingChecker: Setting in namespace system fails:
 gearhead:driving_mode_settings_enabled is not found before module run
Setting in namespace global fails:
 Phenotype_flags changes from  to activity_starts_logging_enabled:alarm_manager_constants:alarm_manager_dummy_flags:always_on_display_constants:anomaly_config:anomaly_config_version:anomaly_detection_constants:app_idle_constants:app_standby_enabled:backup_agent_timeout_parameters:battery_stats_constants:battery_tip_constants:binder_calls_stats:ble_scan_balanced_interval_ms:ble_scan_balanced_window_ms:ble_scan_low_power_interval_ms:ble_scan_low_power_window_ms:blocking_helper_dismiss_to_view_ratio:blocking_helper_streak_limit:emergency_call_codes_data:gnss_satellite_blocklist:hybrid_sysui_battery_warning_flags:job_scheduler_constants:job_scheduler_quota_controller_constants:job_scheduler_time_controller_constants:location_background_throttle_interval_ms:location_ignore_settings_package_whitelist:network_watchlist_enabled:night_display_forced_auto_mode_available:notification_snooze_options:phenotype_test_setting:settings_use_external_provider_api:settings_use_psd_api:smart_replies_in_notifications_flags:sqlite_compatibility_wal_flags:sys_uidcpupower:text_classifier_constants:zram_enabled
Phenotype_boot_count changes from 1 to 2
night_display_forced_auto_mode_available,settings_use_psd_api,always_on_display_constants,ble_scan_low_power_interval_ms,emergency_call_codes_data,anomaly_config_version,alarm_manager_constants,binder_calls_stats,settings_use_external_provider_api,job_scheduler_constants,text_classifier_constants,sqlite_compatibility_wal_flags,ble_scan_balanced_window_ms,hybrid_sysui_battery_warning_flags,sys_uidcpupower,zram_enabled,anomaly_config,battery_tip_constants,job_scheduler_quota_controller_constants,backup_agent_timeout_parameters,anomaly_detection_constants,battery_stats_constants,smart_replies_in_notifications_flags,ble_scan_low_power_window_ms,location_background_throttle_interval_ms,ble_scan_balanced_interval_ms,activity_starts_logging_enabled,notification_snooze_options,app_idle_constants,app_standby_enabled,job_scheduler_time_controller_constants,network_watchlist_enabled,gnss_satellite_blocklist,blocking_helper_dismiss_to_view_ratio,blocking_helper_streak_limit,location_ignore_settings_package_whitelist,alarm_manager_dummy_flags,phenotype_test_setting is not found before module run

11-29 19:26:35 W/ITestSuite: System status checker [com.android.tradefed.suite.checker.DeviceSettingChecker] failed
11-29 19:26:35 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:35 W/ITestSuite: There are failed system status checkers: {com.android.tradefed.suite.checker.LeakedThreadStatusChecker=We have 3 threads instead of 1. List: [Thread[Invocation-SKBFXYU4133L,5,Invocation-SKBFXYU4133L], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-SKBFXYU4133L], Thread[Timer-1,5,Invocation-SKBFXYU4133L]], com.android.tradefed.suite.checker.DeviceSettingChecker=Setting in namespace system fails:
 gearhead:driving_mode_settings_enabled is not found before module run
Setting in namespace global fails:
 Phenotype_flags changes from  to activity_starts_logging_enabled:alarm_manager_constants:alarm_manager_dummy_flags:always_on_display_constants:anomaly_config:anomaly_config_version:anomaly_detection_constants:app_idle_constants:app_standby_enabled:backup_agent_timeout_parameters:battery_stats_constants:battery_tip_constants:binder_calls_stats:ble_scan_balanced_interval_ms:ble_scan_balanced_window_ms:ble_scan_low_power_interval_ms:ble_scan_low_power_window_ms:blocking_helper_dismiss_to_view_ratio:blocking_helper_streak_limit:emergency_call_codes_data:gnss_satellite_blocklist:hybrid_sysui_battery_warning_flags:job_scheduler_constants:job_scheduler_quota_controller_constants:job_scheduler_time_controller_constants:location_background_throttle_interval_ms:location_ignore_settings_package_whitelist:network_watchlist_enabled:night_display_forced_auto_mode_available:notification_snooze_options:phenotype_test_setting:settings_use_external_provider_api:settings_use_psd_api:smart_replies_in_notifications_flags:sqlite_compatibility_wal_flags:sys_uidcpupower:text_classifier_constants:zram_enabled
Phenotype_boot_count changes from 1 to 2
night_display_forced_auto_mode_available,settings_use_psd_api,always_on_display_constants,ble_scan_low_power_interval_ms,emergency_call_codes_data,anomaly_config_version,alarm_manager_constants,binder_calls_stats,settings_use_external_provider_api,job_scheduler_constants,text_classifier_constants,sqlite_compatibility_wal_flags,ble_scan_balanced_window_ms,hybrid_sysui_battery_warning_flags,sys_uidcpupower,zram_enabled,anomaly_config,battery_tip_constants,job_scheduler_quota_controller_constants,backup_agent_timeout_parameters,anomaly_detection_constants,battery_stats_constants,smart_replies_in_notifications_flags,ble_scan_low_power_window_ms,location_background_throttle_interval_ms,ble_scan_balanced_interval_ms,activity_starts_logging_enabled,notification_snooze_options,app_idle_constants,app_standby_enabled,job_scheduler_time_controller_constants,network_watchlist_enabled,gnss_satellite_blocklist,blocking_helper_dismiss_to_view_ratio,blocking_helper_streak_limit,location_ignore_settings_package_whitelist,alarm_manager_dummy_flags,phenotype_test_setting is not found before module run
}
11-29 19:26:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/results/2023.11.29_19.24.40/proto with prefix "tmp-proto" suffix ""
11-29 19:26:35 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
11-29 19:26:35 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
11-29 19:26:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
11-29 19:26:35 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_test_SKBFXYU4133L_7357883710265047180.txt. [size=5388834, elapsed=18ms]
11-29 19:26:35 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYU4133L in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_test_SKBFXYU4133L_7357883710265047180.txt
11-29 19:26:35 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
11-29 19:26:35 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
11-29 19:26:35 I/LogFileSaver: Saved log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/device_logcat_test_SKBFXYU4133L_3780997731124216809.txt. [size=5388834, elapsed=7ms]
11-29 19:26:35 I/CommandInterrupter: Interrupt blocked
11-29 19:26:35 D/TestInvocation: Testing minimum responsiveness.
11-29 19:26:35 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:26:35 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 45s
11-29 19:26:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
11-29 19:26:35 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/executeShellCommandLog_SKBFXYU4133L_17855898580787030422.txt.gz. [size=10, elapsed=4ms]
11-29 19:26:35 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYU4133L in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/executeShellCommandLog_SKBFXYU4133L_17855898580787030422.txt.gz
11-29 19:26:35 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
11-29 19:26:35 I/LogFileSaver: Saved gzip log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/executeShellCommandLog_SKBFXYU4133L_18240641256662464111.txt.gz. [size=10, elapsed=3ms]
11-29 19:26:35 D/TestInvocation: Checking that devices are online.
11-29 19:26:35 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
11-29 19:26:35 I/NativeDeviceStateMonitor: Waiting 180000 ms for device SKBFXYU4133L boot complete
11-29 19:26:35 I/DeviceStateMonitor: Waiting 179939 ms for device SKBFXYU4133L package manager
11-29 19:26:36 I/NativeDeviceStateMonitor: Waiting 179847 ms for device SKBFXYU4133L external store
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ccec7c8' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ccec7c8' on device: 'SKBFXYU4133L' in 1 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@1e39215a' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@1e39215a' on device: 'SKBFXYU4133L' in 1 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@194eed86' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@194eed86' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@6dad673' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, settings, put, global, verifier_engprod, 0] with timeout: 2m 0s
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@6dad673' on device: 'SKBFXYU4133L' in 66 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@251383a3' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@251383a3' on device: 'SKBFXYU4133L' in 64 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@1532af89' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-29 19:26:36 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
11-29 19:26:36 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@1532af89' on device: 'SKBFXYU4133L' in 128 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@554f1d3a' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@554f1d3a' on device: 'SKBFXYU4133L' in 2 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7e6c4032' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7e6c4032' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@6d99cfce' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@6d99cfce' on device: 'SKBFXYU4133L' in 1 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@b69b0c' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@b69b0c' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@675574b0' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@675574b0' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@72d07592' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@72d07592' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@610ca5f3' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@610ca5f3' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@5b8bd02' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@5b8bd02' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@46197603' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@46197603' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@6bb87baf' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@6bb87baf' on device: 'SKBFXYU4133L' in 1 ms
11-29 19:26:36 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@73efe3bc' on device: 'SKBFXYU4133L'
11-29 19:26:36 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@73efe3bc' on device: 'SKBFXYU4133L' in 0 ms
11-29 19:26:36 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
11-29 19:26:37 D/WifiHelper: Successfully clean up WifiHelper.
11-29 19:26:37 D/RunUtil: Running command [id, -u, xts] with timeout: 1m 0s
11-29 19:26:37 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
11-29 19:26:37 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
11-29 19:26:37 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "host_adb_log_" suffix ".txt"
11-29 19:26:37 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/host_adb_log_1590999313985825153.txt. [size=879, elapsed=1ms]
11-29 19:26:37 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/host_adb_log_1590999313985825153.txt
11-29 19:26:37 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
11-29 19:26:37 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "host_adb_log_" suffix ".txt"
11-29 19:26:37 I/LogFileSaver: Saved log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/host_adb_log_7318920485885533037.txt. [size=879, elapsed=1ms]
11-29 19:26:37 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
11-29 19:26:37 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
11-29 19:26:37 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_teardown_SKBFXYU4133L_15383861906121856290.txt. [size=15442, elapsed=1ms]
11-29 19:26:37 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYU4133L in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.11.29_19.24.40/inv_13092084184248176938/device_logcat_teardown_SKBFXYU4133L_15383861906121856290.txt
11-29 19:26:37 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
11-29 19:26:37 D/FileUtil: Creating temp file at /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
11-29 19:26:37 I/LogFileSaver: Saved log file /tmp/10513372/cts/inv_14631551153566452346/inv_727897867407908915/device_logcat_teardown_SKBFXYU4133L_13142520690740466508.txt. [size=15442, elapsed=1ms]
11-29 19:26:37 I/TestInvocation: Done stopping logcat for SKBFXYU4133L
11-29 19:26:37 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYU4133L. May see duplicated content in log.
11-29 19:26:37 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
11-29 19:26:37 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYU4133L(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
11-29 19:26:37 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYU4133L shell to be responsive
11-29 19:26:37 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

11-29 19:26:37 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@3fdc087=AVAILABLE}
11-29 19:26:37 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYU4133L
