04-13 17:14:11 D/Configuration: Resolve and download remote files from @Option
04-13 17:14:11 I/TestInvocation: Invocation was started with cmd: cts -m CtsGraphicsTestCases -t android.graphics.cts.MatchContentFrameRateTest
04-13 17:14:11 D/InvocationExecution: Skip linking external directory as FileProperty was set.
04-13 17:14:11 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
04-13 17:14:11 D/TestInvocation: Fetch build duration: 3 ms
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:11 D/GlobalTestFilter: Setting up global filters
04-13 17:14:11 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for 0001201904000455.
04-13 17:14:11 I/TestInvocation: Starting invocation for 'cts' with '[ DeviceBuildInfo{bid=9566628, serial=0001201904000455} on device '0001201904000455'] 
04-13 17:14:11 D/FileUtil: Creating temp directory at /tmp/9566628/cts with prefix "inv_"
04-13 17:14:11 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9566628/cts/inv_13290276993911769585] with timeout: 10s
04-13 17:14:11 D/FileSystemLogSaver: Using log file directory /tmp/9566628/cts/inv_13290276993911769585
04-13 17:14:11 D/FileUtil: Creating temp directory at /tmp/9566628/cts/inv_13290276993911769585 with prefix "inv_"
04-13 17:14:11 I/LogFileSaver: Using log file directory /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911
04-13 17:14:11 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11/proto
04-13 17:14:11 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11/proto with prefix "tmp-proto" suffix ""
04-13 17:14:11 D/CertificationSuiteResultReporter: Initializing result directory
04-13 17:14:11 D/CertificationSuiteResultReporter: Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11
04-13 17:14:11 D/CertificationSuiteResultReporter: Created log dir /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11
04-13 17:14:11 D/FileUtil: Creating temp directory at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11 with prefix "inv_"
04-13 17:14:11 I/LogFileSaver: Using log file directory /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513
04-13 17:14:11 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11/proto
04-13 17:14:11 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
04-13 17:14:11 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "tradefed-expanded-config_" suffix ".xml"
04-13 17:14:11 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/tradefed-expanded-config_7956500348138175354.xml. [size=31749, elapsed=0ms]
04-13 17:14:11 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/tradefed-expanded-config_7956500348138175354.xml
04-13 17:14:11 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
04-13 17:14:11 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "tradefed-expanded-config_" suffix ".xml"
04-13 17:14:11 I/LogFileSaver: Saved log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/tradefed-expanded-config_9099339411395213175.xml. [size=31749, elapsed=1ms]
04-13 17:14:11 D/InvocationExecution: Starting device pre invocation setup for : '0001201904000455'
04-13 17:14:11 I/CommandInterrupter: Interrupt allowed
04-13 17:14:11 D/InvocationExecution: Starting setup for device: '0001201904000455'
04-13 17:14:11 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@76635791' on device: '0001201904000455'
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.treble.enabled] with timeout: 2m 0s
04-13 17:14:11 D/NativeDevice: Using 'ls' to check doesFileExist(/sys/fs/selinux/policy)
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:11 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@76635791' on device: '0001201904000455' in 315 ms
04-13 17:14:11 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@523bbfe3' on device: '0001201904000455'
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.treble.enabled] with timeout: 2m 0s
04-13 17:14:11 D/NativeDevice: Using 'ls' to check doesFileExist(/proc/config.gz)
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:11 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:11 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@523bbfe3' on device: '0001201904000455' in 302 ms
04-13 17:14:11 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@1ae072ac' on device: '0001201904000455'
04-13 17:14:11 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@1ae072ac' on device: '0001201904000455' in 125 ms
04-13 17:14:11 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@70204235' on device: '0001201904000455'
04-13 17:14:12 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@70204235' on device: '0001201904000455' in 98 ms
04-13 17:14:12 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@1c135c7f' on device: '0001201904000455'
04-13 17:14:12 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@1c135c7f' on device: '0001201904000455' in 107 ms
04-13 17:14:12 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@13d83cb9' on device: '0001201904000455'
04-13 17:14:12 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
04-13 17:14:12 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@13d83cb9' on device: '0001201904000455' in 61 ms
04-13 17:14:12 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@a34597c' on device: '0001201904000455'
04-13 17:14:12 D/SettingsPreparer: Setting hide_error_dialogs to value 1
04-13 17:14:12 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@a34597c' on device: '0001201904000455' in 62 ms
04-13 17:14:12 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@78f793e5' on device: '0001201904000455'
04-13 17:14:12 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:14:12 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:14:12 I/DeviceStateMonitor: Waiting 359959 ms for device 0001201904000455 package manager
04-13 17:14:12 I/NativeDeviceStateMonitor: Waiting 359873 ms for device 0001201904000455 external store
04-13 17:14:12 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.cts
04-13 17:14:12 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:12 D/CtsPreconditions.apk: Uploading CtsPreconditions.apk onto device '0001201904000455'
04-13 17:14:12 D/Device: Uploading file onto device '0001201904000455'
04-13 17:14:13 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsPreconditions.apk] with timeout: 1m 0s
04-13 17:14:13 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:14:13 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, appops, write-settings] with timeout: 2m 0s
04-13 17:14:14 D/ListInstrumentationParser: instrumentation:com.android.preconditions.cts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.cts)
04-13 17:14:14 D/ListInstrumentationParser: 
04-13 17:14:14 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
04-13 17:14:14 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:14 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:14 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:14 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:14 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
04-13 17:14:14 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-0001201904000455
04-13 17:14:16 D/BackgroundDeviceAction: Waiting for device 0001201904000455 online before starting.
04-13 17:14:16 D/BackgroundDeviceAction: Device 0001201904000455 now online.
04-13 17:14:16 D/BackgroundDeviceAction: Starting logcat for 0001201904000455.
04-13 17:14:16 D/TestDevice: Uninstalling com.android.preconditions.cts with extra args 
04-13 17:14:16 D/ApkInstrumentationPreparer: Target preparation successful
04-13 17:14:16 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@78f793e5' on device: '0001201904000455' in 4s
04-13 17:14:16 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@7e81c820' on device: '0001201904000455'
04-13 17:14:17 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:14:17 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:14:17 I/DeviceStateMonitor: Waiting 359929 ms for device 0001201904000455 package manager
04-13 17:14:17 I/NativeDeviceStateMonitor: Waiting 359782 ms for device 0001201904000455 external store
04-13 17:14:17 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:17 D/WifiUtil.apk14223815016060790904.apk: Uploading WifiUtil.apk14223815016060790904.apk onto device '0001201904000455'
04-13 17:14:17 D/Device: Uploading file onto device '0001201904000455'
04-13 17:14:18 D/RunUtil: Running command [aapt, dump, badging, /tmp/WifiUtil.apk14223815016060790904.apk] with timeout: 1m 0s
04-13 17:14:18 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/WifiUtil.apk14223815016060790904.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:14:18 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, appops, write-settings] with timeout: 2m 0s
04-13 17:14:20 I/WifiCheck: Wifi is connected
04-13 17:14:20 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@7e81c820' on device: '0001201904000455' in 3s
04-13 17:14:20 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2c6e1ebd' on device: '0001201904000455'
04-13 17:14:20 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
04-13 17:14:20 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
04-13 17:14:20 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, locksettings, set-disabled, true] with timeout: 2m 0s
04-13 17:14:21 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2c6e1ebd' on device: '0001201904000455' in 1s
04-13 17:14:21 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@4b95df5' on device: '0001201904000455'
04-13 17:14:21 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.model] with timeout: 2m 0s
04-13 17:14:21 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.device] with timeout: 2m 0s
04-13 17:14:21 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.id] with timeout: 2m 0s
04-13 17:14:21 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.serialno] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.name] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.tags] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.incremental] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.brand] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.board] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.type] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.release] with timeout: 2m 0s
04-13 17:14:22 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
04-13 17:14:22 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:14:22 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:14:22 I/DeviceStateMonitor: Waiting 359959 ms for device 0001201904000455 package manager
04-13 17:14:22 I/NativeDeviceStateMonitor: Waiting 359892 ms for device 0001201904000455 external store
04-13 17:14:23 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
04-13 17:14:23 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:23 D/CtsDeviceInfo.apk: Uploading CtsDeviceInfo.apk onto device '0001201904000455'
04-13 17:14:23 D/Device: Uploading file onto device '0001201904000455'
04-13 17:14:25 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsDeviceInfo.apk] with timeout: 1m 0s
04-13 17:14:25 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:14:25 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, appops, write-settings] with timeout: 2m 0s
04-13 17:14:25 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
04-13 17:14:25 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-13 17:14:25 D/ListInstrumentationParser: 
04-13 17:14:25 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
04-13 17:14:25 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:25 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:25 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:25 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:25 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
04-13 17:14:25 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-0001201904000455
04-13 17:14:40 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
04-13 17:14:40 D/ApkInstrumentationPreparer: Target preparation successful
04-13 17:14:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:41 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
04-13 17:14:42 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "framework_manifest.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/framework_manifest.xml_1021522609697247425.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/framework_manifest.xml_1021522609697247425.txt.gz
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "framework_manifest.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/framework_manifest.xml_8611383733471461947.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/GenericDeviceInfo.deviceinfo.json_7817379154483864761.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "device_manifest.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_manifest.xml_15575261068386165403.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_manifest.xml_15575261068386165403.txt.gz
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "device_manifest.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/device_manifest.xml_14971899645420237589.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/MediaDeviceInfo.deviceinfo.json_17692319296377773317.txt.gz. [size=10, elapsed=2ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/StorageDeviceInfo.deviceinfo.json_12111226250395812061.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "SensorDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/SensorDeviceInfo.deviceinfo.json_6584339864546827849.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "AppStandbyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/AppStandbyDeviceInfo.deviceinfo.json_2963664601166313533.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_compatibility_matrix.xml_7715909941828789101.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_compatibility_matrix.xml_7715909941828789101.txt.gz
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/device_compatibility_matrix.xml_6366902120572109364.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/PackageDeviceInfo.deviceinfo.json_8129847502140159855.txt.gz. [size=522, elapsed=8ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/framework_compatibility_matrix.xml_10296291149778943550.txt.gz. [size=10, elapsed=2ms]
04-13 17:14:42 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/framework_compatibility_matrix.xml_10296291149778943550.txt.gz
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/framework_compatibility_matrix.xml_10842580738166104042.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/UserDeviceInfo.deviceinfo.json_9830226531801765710.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/FeatureDeviceInfo.deviceinfo.json_17676911533602173708.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/ConfigurationDeviceInfo.deviceinfo.json_16421169277423136555.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/VintfDeviceInfo.deviceinfo.json_16872225203707234366.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/LocaleDeviceInfo.deviceinfo.json_12765522597106028926.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "CameraDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/CameraDeviceInfo.deviceinfo.json_10452786154245570183.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/CpuDeviceInfo.deviceinfo.json_8662087442210500707.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "VulkanDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/VulkanDeviceInfo.deviceinfo.json_13452368000551592657.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/ScreenDeviceInfo.deviceinfo.json_2578793228517033949.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/GraphicsDeviceInfo.deviceinfo.json_13958017496127863284.txt.gz. [size=10, elapsed=1ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/ClientIdDeviceInfo.deviceinfo.json_8390854604827827033.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
04-13 17:14:42 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/MemoryDeviceInfo.deviceinfo.json_12998671005370092852.txt.gz. [size=10, elapsed=0ms]
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@4b95df5' on device: '0001201904000455' in 20s
04-13 17:14:42 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@40158e3c' on device: '0001201904000455'
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@40158e3c' on device: '0001201904000455' in 0 ms
04-13 17:14:42 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@30259ed6' on device: '0001201904000455'
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@30259ed6' on device: '0001201904000455' in 91 ms
04-13 17:14:42 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ce2f599' on device: '0001201904000455'
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.type] with timeout: 2m 0s
04-13 17:14:42 W/PropertyCheck: Expected "user" but found "userdebug" for property: ro.build.type
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ce2f599' on device: '0001201904000455' in 42 ms
04-13 17:14:42 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@227a5b05' on device: '0001201904000455'
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.locale] with timeout: 2m 0s
04-13 17:14:42 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@227a5b05' on device: '0001201904000455' in 37 ms
04-13 17:14:42 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@4e724518' on device: '0001201904000455'
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, persist.sys.test_harness] with timeout: 2m 0s
04-13 17:14:42 W/PropertyCheck: Property "persist.sys.test_harness" not found on device, cannot verify value "false" 
04-13 17:14:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@4e724518' on device: '0001201904000455' in 36 ms
04-13 17:14:42 D/InvocationExecution: Done with setup of device: '0001201904000455'
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, uname, -a] with timeout: 2m 0s
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.system.build.fingerprint] with timeout: 2m 0s
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
04-13 17:14:42 D/InvocationExecution: Total setup duration: 31s'
04-13 17:14:42 D/LogFileSaver: Log data for device_logcat_setup_0001201904000455 is already compressed, skipping compression
04-13 17:14:42 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "device_logcat_setup_0001201904000455_" suffix ".txt"
04-13 17:14:42 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_setup_0001201904000455_13146740184404811933.txt. [size=3408325, elapsed=6ms]
04-13 17:14:42 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_0001201904000455 in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_setup_0001201904000455_13146740184404811933.txt
04-13 17:14:42 D/LogFileSaver: Log data for device_logcat_setup_0001201904000455 is already compressed, skipping compression
04-13 17:14:42 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "device_logcat_setup_0001201904000455_" suffix ".txt"
04-13 17:14:42 I/LogFileSaver: Saved log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/device_logcat_setup_0001201904000455_9966400243321985908.txt. [size=3408325, elapsed=4ms]
04-13 17:14:42 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, cmd, device_state, print-states] with timeout: 2m 0s
04-13 17:14:42 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
04-13 17:14:42 D/ITestSuite: abi 'armeabi' is supported by device but not by this suite build ([arm64-v8a, armeabi-v7a]), tests will not run against it.
04-13 17:14:42 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 CtsGraphicsTestCases[run-on-secondary-user]=[CtsGraphicsTestCases[run-on-secondary-user] android.graphics.cts.MatchContentFrameRateTest], armeabi-v7a CtsGraphicsTestCases=[CtsGraphicsTestCases android.graphics.cts.MatchContentFrameRateTest], armeabi-v7a CtsGraphicsTestCases[instant]=[CtsGraphicsTestCases[instant] android.graphics.cts.MatchContentFrameRateTest], armeabi-v7a CtsGraphicsTestCases[run-on-work-profile]=[CtsGraphicsTestCases[run-on-work-profile] android.graphics.cts.MatchContentFrameRateTest]}
Excludes: {arm64-v8a CtsMediaBitstreamsTestCases=[arm64-v8a CtsMediaBitstreamsTestCases], x86_64 CtsMediaBitstreamsTestCases=[x86_64 CtsMediaBitstreamsTestCases], x86_64 CtsRenderscriptLegacyTestCases=[x86_64 CtsRenderscriptLegacyTestCases], x86_64 CtsLiblogTestCases=[x86_64 CtsLiblogTestCases liblog#event_log_tags], mips64 CtsMediaBitstreamsTestCases=[mips64 CtsMediaBitstreamsTestCases], mips64 CtsRenderscriptLegacyTestCases=[mips64 CtsRenderscriptLegacyTestCases], x86 CtsPerfettoTestCases=[x86 CtsPerfettoTestCases PerfettoTest#TestFtraceProducer], arm64-v8a CtsWrapWrapDebugMallocDebugTestCases=[arm64-v8a CtsWrapWrapDebugMallocDebugTestCases], arm64-v8a CtsRenderscriptLegacyTestCases=[arm64-v8a CtsRenderscriptLegacyTestCases]}
04-13 17:14:42 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
04-13 17:14:44 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
04-13 17:14:44 D/ITestSuite: [Total Unique Modules = 2]
04-13 17:14:44 D/BaseTestSuite: Filters for 'armeabi-v7a CtsGraphicsTestCases[instant]': []
04-13 17:14:44 I/ITestSuite: Running system status checker before module execution: armeabi-v7a CtsGraphicsTestCases[instant]
04-13 17:14:44 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:44 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:44 I/UserChecker: Current user 0 is already user type CURRENT, no action.
04-13 17:14:44 D/DeviceSettingChecker: Begin preExecutionCheck for checking device setting
04-13 17:14:44 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.type] with timeout: 2m 0s
04-13 17:14:45 I/DeviceBaselineChecker: Enable device baselines  in 0 millis.
04-13 17:14:45 D/ModuleDefinition: Running module armeabi-v7a CtsGraphicsTestCases[instant]
04-13 17:14:45 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.id] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.revision] with timeout: 2m 0s
04-13 17:14:45 D/NativeDevice: No valid MAC address queried from device 0001201904000455 by 'su root cat /sys/class/net/wlan0/address'
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.sim.state] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.id] with timeout: 2m 0s
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.revision] with timeout: 2m 0s
04-13 17:14:45 D/NativeDevice: No valid MAC address queried from device 0001201904000455 by 'su root cat /sys/class/net/wlan0/address'
04-13 17:14:45 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.sim.state] with timeout: 2m 0s
04-13 17:14:46 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
04-13 17:14:46 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] with timeout: 1m 0s
04-13 17:14:46 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:14:46 D/TestAppInstallSetup: Installing apk android.graphics.cts with [/home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] ...
04-13 17:14:46 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:14:46 D/CtsGraphicsTestCases.apk: Uploading CtsGraphicsTestCases.apk onto device '0001201904000455'
04-13 17:14:46 D/Device: Uploading file onto device '0001201904000455'
04-13 17:15:03 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] with timeout: 1m 0s
04-13 17:15:03 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:15:03 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, appops, write-settings] with timeout: 2m 0s
04-13 17:15:03 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
04-13 17:15:03 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
04-13 17:15:03 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
04-13 17:15:03 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include_" suffix ".txt.gz"
04-13 17:15:03 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include_2665906489215900928.txt.gz. [size=10, elapsed=1ms]
04-13 17:15:03 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include_2665906489215900928.txt.gz
04-13 17:15:03 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include_" suffix ".txt.gz"
04-13 17:15:03 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/filter-armeabi-v7a%20CtsGraphicsTestCases%5Binstant%5D18147882453663375261.include_8656899981019065100.txt.gz. [size=10, elapsed=1ms]
04-13 17:15:04 D/ListInstrumentationParser: instrumentation:android.graphics.cts/androidx.test.runner.AndroidJUnitRunner (target=android.graphics.cts)
04-13 17:15:04 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-13 17:15:04 D/ListInstrumentationParser: 
04-13 17:15:04 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
04-13 17:15:04 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:04 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:15:04 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:04 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:04 D/InstrumentationTest: Collecting test info for android.graphics.cts on device 0001201904000455
04-13 17:15:04 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --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,com.android.compatibility.common.util.CtsDownstreamingTest,android.platform.test.annotations.AppModeFull -e timeout_msec 300000 android.graphics.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-0001201904000455
04-13 17:15:06 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
04-13 17:15:06 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --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,com.android.compatibility.common.util.CtsDownstreamingTest,android.platform.test.annotations.AppModeFull -e timeout_msec 300000 android.graphics.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-0001201904000455
04-13 17:15:07 D/ModuleListener: ModuleListener.testRunStarted(android.graphics.cts, 3, 0)
04-13 17:15:07 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always)
04-13 17:15:18 I/TestFailureListener: FailureListener.testFailed android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always false
04-13 17:15:18 I/ModuleListener: [1/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always FAILURE: java.lang.AssertionError: Timed out waiting for a stable and compatible frame rate. expected=60.00 received=60.00. Stack trace: android.graphics.cts.FrameRateCtsActivity$FrameRateTimeoutException
	at android.graphics.cts.FrameRateCtsActivity.verifyCompatibleAndStableFrameRate(FrameRateCtsActivity.java:562)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$8$FrameRateCtsActivity(FrameRateCtsActivity.java:970)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda0.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runOneSurfaceTest(FrameRateCtsActivity.java:867)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:962)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$9$FrameRateCtsActivity(FrameRateCtsActivity.java:978)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda5.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:630)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)
	at java.lang.reflect.Method.invoke(Native Method)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:61)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:148)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:142)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.lang.Thread.run(Thread.java:920)

	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:645)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)

04-13 17:15:19 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto)
04-13 17:15:28 I/ModuleListener: [2/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto PASSED
04-13 17:15:28 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None)
04-13 17:15:33 I/ModuleListener: [3/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None PASSED
04-13 17:15:34 D/ModuleListener: ModuleListener.testRunEnded(27319)
04-13 17:15:34 D/TestDevice: Uninstalling android.graphics.cts with extra args 
04-13 17:15:35 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:15:35 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:15:35 I/DeviceStateMonitor: Waiting 359898 ms for device 0001201904000455 package manager
04-13 17:15:35 I/NativeDeviceStateMonitor: Waiting 359782 ms for device 0001201904000455 external store
04-13 17:15:36 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:36 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, persist.sys.boot.reason.history] with timeout: 2m 0s
04-13 17:15:36 W/NativeDevice: There is no reboot history since 1681377330000
04-13 17:15:36 E/ModuleDefinition: Detected a soft-restart after module armeabi-v7a CtsGraphicsTestCases[instant]
04-13 17:15:36 I/ConsoleReporter: [0001201904000455] Starting armeabi-v7a CtsGraphicsTestCases[instant] with 3 tests
04-13 17:15:36 I/ConsoleReporter: [1/3 armeabi-v7a CtsGraphicsTestCases[instant] 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always fail: java.lang.AssertionError: Timed out waiting for a stable and compatible frame rate. expected=60.00 received=60.00. Stack trace: android.graphics.cts.FrameRateCtsActivity$FrameRateTimeoutException
	at android.graphics.cts.FrameRateCtsActivity.verifyCompatibleAndStableFrameRate(FrameRateCtsActivity.java:562)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$8$FrameRateCtsActivity(FrameRateCtsActivity.java:970)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda0.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runOneSurfaceTest(FrameRateCtsActivity.java:867)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:962)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$9$FrameRateCtsActivity(FrameRateCtsActivity.java:978)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda5.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:630)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)
	at java.lang.reflect.Method.invoke(Native Method)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:61)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:148)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:142)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.lang.Thread.run(Thread.java:920)

	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:645)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)

04-13 17:15:36 I/ConsoleReporter: [2/3 armeabi-v7a CtsGraphicsTestCases[instant] 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto pass
04-13 17:15:36 I/ConsoleReporter: [3/3 armeabi-v7a CtsGraphicsTestCases[instant] 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None pass
04-13 17:15:36 I/ConsoleReporter: [0001201904000455] armeabi-v7a CtsGraphicsTestCases[instant] completed in 32s. 2 passed, 1 failed, 0 not executed
04-13 17:15:36 I/ITestSuite: Running system status checker after module execution: armeabi-v7a CtsGraphicsTestCases[instant]
04-13 17:15:36 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:15:36 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:36 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:15:36 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:15:36 I/DeviceStateMonitor: Waiting 359938 ms for device 0001201904000455 package manager
04-13 17:15:36 I/NativeDeviceStateMonitor: Waiting 359877 ms for device 0001201904000455 external store
04-13 17:15:38 I/MonitoringUtils: Wifi Connectivity: passed check.
04-13 17:15:38 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

04-13 17:15:38 E/LeakedThreadStatusChecker: We have 3 threads instead of 1. List: [Thread[Invocation-0001201904000455,5,Invocation-0001201904000455], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-0001201904000455], Thread[Timer-7,5,Invocation-0001201904000455]]
04-13 17:15:38 W/ITestSuite: System status checker [com.android.tradefed.suite.checker.LeakedThreadStatusChecker] failed
04-13 17:15:38 D/NativeDevice: Time offset = 779 ms
04-13 17:15:38 D/DeviceSettingChecker: Begin postExecution for checking device setting
04-13 17:15:39 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:39 W/ITestSuite: There are failed system status checkers: {com.android.tradefed.suite.checker.LeakedThreadStatusChecker=We have 3 threads instead of 1. List: [Thread[Invocation-0001201904000455,5,Invocation-0001201904000455], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-0001201904000455], Thread[Timer-7,5,Invocation-0001201904000455]]}
04-13 17:15:39 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11/proto with prefix "tmp-proto" suffix ""
04-13 17:15:39 D/BaseTestSuite: Filters for 'armeabi-v7a CtsGraphicsTestCases': []
04-13 17:15:39 I/ITestSuite: Running system status checker before module execution: armeabi-v7a CtsGraphicsTestCases
04-13 17:15:39 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:15:39 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:39 I/UserChecker: Current user 0 is already user type CURRENT, no action.
04-13 17:15:39 D/DeviceSettingChecker: Begin preExecutionCheck for checking device setting
04-13 17:15:39 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 I/DeviceBaselineChecker: Enable device baselines  in 0 millis.
04-13 17:15:40 D/ModuleDefinition: Running module armeabi-v7a CtsGraphicsTestCases
04-13 17:15:40 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.id] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.revision] with timeout: 2m 0s
04-13 17:15:40 D/NativeDevice: No valid MAC address queried from device 0001201904000455 by 'su root cat /sys/class/net/wlan0/address'
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.sim.state] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
04-13 17:15:40 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:41 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.id] with timeout: 2m 0s
04-13 17:15:41 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.revision] with timeout: 2m 0s
04-13 17:15:41 D/NativeDevice: No valid MAC address queried from device 0001201904000455 by 'su root cat /sys/class/net/wlan0/address'
04-13 17:15:41 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.sim.state] with timeout: 2m 0s
04-13 17:15:41 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
04-13 17:15:41 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] with timeout: 1m 0s
04-13 17:15:41 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:15:41 D/TestAppInstallSetup: Installing apk android.graphics.cts with [/home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] ...
04-13 17:15:41 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:15:41 D/CtsGraphicsTestCases.apk: Uploading CtsGraphicsTestCases.apk onto device '0001201904000455'
04-13 17:15:41 D/Device: Uploading file onto device '0001201904000455'
04-13 17:16:00 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk] with timeout: 1m 0s
04-13 17:16:00 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-cts/tools/../../android-cts/testcases/CtsGraphicsTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
04-13 17:16:00 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, appops, write-settings] with timeout: 2m 0s
04-13 17:16:00 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
04-13 17:16:00 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
04-13 17:16:00 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
04-13 17:16:00 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include_" suffix ".txt.gz"
04-13 17:16:00 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include_7266485585435046157.txt.gz. [size=10, elapsed=0ms]
04-13 17:16:00 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include_7266485585435046157.txt.gz
04-13 17:16:00 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include_" suffix ".txt.gz"
04-13 17:16:00 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/filter-armeabi-v7a%20CtsGraphicsTestCases7945641904665719981.include_3840893220000800906.txt.gz. [size=10, elapsed=1ms]
04-13 17:16:00 D/ListInstrumentationParser: instrumentation:android.graphics.cts/androidx.test.runner.AndroidJUnitRunner (target=android.graphics.cts)
04-13 17:16:00 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-13 17:16:00 D/ListInstrumentationParser: 
04-13 17:16:00 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
04-13 17:16:00 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:00 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:16:00 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:00 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:00 D/InstrumentationTest: Collecting test info for android.graphics.cts on device 0001201904000455
04-13 17:16:00 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --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 timeout_msec 300000 android.graphics.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-0001201904000455
04-13 17:16:02 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
04-13 17:16:02 I/RemoteAndroidTest: Running am instrument -w -r --no-hidden-api-checks --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 timeout_msec 300000 android.graphics.cts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-0001201904000455
04-13 17:16:03 D/ModuleListener: ModuleListener.testRunStarted(android.graphics.cts, 3, 0)
04-13 17:16:03 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always)
04-13 17:16:14 I/TestFailureListener: FailureListener.testFailed android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always false
04-13 17:16:14 I/ModuleListener: [1/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always FAILURE: java.lang.AssertionError: Timed out waiting for a stable and compatible frame rate. expected=60.00 received=60.00. Stack trace: android.graphics.cts.FrameRateCtsActivity$FrameRateTimeoutException
	at android.graphics.cts.FrameRateCtsActivity.verifyCompatibleAndStableFrameRate(FrameRateCtsActivity.java:562)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$8$FrameRateCtsActivity(FrameRateCtsActivity.java:970)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda0.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runOneSurfaceTest(FrameRateCtsActivity.java:867)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:962)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$9$FrameRateCtsActivity(FrameRateCtsActivity.java:978)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda5.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:630)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)
	at java.lang.reflect.Method.invoke(Native Method)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:61)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:148)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:142)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.lang.Thread.run(Thread.java:920)

	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:645)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)

04-13 17:16:14 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto)
04-13 17:16:23 I/ModuleListener: [2/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto PASSED
04-13 17:16:24 D/ModuleListener: ModuleListener.testStarted(android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None)
04-13 17:16:28 I/ModuleListener: [3/3] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None PASSED
04-13 17:16:30 D/ModuleListener: ModuleListener.testRunEnded(27315)
04-13 17:16:30 D/TestDevice: Uninstalling android.graphics.cts with extra args 
04-13 17:16:30 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:16:30 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:16:30 I/DeviceStateMonitor: Waiting 359934 ms for device 0001201904000455 package manager
04-13 17:16:31 I/NativeDeviceStateMonitor: Waiting 359726 ms for device 0001201904000455 external store
04-13 17:16:31 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:31 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, persist.sys.boot.reason.history] with timeout: 2m 0s
04-13 17:16:31 W/NativeDevice: There is no reboot history since 1681377386000
04-13 17:16:31 E/ModuleDefinition: Detected a soft-restart after module armeabi-v7a CtsGraphicsTestCases
04-13 17:16:31 I/ConsoleReporter: [0001201904000455] Starting armeabi-v7a CtsGraphicsTestCases with 3 tests
04-13 17:16:31 I/ConsoleReporter: [1/3 armeabi-v7a CtsGraphicsTestCases 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Always fail: java.lang.AssertionError: Timed out waiting for a stable and compatible frame rate. expected=60.00 received=60.00. Stack trace: android.graphics.cts.FrameRateCtsActivity$FrameRateTimeoutException
	at android.graphics.cts.FrameRateCtsActivity.verifyCompatibleAndStableFrameRate(FrameRateCtsActivity.java:562)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$8$FrameRateCtsActivity(FrameRateCtsActivity.java:970)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda0.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runOneSurfaceTest(FrameRateCtsActivity.java:867)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:962)
	at android.graphics.cts.FrameRateCtsActivity.lambda$testMatchContentFramerate_Always$9$FrameRateCtsActivity(FrameRateCtsActivity.java:978)
	at android.graphics.cts.FrameRateCtsActivity$$ExternalSyntheticLambda5.run(Unknown Source:2)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:630)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)
	at java.lang.reflect.Method.invoke(Native Method)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:61)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:148)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:142)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.lang.Thread.run(Thread.java:920)

	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at android.graphics.cts.FrameRateCtsActivity.runTestsWithPreconditions(FrameRateCtsActivity.java:645)
	at android.graphics.cts.FrameRateCtsActivity.testMatchContentFramerate_Always(FrameRateCtsActivity.java:978)
	at android.graphics.cts.MatchContentFrameRateTest.testMatchContentFramerate_Always(MatchContentFrameRateTest.java:109)

04-13 17:16:31 I/ConsoleReporter: [2/3 armeabi-v7a CtsGraphicsTestCases 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_Auto pass
04-13 17:16:31 I/ConsoleReporter: [3/3 armeabi-v7a CtsGraphicsTestCases 0001201904000455] android.graphics.cts.MatchContentFrameRateTest#testMatchContentFramerate_None pass
04-13 17:16:31 I/ConsoleReporter: [0001201904000455] armeabi-v7a CtsGraphicsTestCases completed in 31s. 2 passed, 1 failed, 0 not executed
04-13 17:16:31 I/ITestSuite: Running system status checker after module execution: armeabi-v7a CtsGraphicsTestCases
04-13 17:16:32 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:16:32 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:32 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:16:32 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:16:32 I/DeviceStateMonitor: Waiting 359939 ms for device 0001201904000455 package manager
04-13 17:16:32 I/NativeDeviceStateMonitor: Waiting 359872 ms for device 0001201904000455 external store
04-13 17:16:34 I/MonitoringUtils: Wifi Connectivity: passed check.
04-13 17:16:34 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

04-13 17:16:34 E/LeakedThreadStatusChecker: We have 3 threads instead of 1. List: [Thread[Invocation-0001201904000455,5,Invocation-0001201904000455], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-0001201904000455], Thread[Timer-7,5,Invocation-0001201904000455]]
04-13 17:16:34 W/ITestSuite: System status checker [com.android.tradefed.suite.checker.LeakedThreadStatusChecker] failed
04-13 17:16:34 D/NativeDevice: Time offset = 541 ms
04-13 17:16:34 D/DeviceSettingChecker: Begin postExecution for checking device setting
04-13 17:16:34 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16: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-0001201904000455,5,Invocation-0001201904000455], Thread[BackgroundDeviceAction-logcat -v threadtime,uid,5,Invocation-0001201904000455], Thread[Timer-7,5,Invocation-0001201904000455]]}
04-13 17:16:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/results/2023.04.13_17.14.11/proto with prefix "tmp-proto" suffix ""
04-13 17:16:35 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
04-13 17:16:35 D/LogFileSaver: Log data for device_logcat_test_0001201904000455 is already compressed, skipping compression
04-13 17:16:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "device_logcat_test_0001201904000455_" suffix ".txt"
04-13 17:16:35 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_test_0001201904000455_6371698129944332435.txt. [size=1727344, elapsed=4ms]
04-13 17:16:35 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_0001201904000455 in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_test_0001201904000455_6371698129944332435.txt
04-13 17:16:35 D/LogFileSaver: Log data for device_logcat_test_0001201904000455 is already compressed, skipping compression
04-13 17:16:35 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "device_logcat_test_0001201904000455_" suffix ".txt"
04-13 17:16:35 I/LogFileSaver: Saved log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/device_logcat_test_0001201904000455_8498257872282000088.txt. [size=1727344, elapsed=3ms]
04-13 17:16:35 I/CommandInterrupter: Interrupt blocked
04-13 17:16:35 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "executeShellCommandLog_0001201904000455_" suffix ".txt.gz"
04-13 17:16:35 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/executeShellCommandLog_0001201904000455_2973397757486932711.txt.gz. [size=10, elapsed=2ms]
04-13 17:16:35 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_0001201904000455 in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/executeShellCommandLog_0001201904000455_2973397757486932711.txt.gz
04-13 17:16:35 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "executeShellCommandLog_0001201904000455_" suffix ".txt.gz"
04-13 17:16:35 I/LogFileSaver: Saved gzip log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/executeShellCommandLog_0001201904000455_5257640291000633985.txt.gz. [size=10, elapsed=1ms]
04-13 17:16:35 D/TestInvocation: Checking that devices are online.
04-13 17:16:35 I/NativeDeviceStateMonitor: Device 0001201904000455 is already ONLINE
04-13 17:16:35 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 0001201904000455 boot complete
04-13 17:16:35 I/DeviceStateMonitor: Waiting 359939 ms for device 0001201904000455 package manager
04-13 17:16:35 I/NativeDeviceStateMonitor: Waiting 359878 ms for device 0001201904000455 external store
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@4e724518' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@4e724518' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@227a5b05' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@227a5b05' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ce2f599' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@6ce2f599' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@30259ed6' on device: '0001201904000455'
04-13 17:16:35 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@30259ed6' on device: '0001201904000455' in 60 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@40158e3c' on device: '0001201904000455'
04-13 17:16:35 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
04-13 17:16:35 D/RunUtil: Running command [adb, -s, 0001201904000455, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
04-13 17:16:35 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
04-13 17:16:35 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@40158e3c' on device: '0001201904000455' in 198 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@4b95df5' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@4b95df5' on device: '0001201904000455' in 1 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2c6e1ebd' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@2c6e1ebd' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@7e81c820' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@7e81c820' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@78f793e5' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@78f793e5' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@a34597c' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@a34597c' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@13d83cb9' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@13d83cb9' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@1c135c7f' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PackageDisabler@1c135c7f' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@70204235' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@70204235' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@1ae072ac' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@1ae072ac' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@523bbfe3' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@523bbfe3' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@76635791' on device: '0001201904000455'
04-13 17:16:35 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceFileCollector@76635791' on device: '0001201904000455' in 0 ms
04-13 17:16:35 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
04-13 17:16:36 D/WifiHelper: Successfully clean up WifiHelper.
04-13 17:16:36 D/RunUtil: Running command [id, -u, xts] with timeout: 1m 0s
04-13 17:16:36 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
04-13 17:16:36 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
04-13 17:16:36 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "host_adb_log_" suffix ".txt"
04-13 17:16:36 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/host_adb_log_6940472410224311347.txt. [size=902, elapsed=0ms]
04-13 17:16:36 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/host_adb_log_6940472410224311347.txt
04-13 17:16:36 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
04-13 17:16:36 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "host_adb_log_" suffix ".txt"
04-13 17:16:36 I/LogFileSaver: Saved log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/host_adb_log_17434226498349525499.txt. [size=902, elapsed=0ms]
04-13 17:16:36 D/LogFileSaver: Log data for device_logcat_teardown_0001201904000455 is already compressed, skipping compression
04-13 17:16:36 D/FileUtil: Creating temp file at /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513 with prefix "device_logcat_teardown_0001201904000455_" suffix ".txt"
04-13 17:16:36 I/LogFileSaver: Saved log file /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_teardown_0001201904000455_4700299936167879439.txt. [size=12103, elapsed=1ms]
04-13 17:16:36 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_0001201904000455 in /home/xts/tools/android-cts/tools/../../android-cts/logs/2023.04.13_17.14.11/inv_13168773413416226513/device_logcat_teardown_0001201904000455_4700299936167879439.txt
04-13 17:16:36 D/LogFileSaver: Log data for device_logcat_teardown_0001201904000455 is already compressed, skipping compression
04-13 17:16:36 D/FileUtil: Creating temp file at /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911 with prefix "device_logcat_teardown_0001201904000455_" suffix ".txt"
04-13 17:16:36 I/LogFileSaver: Saved log file /tmp/9566628/cts/inv_13290276993911769585/inv_13382655654703367911/device_logcat_teardown_0001201904000455_14757595913578046791.txt. [size=12103, elapsed=0ms]
04-13 17:16:36 I/TestInvocation: Done stopping logcat for 0001201904000455
04-13 17:16:36 D/CommandScheduler: TestDeviceState for releasing '0001201904000455(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
04-13 17:16:36 I/NativeDeviceStateMonitor: Waiting 30000 ms for device 0001201904000455 shell to be responsive
04-13 17:16:36 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on 0001201904000455. May see duplicated content in log.
04-13 17:16:36 D/BackgroundDeviceAction: Waiting for device 0001201904000455 online before starting.
04-13 17:16:36 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@34f809bc=AVAILABLE}
04-13 17:16:36 W/NativeDevice: Attempting to stop logcat when not capturing for 0001201904000455
