05-26 14:31:41 D/Configuration: Resolve and download remote files from @Option
05-26 14:31:41 I/TestInvocation: Invocation was started with cmd: vts -m VtsHalGraphicsComposerV2_1TargetTest -t PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default
05-26 14:31:41 D/InvocationExecution: Skip linking external directory as FileProperty was set.
05-26 14:31:41 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
05-26 14:31:41 D/TestInvocation: Fetch build duration: 4 ms
05-26 14:31:41 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:41 D/GlobalTestFilter: Setting up global filters
05-26 14:31:41 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:31:41 I/TestInvocation: Starting invocation for 'vts' with '[ DeviceBuildInfo{bid=9504181, serial=SKBFXYU4133L} on device 'SKBFXYU4133L'] 
05-26 14:31:41 D/FileUtil: Creating temp directory at /tmp/9504181/vts with prefix "inv_"
05-26 14:31:41 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9504181/vts/inv_6686036451934023147] with timeout: 10s
05-26 14:31:41 D/FileSystemLogSaver: Using log file directory /tmp/9504181/vts/inv_6686036451934023147
05-26 14:31:41 D/FileUtil: Creating temp directory at /tmp/9504181/vts/inv_6686036451934023147 with prefix "inv_"
05-26 14:31:41 I/LogFileSaver: Using log file directory /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847
05-26 14:31:41 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.31.41/proto
05-26 14:31:41 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.31.41/proto with prefix "tmp-proto" suffix ""
05-26 14:31:41 D/CertificationSuiteResultReporter: Initializing result directory
05-26 14:31:41 D/CertificationSuiteResultReporter: Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.31.41
05-26 14:31:41 D/CertificationSuiteResultReporter: Created log dir /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41
05-26 14:31:41 D/FileUtil: Creating temp directory at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41 with prefix "inv_"
05-26 14:31:41 I/LogFileSaver: Using log file directory /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248
05-26 14:31:41 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.31.41/proto
05-26 14:31:41 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-26 14:31:41 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "tradefed-expanded-config_" suffix ".xml"
05-26 14:31:41 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/tradefed-expanded-config_3813247462861770681.xml. [size=4041, elapsed=0ms]
05-26 14:31:41 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/tradefed-expanded-config_3813247462861770681.xml
05-26 14:31:41 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-26 14:31:41 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "tradefed-expanded-config_" suffix ".xml"
05-26 14:31:41 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/tradefed-expanded-config_15607469243138228468.xml. [size=4041, elapsed=1ms]
05-26 14:31:41 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYU4133L'
05-26 14:31:41 I/CommandInterrupter: Interrupt allowed
05-26 14:31:41 D/InvocationExecution: Starting setup for device: 'SKBFXYU4133L'
05-26 14:31:41 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@386eeb96' on device: 'SKBFXYU4133L'
05-26 14:31:41 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:31:41 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:31:41 I/DeviceStateMonitor: Waiting 359936 ms for device SKBFXYU4133L package manager
05-26 14:31:41 I/NativeDeviceStateMonitor: Waiting 359873 ms for device SKBFXYU4133L external store
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.manufacturer] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.first_api_level] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.manufacturer] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.name] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.tags] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.brand] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.model] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.device] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.model] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.serialno] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:31:42 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.board] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.type] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.release] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.odm.build.fingerprint] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.odm.build.version.incremental] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.odm.device] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.boot.product.hardware.sku] with timeout: 2m 0s
05-26 14:31:43 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@386eeb96' on device: 'SKBFXYU4133L' in 1s
05-26 14:31:43 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@2af278aa' on device: 'SKBFXYU4133L'
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.model] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.device] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.serialno] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.name] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.tags] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.incremental] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.brand] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:31:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.board] with timeout: 2m 0s
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.type] with timeout: 2m 0s
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.release] with timeout: 2m 0s
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
05-26 14:31:44 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:31:44 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:31:44 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYU4133L package manager
05-26 14:31:44 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYU4133L external store
05-26 14:31:44 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
05-26 14:31:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:44 D/CtsDeviceInfo.apk: Uploading CtsDeviceInfo.apk onto device 'SKBFXYU4133L'
05-26 14:31:44 D/Device: Uploading file onto device 'SKBFXYU4133L'
05-26 14:31:46 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:31:46 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:31:46 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:31:46 D/RunUtil: Running command [aapt, dump, badging, /home/xts/tools/android-vts/tools/../../android-vts/testcases/CtsDeviceInfo/arm64/CtsDeviceInfo.apk] with timeout: 1m 0s
05-26 14:31:46 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/tools/android-vts/tools/../../android-vts/testcases/CtsDeviceInfo/arm64/CtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
05-26 14:31:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
05-26 14:31:46 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
05-26 14:31:46 D/ListInstrumentationParser: 
05-26 14:31:46 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
05-26 14:31:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:31:47 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:47 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:31:47 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:47 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
05-26 14:31:47 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 unknown-tv_gsi_on_arm-SKBFXYU4133L
05-26 14:31:59 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
05-26 14:31:59 D/ApkInstrumentationPreparer: Target preparation successful
05-26 14:31:59 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:31:59 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:31:59 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
05-26 14:32:00 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/framework_manifest.xml_9819094251587999612.txt.gz. [size=10, elapsed=7ms]
05-26 14:32:00 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/framework_manifest.xml_9819094251587999612.txt.gz
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/framework_manifest.xml_3858749759334799588.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/GenericDeviceInfo.deviceinfo.json_17376614877428780315.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_manifest.xml_12093386295137639968.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_manifest.xml_12093386295137639968.txt.gz
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/device_manifest.xml_18399279206323413931.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/MediaDeviceInfo.deviceinfo.json_13832723825183743351.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/StorageDeviceInfo.deviceinfo.json_4170957094197207727.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "SensorDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/SensorDeviceInfo.deviceinfo.json_11047318632791200494.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "AppStandbyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/AppStandbyDeviceInfo.deviceinfo.json_10649700252287238715.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_compatibility_matrix.xml_14046463053091706347.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:00 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_compatibility_matrix.xml_14046463053091706347.txt.gz
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/device_compatibility_matrix.xml_581150456437600414.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/PackageDeviceInfo.deviceinfo.json_15697137471196742182.txt.gz. [size=10, elapsed=3ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/framework_compatibility_matrix.xml_12894154106442857733.txt.gz. [size=10, elapsed=3ms]
05-26 14:32:00 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/framework_compatibility_matrix.xml_12894154106442857733.txt.gz
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/framework_compatibility_matrix.xml_9889994193671811874.txt.gz. [size=10, elapsed=2ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/UserDeviceInfo.deviceinfo.json_825438435958252904.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/FeatureDeviceInfo.deviceinfo.json_1799325915129164509.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/ConfigurationDeviceInfo.deviceinfo.json_13566490111936533026.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/VintfDeviceInfo.deviceinfo.json_12935745669396028870.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/LocaleDeviceInfo.deviceinfo.json_16693434638579739180.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "CameraDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/CameraDeviceInfo.deviceinfo.json_9167953333892260431.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/CpuDeviceInfo.deviceinfo.json_6781449474490610054.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "VulkanDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/VulkanDeviceInfo.deviceinfo.json_168453011127495012.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/ScreenDeviceInfo.deviceinfo.json_2344364258926289808.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/GraphicsDeviceInfo.deviceinfo.json_8616041539677506195.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/ClientIdDeviceInfo.deviceinfo.json_10898612668824997654.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:32:00 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/MemoryDeviceInfo.deviceinfo.json_11574167167894199623.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:00 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@2af278aa' on device: 'SKBFXYU4133L' in 17s
05-26 14:32:00 D/InvocationExecution: Done with setup of device: 'SKBFXYU4133L'
05-26 14:32:00 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, uname, -a] with timeout: 2m 0s
05-26 14:32:00 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.system.build.fingerprint] with timeout: 2m 0s
05-26 14:32:00 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:32:00 D/InvocationExecution: Total setup duration: 19s'
05-26 14:32:00 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:00 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:00 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_setup_SKBFXYU4133L_482528086849117972.txt. [size=1931768, elapsed=4ms]
05-26 14:32:00 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_setup_SKBFXYU4133L_482528086849117972.txt
05-26 14:32:00 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:00 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:00 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/device_logcat_setup_SKBFXYU4133L_3208466988531154783.txt. [size=1931768, elapsed=3ms]
05-26 14:32:00 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
05-26 14:32:00 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, cmd, device_state, print-states] with timeout: 2m 0s
05-26 14:32:01 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:32:01 D/ITestSuite: abi 'armeabi' is supported by device but not by this suite build ([arm64-v8a, armeabi-v7a]), tests will not run against it.
05-26 14:32:01 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[]
Module Args:[]
Includes: {armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest=[VtsHalGraphicsComposerV2_1TargetTest PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default]}

05-26 14:32:01 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm/tests/apm_config_b_204314749_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm/tests/apm_config_with_vx_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm/tests/apm_config_b_205808571_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm/tests/apm_config_no_vx_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm64/tests/apm_config_b_204314749_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm64/tests/apm_config_with_vx_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm64/tests/apm_config_b_205808571_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV7_0GeneratorTest/arm64/tests/apm_config_no_vx_7_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm/tests/apm_config_no_vx.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm/tests/apm_config_with_vx.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm/tests/apm_config_b_205808571_6_0.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm64/tests/apm_config_no_vx.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm64/tests/apm_config_with_vx.xml doesn't look like a test configuration.
05-26 14:32:01 E/ConfigurationUtil: /home/xts/tools/android-vts/tools/../../android-vts/testcases/HalAudioV6_0GeneratorTest/arm64/tests/apm_config_b_205808571_6_0.xml doesn't look like a test configuration.
05-26 14:32:02 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
05-26 14:32:02 D/ITestSuite: [Total Unique Modules = 1]
05-26 14:32:02 D/BaseTestSuite: Filters for 'armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest': []
05-26 14:32:02 D/ModuleDefinition: Running module armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest
05-26 14:32:02 D/ModuleDefinition: Running setup preparer: RootTargetPreparer
05-26 14:32:02 I/NativeDevice: adb root on device SKBFXYU4133L
05-26 14:32:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, root] with timeout: 2m 0s
05-26 14:32:02 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:32:05 I/NativeDeviceStateMonitor: Waiting for device SKBFXYU4133L to be in NOT_AVAILABLE mode for '2s'; it is currently in ONLINE mode...
05-26 14:32:07 W/NativeDevice: Did not detect device SKBFXYU4133L becoming unavailable after root
05-26 14:32:07 D/ModuleDefinition: Running setup preparer: StopServicesSetup
05-26 14:32:07 D/ModuleDefinition: Running setup preparer: PushFilePreparer
05-26 14:32:07 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:32:07 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:32:07 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:32:07 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:32:07 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:32:07 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.revision] with timeout: 2m 0s
05-26 14:32:07 D/NativeDevice: No valid MAC address queried from device SKBFXYU4133L by 'su root cat /sys/class/net/wlan0/address'
05-26 14:32:07 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, gsm.sim.state] with timeout: 2m 0s
05-26 14:32:08 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
05-26 14:32:08 D/PushFilePreparer: Trying to push local 'VtsHalGraphicsComposerV2_1TargetTest' to remote '/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest'
05-26 14:32:08 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest)
05-26 14:32:08 D/NativeDevice: /home/xts/tools/android-vts/tools/../../android-vts/testcases/VtsHalGraphicsComposerV2_1TargetTest/arm64 directory was not pushed because it was filtered.
05-26 14:32:08 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest)
05-26 14:32:08 I/GTest: Running gtest /data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest/arm/VtsHalGraphicsComposerV2_1TargetTest --gtest_print_time --gtest_filter=PerInstance/GraphicsComposerHidlTest.SetActiveConfig/0_default on SKBFXYU4133L
05-26 14:32:09 D/ModuleListener: ModuleListener.testRunStarted(VtsHalGraphicsComposerV2_1TargetTest, 1, 0)
05-26 14:32:09 D/ModuleListener: ModuleListener.testStarted(PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default)
05-26 14:32:11 E/GTestResultParser: Name for current test does not match test we started with, expected: SetActiveConfig/0_default  but got: SetActiveConfig/0_default, where GetParam() = "default"
05-26 14:32:11 I/TestFailureListener: FailureListener.testFailed PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default false
05-26 14:32:11 I/ModuleListener: [1/1] VtsHalGraphicsComposerV2_1TargetTest PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default FAILURE: hardware/interfaces/graphics/composer/2.1/vts/functional/VtsHalGraphicsComposerV2_1TargetTest.cpp:418: Failure
Expected equality of these values:
  config
    Which is: 5
  mComposerClient->getActiveConfig(mPrimaryDisplay)
    Which is: 0
05-26 14:32:11 D/ModuleListener: ModuleListener.testRunEnded(2497)
05-26 14:32:11 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:32:11 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:32:11 I/DeviceStateMonitor: Waiting 359892 ms for device SKBFXYU4133L package manager
05-26 14:32:18 I/NativeDeviceStateMonitor: Waiting 353523 ms for device SKBFXYU4133L external store
05-26 14:32:22 I/NativeDevice: adb unroot on device SKBFXYU4133L
05-26 14:32:22 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, unroot] with timeout: 2m 0s
05-26 14:32:22 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:32:25 I/NativeDeviceStateMonitor: Waiting for device SKBFXYU4133L to be in NOT_AVAILABLE mode for '5s'; it is currently in ONLINE mode...
05-26 14:32:27 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:32:27 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:32:27 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:32:30 W/NativeDevice: Did not detect device SKBFXYU4133L becoming unavailable after unroot
05-26 14:32:30 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:32:30 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:32:30 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:32:30 I/DeviceStateMonitor: Waiting 359938 ms for device SKBFXYU4133L package manager
05-26 14:32:30 I/NativeDeviceStateMonitor: Waiting 359876 ms for device SKBFXYU4133L external store
05-26 14:32:30 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:32:31 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, persist.sys.boot.reason.history] with timeout: 2m 0s
05-26 14:32:31 W/NativeDevice: There is no reboot history since 1685082745000
05-26 14:32:31 E/ModuleDefinition: Detected a soft-restart after module armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest
05-26 14:32:31 I/ConsoleReporter: [SKBFXYU4133L] Starting armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest with 1 test
05-26 14:32:31 I/ConsoleReporter: [1/1 armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest SKBFXYU4133L] PerInstance/GraphicsComposerHidlTest#SetActiveConfig/0_default fail: hardware/interfaces/graphics/composer/2.1/vts/functional/VtsHalGraphicsComposerV2_1TargetTest.cpp:418: Failure
Expected equality of these values:
  config
    Which is: 5
  mComposerClient->getActiveConfig(mPrimaryDisplay)
    Which is: 0
05-26 14:32:31 I/ConsoleReporter: [SKBFXYU4133L] armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest completed in 22s. 0 passed, 1 failed, 0 not executed
05-26 14:32:31 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.31.41/proto with prefix "tmp-proto" suffix ""
05-26 14:32:31 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
05-26 14:32:31 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_test_SKBFXYU4133L_12257002517912471436.txt. [size=6030068, elapsed=7ms]
05-26 14:32:31 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_test_SKBFXYU4133L_12257002517912471436.txt
05-26 14:32:31 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/device_logcat_test_SKBFXYU4133L_11459493755703127538.txt. [size=6030068, elapsed=4ms]
05-26 14:32:31 I/CommandInterrupter: Interrupt blocked
05-26 14:32:31 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
05-26 14:32:31 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/executeShellCommandLog_SKBFXYU4133L_10829964494754644623.txt.gz. [size=10, elapsed=1ms]
05-26 14:32:31 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/executeShellCommandLog_SKBFXYU4133L_10829964494754644623.txt.gz
05-26 14:32:31 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
05-26 14:32:31 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/executeShellCommandLog_SKBFXYU4133L_5179429278624283476.txt.gz. [size=10, elapsed=0ms]
05-26 14:32:31 D/TestInvocation: Checking that devices are online.
05-26 14:32:31 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:32:31 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:32:31 I/DeviceStateMonitor: Waiting 359939 ms for device SKBFXYU4133L package manager
05-26 14:32:31 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYU4133L external store
05-26 14:32:31 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@2af278aa' on device: 'SKBFXYU4133L'
05-26 14:32:31 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@2af278aa' on device: 'SKBFXYU4133L' in 2 ms
05-26 14:32:31 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@386eeb96' on device: 'SKBFXYU4133L'
05-26 14:32:31 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@386eeb96' on device: 'SKBFXYU4133L' in 0 ms
05-26 14:32:31 D/RunUtil: Running command [id, -u, xts] with timeout: 1m 0s
05-26 14:32:31 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
05-26 14:32:31 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "host_adb_log_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/host_adb_log_8406707610207547274.txt. [size=878, elapsed=0ms]
05-26 14:32:31 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/host_adb_log_8406707610207547274.txt
05-26 14:32:31 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "host_adb_log_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/host_adb_log_13339613246209291688.txt. [size=878, elapsed=0ms]
05-26 14:32:31 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_teardown_SKBFXYU4133L_11675217454772308024.txt. [size=784, elapsed=0ms]
05-26 14:32:31 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.31.41/inv_14248954604544800248/device_logcat_teardown_SKBFXYU4133L_11675217454772308024.txt
05-26 14:32:31 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
05-26 14:32:31 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
05-26 14:32:31 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_6686036451934023147/inv_15348587561648514847/device_logcat_teardown_SKBFXYU4133L_8022313259562196241.txt. [size=784, elapsed=0ms]
05-26 14:32:31 I/TestInvocation: Done stopping logcat for SKBFXYU4133L
05-26 14:32:31 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYU4133L. May see duplicated content in log.
05-26 14:32:31 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:32:31 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYU4133L(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
05-26 14:32:31 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYU4133L shell to be responsive
05-26 14:32:31 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@24347952=AVAILABLE}
05-26 14:32:31 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYU4133L
