05-26 14:33:43 D/Configuration: Resolve and download remote files from @Option
05-26 14:33:43 I/TestInvocation: Invocation was started with cmd: vts -m VtsHalGraphicsComposerV2_1TargetTest -t PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default
05-26 14:33:43 D/InvocationExecution: Skip linking external directory as FileProperty was set.
05-26 14:33:43 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
05-26 14:33:43 D/TestInvocation: Fetch build duration: 4 ms
05-26 14:33:43 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:43 D/GlobalTestFilter: Setting up global filters
05-26 14:33:43 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:33:43 I/TestInvocation: Starting invocation for 'vts' with '[ DeviceBuildInfo{bid=9504181, serial=SKBFXYU4133L} on device 'SKBFXYU4133L'] 
05-26 14:33:43 D/FileUtil: Creating temp directory at /tmp/9504181/vts with prefix "inv_"
05-26 14:33:43 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9504181/vts/inv_9575912153238284697] with timeout: 10s
05-26 14:33:43 D/FileSystemLogSaver: Using log file directory /tmp/9504181/vts/inv_9575912153238284697
05-26 14:33:43 D/FileUtil: Creating temp directory at /tmp/9504181/vts/inv_9575912153238284697 with prefix "inv_"
05-26 14:33:43 I/LogFileSaver: Using log file directory /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434
05-26 14:33:43 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.33.43/proto
05-26 14:33:43 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.33.43/proto with prefix "tmp-proto" suffix ""
05-26 14:33:43 D/CertificationSuiteResultReporter: Initializing result directory
05-26 14:33:43 D/CertificationSuiteResultReporter: Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.33.43
05-26 14:33:43 D/CertificationSuiteResultReporter: Created log dir /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43
05-26 14:33:43 D/FileUtil: Creating temp directory at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43 with prefix "inv_"
05-26 14:33:43 I/LogFileSaver: Using log file directory /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130
05-26 14:33:43 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.33.43/proto
05-26 14:33:43 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-26 14:33:43 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "tradefed-expanded-config_" suffix ".xml"
05-26 14:33:43 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/tradefed-expanded-config_3180871289564048209.xml. [size=4051, elapsed=0ms]
05-26 14:33:43 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/tradefed-expanded-config_3180871289564048209.xml
05-26 14:33:43 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
05-26 14:33:43 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "tradefed-expanded-config_" suffix ".xml"
05-26 14:33:43 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/tradefed-expanded-config_4299370597779566140.xml. [size=4051, elapsed=0ms]
05-26 14:33:43 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYU4133L'
05-26 14:33:43 I/CommandInterrupter: Interrupt allowed
05-26 14:33:43 D/InvocationExecution: Starting setup for device: 'SKBFXYU4133L'
05-26 14:33:43 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@4e29e56f' on device: 'SKBFXYU4133L'
05-26 14:33:43 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:33:43 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:33:43 I/DeviceStateMonitor: Waiting 359957 ms for device SKBFXYU4133L package manager
05-26 14:33:44 I/NativeDeviceStateMonitor: Waiting 359895 ms for device SKBFXYU4133L external store
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.manufacturer] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.first_api_level] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.manufacturer] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.name] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.tags] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.brand] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.model] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.device] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.vendor.model] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.serialno] with timeout: 2m 0s
05-26 14:33:44 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.board] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.type] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.release] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.odm.build.fingerprint] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.odm.build.version.incremental] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.odm.device] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.boot.product.hardware.sku] with timeout: 2m 0s
05-26 14:33:45 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@4e29e56f' on device: 'SKBFXYU4133L' in 1s
05-26 14:33:45 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@3f018283' on device: 'SKBFXYU4133L'
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.model] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.device] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.serialno] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
05-26 14:33:45 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.name] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.tags] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.incremental] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.brand] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.board] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.type] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.release] with timeout: 2m 0s
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
05-26 14:33:46 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:33:46 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:33:46 I/DeviceStateMonitor: Waiting 359969 ms for device SKBFXYU4133L package manager
05-26 14:33:46 I/NativeDeviceStateMonitor: Waiting 359908 ms for device SKBFXYU4133L external store
05-26 14:33:46 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
05-26 14:33:46 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:46 D/CtsDeviceInfo.apk: Uploading CtsDeviceInfo.apk onto device 'SKBFXYU4133L'
05-26 14:33:46 D/Device: Uploading file onto device 'SKBFXYU4133L'
05-26 14:33:48 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:33:48 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:33:48 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:33:49 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:33:49 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:33:49 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, appops, write-settings] with timeout: 2m 0s
05-26 14:33:49 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
05-26 14:33:49 D/ListInstrumentationParser: 
05-26 14:33:49 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
05-26 14:33:49 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:33:49 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:49 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:33:49 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:33:49 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
05-26 14:33:49 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:34:01 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
05-26 14:34:01 D/ApkInstrumentationPreparer: Target preparation successful
05-26 14:34:01 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
05-26 14:34:01 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:34:01 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
05-26 14:34:02 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/framework_manifest.xml_2162593496304871147.txt.gz. [size=10, elapsed=2ms]
05-26 14:34:02 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/framework_manifest.xml_2162593496304871147.txt.gz
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "framework_manifest.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/framework_manifest.xml_7330988554297214778.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/GenericDeviceInfo.deviceinfo.json_9365260569617437790.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_manifest.xml_6992112536577645189.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_manifest.xml_6992112536577645189.txt.gz
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "device_manifest.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/device_manifest.xml_9425860157084520804.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/MediaDeviceInfo.deviceinfo.json_2879849678961270409.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/StorageDeviceInfo.deviceinfo.json_17189769010213414880.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "SensorDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/SensorDeviceInfo.deviceinfo.json_643680250708916461.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "AppStandbyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/AppStandbyDeviceInfo.deviceinfo.json_17039438794742670780.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_compatibility_matrix.xml_12438508902366276386.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_compatibility_matrix.xml_12438508902366276386.txt.gz
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/device_compatibility_matrix.xml_157136315670971893.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/PackageDeviceInfo.deviceinfo.json_6879764561458479948.txt.gz. [size=10, elapsed=2ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/framework_compatibility_matrix.xml_12550732724678309974.txt.gz. [size=10, elapsed=2ms]
05-26 14:34:02 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/framework_compatibility_matrix.xml_12550732724678309974.txt.gz
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/framework_compatibility_matrix.xml_8676855762547706750.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/UserDeviceInfo.deviceinfo.json_4862728604904988822.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/FeatureDeviceInfo.deviceinfo.json_15590157264403356467.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/ConfigurationDeviceInfo.deviceinfo.json_12808269679826345395.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/VintfDeviceInfo.deviceinfo.json_8762993272430405760.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/LocaleDeviceInfo.deviceinfo.json_5969879696716215851.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "CameraDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/CameraDeviceInfo.deviceinfo.json_2308826194182661073.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/CpuDeviceInfo.deviceinfo.json_4808592044761876748.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "VulkanDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/VulkanDeviceInfo.deviceinfo.json_16178565481321795438.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/ScreenDeviceInfo.deviceinfo.json_7859115270280293817.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/GraphicsDeviceInfo.deviceinfo.json_14026304972668587041.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/ClientIdDeviceInfo.deviceinfo.json_17879439239388833395.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
05-26 14:34:02 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/MemoryDeviceInfo.deviceinfo.json_18073418402853941653.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:02 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@3f018283' on device: 'SKBFXYU4133L' in 17s
05-26 14:34:02 D/InvocationExecution: Done with setup of device: 'SKBFXYU4133L'
05-26 14:34:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, uname, -a] with timeout: 2m 0s
05-26 14:34:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.system.build.fingerprint] with timeout: 2m 0s
05-26 14:34:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
05-26 14:34:02 D/InvocationExecution: Total setup duration: 18s'
05-26 14:34:02 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:02 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:02 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_setup_SKBFXYU4133L_16106653910983491010.txt. [size=1796651, elapsed=4ms]
05-26 14:34:02 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_setup_SKBFXYU4133L_16106653910983491010.txt
05-26 14:34:02 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:02 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "device_logcat_setup_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:02 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/device_logcat_setup_SKBFXYU4133L_3148761919200152433.txt. [size=1796651, elapsed=3ms]
05-26 14:34:02 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
05-26 14:34:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, cmd, device_state, print-states] with timeout: 2m 0s
05-26 14:34:02 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
05-26 14:34:02 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:34:02 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[]
Module Args:[]
Includes: {armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest=[VtsHalGraphicsComposerV2_1TargetTest PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default]}

05-26 14:34:02 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
05-26 14:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:03 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:34:04 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
05-26 14:34:04 D/ITestSuite: [Total Unique Modules = 1]
05-26 14:34:04 D/BaseTestSuite: Filters for 'armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest': []
05-26 14:34:04 D/ModuleDefinition: Running module armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest
05-26 14:34:04 D/ModuleDefinition: Running setup preparer: RootTargetPreparer
05-26 14:34:04 I/NativeDevice: adb root on device SKBFXYU4133L
05-26 14:34:04 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, root] with timeout: 2m 0s
05-26 14:34:04 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:34:06 I/NativeDeviceStateMonitor: Waiting for device SKBFXYU4133L to be in NOT_AVAILABLE mode for '2s'; it is currently in ONLINE mode...
05-26 14:34:08 W/NativeDevice: Did not detect device SKBFXYU4133L becoming unavailable after root
05-26 14:34:09 D/ModuleDefinition: Running setup preparer: StopServicesSetup
05-26 14:34:09 D/ModuleDefinition: Running setup preparer: PushFilePreparer
05-26 14:34:09 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:34:09 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:34:09 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:34:09 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:34:09 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.id] with timeout: 2m 0s
05-26 14:34:09 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.revision] with timeout: 2m 0s
05-26 14:34:09 D/NativeDevice: No valid MAC address queried from device SKBFXYU4133L by 'su root cat /sys/class/net/wlan0/address'
05-26 14:34:09 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, gsm.sim.state] with timeout: 2m 0s
05-26 14:34:09 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
05-26 14:34:09 D/PushFilePreparer: Trying to push local 'VtsHalGraphicsComposerV2_1TargetTest' to remote '/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest'
05-26 14:34:10 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest)
05-26 14:34:10 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:34:10 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest)
05-26 14:34:10 I/GTest: Running gtest /data/local/tmp/VtsHalGraphicsComposerV2_1TargetTest/arm/VtsHalGraphicsComposerV2_1TargetTest --gtest_print_time --gtest_filter=PerInstance/GraphicsComposerHidlTest.SetActiveConfigPowerCycle/0_default on SKBFXYU4133L
05-26 14:34:10 D/ModuleListener: ModuleListener.testRunStarted(VtsHalGraphicsComposerV2_1TargetTest, 1, 0)
05-26 14:34:10 D/ModuleListener: ModuleListener.testStarted(PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default)
05-26 14:34:13 E/GTestResultParser: Name for current test does not match test we started with, expected: SetActiveConfigPowerCycle/0_default  but got: SetActiveConfigPowerCycle/0_default, where GetParam() = "default"
05-26 14:34:13 I/TestFailureListener: FailureListener.testFailed PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default false
05-26 14:34:13 I/ModuleListener: [1/1] VtsHalGraphicsComposerV2_1TargetTest PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default FAILURE: hardware/interfaces/graphics/composer/2.1/vts/functional/VtsHalGraphicsComposerV2_1TargetTest.cpp:437: Failure
Expected equality of these values:
  config
    Which is: 5
  mComposerClient->getActiveConfig(mPrimaryDisplay)
    Which is: 0
05-26 14:34:13 D/ModuleListener: ModuleListener.testRunEnded(2498)
05-26 14:34:13 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:34:13 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:34:13 I/DeviceStateMonitor: Waiting 359902 ms for device SKBFXYU4133L package manager
05-26 14:34:20 I/NativeDeviceStateMonitor: Waiting 353479 ms for device SKBFXYU4133L external store
05-26 14:34:24 I/NativeDevice: adb unroot on device SKBFXYU4133L
05-26 14:34:24 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, unroot] with timeout: 2m 0s
05-26 14:34:24 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYU4133L.
05-26 14:34:26 I/NativeDeviceStateMonitor: Waiting for device SKBFXYU4133L to be in NOT_AVAILABLE mode for '5s'; it is currently in ONLINE mode...
05-26 14:34:29 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:34:29 D/BackgroundDeviceAction: Device SKBFXYU4133L now online.
05-26 14:34:29 D/BackgroundDeviceAction: Starting logcat for SKBFXYU4133L.
05-26 14:34:31 W/NativeDevice: Did not detect device SKBFXYU4133L becoming unavailable after unroot
05-26 14:34:31 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:34:31 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:34:31 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:34:31 I/DeviceStateMonitor: Waiting 359939 ms for device SKBFXYU4133L package manager
05-26 14:34:32 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYU4133L external store
05-26 14:34:32 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
05-26 14:34:32 D/RunUtil: Running command [adb, -s, SKBFXYU4133L, shell, getprop, persist.sys.boot.reason.history] with timeout: 2m 0s
05-26 14:34:32 W/NativeDevice: There is no reboot history since 1685082867000
05-26 14:34:32 E/ModuleDefinition: Detected a soft-restart after module armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest
05-26 14:34:32 I/ConsoleReporter: [SKBFXYU4133L] Starting armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest with 1 test
05-26 14:34:32 I/ConsoleReporter: [1/1 armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest SKBFXYU4133L] PerInstance/GraphicsComposerHidlTest#SetActiveConfigPowerCycle/0_default fail: hardware/interfaces/graphics/composer/2.1/vts/functional/VtsHalGraphicsComposerV2_1TargetTest.cpp:437: Failure
Expected equality of these values:
  config
    Which is: 5
  mComposerClient->getActiveConfig(mPrimaryDisplay)
    Which is: 0
05-26 14:34:32 I/ConsoleReporter: [SKBFXYU4133L] armeabi-v7a VtsHalGraphicsComposerV2_1TargetTest completed in 22s. 0 passed, 1 failed, 0 not executed
05-26 14:34:32 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/results/2023.05.26_14.33.43/proto with prefix "tmp-proto" suffix ""
05-26 14:34:32 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
05-26 14:34:32 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:32 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:32 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_test_SKBFXYU4133L_14947460861811851415.txt. [size=5639357, elapsed=7ms]
05-26 14:34:32 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_test_SKBFXYU4133L_14947460861811851415.txt
05-26 14:34:32 D/LogFileSaver: Log data for device_logcat_test_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:32 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "device_logcat_test_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:32 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/device_logcat_test_SKBFXYU4133L_17656597870993642764.txt. [size=5639357, elapsed=4ms]
05-26 14:34:32 I/CommandInterrupter: Interrupt blocked
05-26 14:34:32 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
05-26 14:34:32 I/LogFileSaver: Saved gzip log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/executeShellCommandLog_SKBFXYU4133L_15143485574818224414.txt.gz. [size=10, elapsed=1ms]
05-26 14:34:32 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/executeShellCommandLog_SKBFXYU4133L_15143485574818224414.txt.gz
05-26 14:34:32 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "executeShellCommandLog_SKBFXYU4133L_" suffix ".txt.gz"
05-26 14:34:32 I/LogFileSaver: Saved gzip log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/executeShellCommandLog_SKBFXYU4133L_7285366010770792797.txt.gz. [size=10, elapsed=0ms]
05-26 14:34:32 D/TestInvocation: Checking that devices are online.
05-26 14:34:32 I/NativeDeviceStateMonitor: Device SKBFXYU4133L is already ONLINE
05-26 14:34:32 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYU4133L boot complete
05-26 14:34:32 I/DeviceStateMonitor: Waiting 359939 ms for device SKBFXYU4133L package manager
05-26 14:34:33 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYU4133L external store
05-26 14:34:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@3f018283' on device: 'SKBFXYU4133L'
05-26 14:34:33 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@3f018283' on device: 'SKBFXYU4133L' in 2 ms
05-26 14:34:33 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@4e29e56f' on device: 'SKBFXYU4133L'
05-26 14:34:33 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.VtsDeviceInfoCollector@4e29e56f' on device: 'SKBFXYU4133L' in 0 ms
05-26 14:34:33 D/RunUtil: Running command [id, -u, xts] with timeout: 1m 0s
05-26 14:34:33 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
05-26 14:34:33 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-26 14:34:33 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "host_adb_log_" suffix ".txt"
05-26 14:34:33 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/host_adb_log_9508240522441183534.txt. [size=878, elapsed=0ms]
05-26 14:34:33 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/host_adb_log_9508240522441183534.txt
05-26 14:34:33 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
05-26 14:34:33 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "host_adb_log_" suffix ".txt"
05-26 14:34:33 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/host_adb_log_6299344264831142525.txt. [size=878, elapsed=1ms]
05-26 14:34:33 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:33 D/FileUtil: Creating temp file at /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:33 I/LogFileSaver: Saved log file /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_teardown_SKBFXYU4133L_10365105164034020426.txt. [size=549, elapsed=0ms]
05-26 14:34:33 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYU4133L in /home/xts/tools/android-vts/tools/../../android-vts/logs/2023.05.26_14.33.43/inv_7364223261937093130/device_logcat_teardown_SKBFXYU4133L_10365105164034020426.txt
05-26 14:34:33 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYU4133L is already compressed, skipping compression
05-26 14:34:33 D/FileUtil: Creating temp file at /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434 with prefix "device_logcat_teardown_SKBFXYU4133L_" suffix ".txt"
05-26 14:34:33 I/LogFileSaver: Saved log file /tmp/9504181/vts/inv_9575912153238284697/inv_4248257641364970434/device_logcat_teardown_SKBFXYU4133L_4055641011200202553.txt. [size=549, elapsed=0ms]
05-26 14:34:33 I/TestInvocation: Done stopping logcat for SKBFXYU4133L
05-26 14:34:33 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYU4133L. May see duplicated content in log.
05-26 14:34:33 D/BackgroundDeviceAction: Waiting for device SKBFXYU4133L online before starting.
05-26 14:34:33 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYU4133L(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
05-26 14:34:33 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYU4133L shell to be responsive
05-26 14:34:33 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@500b1b1f=AVAILABLE}
05-26 14:34:33 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYU4133L
