12-20 08:46:49 D/Configuration: Resolve and download remote files from @Option
12-20 08:46:49 I/TestInvocation: Invocation was started with cmd: gts --include-filter "GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled" --include-filter "GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys" --include-filter "GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsSystem"
12-20 08:46:49 D/InvocationExecution: Skip linking external directory as FileProperty was set.
12-20 08:46:49 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
12-20 08:46:49 D/TestInvocation: Fetch build duration: 24 ms
12-20 08:46:49 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:49 D/GlobalTestFilter: Setting up global filters
12-20 08:46:49 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for 192.168.50.100:5555.
12-20 08:46:49 I/TestInvocation: Starting invocation for 'gts' with '[ DeviceBuildInfo{bid=9265057, serial=192.168.50.100:5555} on device '192.168.50.100:5555'] 
12-20 08:46:49 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9265057] with timeout: 10s
12-20 08:46:49 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9265057/gts] with timeout: 10s
12-20 08:46:49 D/FileUtil: Creating temp directory at /tmp/9265057/gts with prefix "inv_"
12-20 08:46:49 D/RunUtil: Running command [chmod, ug+rwx, /tmp/9265057/gts/inv_4613012397366354256] with timeout: 10s
12-20 08:46:49 D/FileSystemLogSaver: Using log file directory /tmp/9265057/gts/inv_4613012397366354256
12-20 08:46:49 D/FileUtil: Creating temp directory at /tmp/9265057/gts/inv_4613012397366354256 with prefix "inv_"
12-20 08:46:49 I/LogFileSaver: Using log file directory /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724
12-20 08:46:49 D/CompatibilityProtoResultReporter: Proto Results Directory: /home/xts/Downloads/android-gts/tools/./../../android-gts/results/2022.12.20_08.46.49/proto
12-20 08:46:49 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/results/2022.12.20_08.46.49/proto with prefix "tmp-proto" suffix ""
12-20 08:46:49 D/CertificationSuiteResultReporter: Initializing result directory
12-20 08:46:49 D/CertificationSuiteResultReporter: Results Directory: /home/xts/Downloads/android-gts/tools/./../../android-gts/results/2022.12.20_08.46.49
12-20 08:46:49 D/CertificationSuiteResultReporter: Created log dir /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49
12-20 08:46:49 D/FileUtil: Creating temp directory at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49 with prefix "inv_"
12-20 08:46:49 I/LogFileSaver: Using log file directory /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916
12-20 08:46:49 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
12-20 08:46:49 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "tradefed-expanded-config_" suffix ".xml"
12-20 08:46:49 I/LogFileSaver: Saved log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/tradefed-expanded-config_10870519711918625268.xml. [size=11055, elapsed=1ms]
12-20 08:46:49 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/tradefed-expanded-config_10870519711918625268.xml
12-20 08:46:49 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
12-20 08:46:49 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "tradefed-expanded-config_" suffix ".xml"
12-20 08:46:49 I/LogFileSaver: Saved log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/tradefed-expanded-config_11880946241012505114.xml. [size=11055, elapsed=1ms]
12-20 08:46:49 D/InvocationExecution: Starting device pre invocation setup for : '192.168.50.100:5555'
12-20 08:46:49 I/CommandInterrupter: Interrupt allowed
12-20 08:46:49 D/InvocationExecution: Starting setup for device: '192.168.50.100:5555'
12-20 08:46:49 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5703cae6' on device: '192.168.50.100:5555'
12-20 08:46:49 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5703cae6' on device: '192.168.50.100:5555' in 215 ms
12-20 08:46:49 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@29ac1574' on device: '192.168.50.100:5555'
12-20 08:46:49 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@29ac1574' on device: '192.168.50.100:5555' in 115 ms
12-20 08:46:49 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@37e0317a' on device: '192.168.50.100:5555'
12-20 08:46:49 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
12-20 08:46:49 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@37e0317a' on device: '192.168.50.100:5555' in 69 ms
12-20 08:46:49 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@283d0e1e' on device: '192.168.50.100:5555'
12-20 08:46:50 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:46:50 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.50.100:5555 boot complete
12-20 08:46:50 I/DeviceStateMonitor: Waiting 359943 ms for device 192.168.50.100:5555 package manager
12-20 08:46:50 I/NativeDeviceStateMonitor: Waiting 359881 ms for device 192.168.50.100:5555 external store
12-20 08:46:50 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.gts
12-20 08:46:50 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:50 D/GtsPreconditions.apk: Uploading GtsPreconditions.apk onto device '192.168.50.100:5555'
12-20 08:46:50 D/Device: Uploading file onto device '192.168.50.100:5555'
12-20 08:46:52 D/RunUtil: Running command [aapt, dump, badging, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsPreconditions.apk] with timeout: 1m 0s
12-20 08:46:52 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:46:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, appops, write-settings] with timeout: 2m 0s
12-20 08:46:52 D/ListInstrumentationParser: instrumentation:com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.gts)
12-20 08:46:52 D/ListInstrumentationParser: 
12-20 08:46:52 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
12-20 08:46:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:46:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:46:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:52 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage   -e newRunListenerMode true -e timeout_msec 300000 com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.50.100:5555
12-20 08:46:54 D/BackgroundDeviceAction: Waiting for device 192.168.50.100:5555 online before starting.
12-20 08:46:54 D/BackgroundDeviceAction: Device 192.168.50.100:5555 now online.
12-20 08:46:54 D/BackgroundDeviceAction: Starting logcat for 192.168.50.100:5555.
12-20 08:46:54 D/TestDevice: Uninstalling com.android.preconditions.gts with extra args 
12-20 08:46:55 D/ApkInstrumentationPreparer: Target preparation successful
12-20 08:46:55 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@283d0e1e' on device: '192.168.50.100:5555' in 5s
12-20 08:46:55 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@12e4d8fa' on device: '192.168.50.100:5555'
12-20 08:46:55 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:46:55 I/NativeDeviceStateMonitor: Waiting 359999 ms for device 192.168.50.100:5555 boot complete
12-20 08:46:55 I/DeviceStateMonitor: Waiting 359866 ms for device 192.168.50.100:5555 package manager
12-20 08:46:55 I/NativeDeviceStateMonitor: Waiting 359697 ms for device 192.168.50.100:5555 external store
12-20 08:46:56 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:56 D/WifiUtil.apk8810734115839708834.apk: Uploading WifiUtil.apk8810734115839708834.apk onto device '192.168.50.100:5555'
12-20 08:46:56 D/Device: Uploading file onto device '192.168.50.100:5555'
12-20 08:46:56 D/RunUtil: Running command [aapt, dump, badging, /tmp/WifiUtil.apk8810734115839708834.apk] with timeout: 1m 0s
12-20 08:46:56 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/WifiUtil.apk8810734115839708834.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:46:56 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, appops, write-settings] with timeout: 2m 0s
12-20 08:46:58 I/WifiCheck: Wifi is connected
12-20 08:46:58 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@12e4d8fa' on device: '192.168.50.100:5555' in 3s
12-20 08:46:58 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3f6951f9' on device: '192.168.50.100:5555'
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
12-20 08:46:58 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3f6951f9' on device: '192.168.50.100:5555' in 103 ms
12-20 08:46:58 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@5bc52b0c' on device: '192.168.50.100:5555'
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.model] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.device] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.id] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.serialno] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.security_patch] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abilist32] with timeout: 2m 0s
12-20 08:46:58 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.reference.fingerprint] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abi] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abilist64] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.name] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.tags] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.incremental] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.brand] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.base_os] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.board] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.type] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.release] with timeout: 2m 0s
12-20 08:46:59 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abi2] with timeout: 2m 0s
12-20 08:46:59 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:46:59 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.50.100:5555 boot complete
12-20 08:46:59 I/DeviceStateMonitor: Waiting 359963 ms for device 192.168.50.100:5555 package manager
12-20 08:46:59 I/NativeDeviceStateMonitor: Waiting 359902 ms for device 192.168.50.100:5555 external store
12-20 08:47:00 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
12-20 08:47:00 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:00 D/GtsDeviceInfo.apk: Uploading GtsDeviceInfo.apk onto device '192.168.50.100:5555'
12-20 08:47:00 D/Device: Uploading file onto device '192.168.50.100:5555'
12-20 08:47:02 D/RunUtil: Running command [aapt, dump, badging, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsDeviceInfo.apk] with timeout: 1m 0s
12-20 08:47:02 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:47:02 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, appops, write-settings] with timeout: 2m 0s
12-20 08:47:02 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
12-20 08:47:02 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
12-20 08:47:02 D/ListInstrumentationParser: 
12-20 08:47:02 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
12-20 08:47:02 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:02 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:02 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:02 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:02 I/RemoteAndroidTest: Running am instrument -w -r --no-isolated-storage   -e newRunListenerMode true -e timeout_msec 300000 com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.50.100:5555
12-20 08:47:27 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
12-20 08:47:28 D/ApkInstrumentationPreparer: Target preparation successful
12-20 08:47:28 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:28 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:28 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "MainlineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/MainlineDeviceInfo.deviceinfo.json_9416813115190498633.txt.gz. [size=10, elapsed=20ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/StorageDeviceInfo.deviceinfo.json_12109078886237826989.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/MemoryDeviceInfo.deviceinfo.json_11259139241497550012.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "device_manifest.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_manifest.xml_16403860013860015892.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_manifest.xml_16403860013860015892.txt.gz
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "device_manifest.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/device_manifest.xml_1970376111673576055.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "WidevineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/WidevineDeviceInfo.deviceinfo.json_13093243312155437471.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "VendorApexDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/VendorApexDeviceInfo.deviceinfo.json_12775560007715611815.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "BackupPermissionHoldersDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/BackupPermissionHoldersDeviceInfo.deviceinfo.json_10110942076404224839.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "TelephonyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/TelephonyDeviceInfo.deviceinfo.json_2251529603137670083.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/CpuDeviceInfo.deviceinfo.json_3996887977774407408.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/ConfigurationDeviceInfo.deviceinfo.json_6962762105211356127.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/LocaleDeviceInfo.deviceinfo.json_8431274431908330562.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/VintfDeviceInfo.deviceinfo.json_16926572658470193220.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/ClientIdDeviceInfo.deviceinfo.json_17157988697311488267.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/FeatureDeviceInfo.deviceinfo.json_5450171304092991647.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/UserDeviceInfo.deviceinfo.json_9888436947724359174.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "SecurityDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/SecurityDeviceInfo.deviceinfo.json_15242925551995503106.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/GenericDeviceInfo.deviceinfo.json_18128647049607405496.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_compatibility_matrix.xml_14098803345205977001.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_compatibility_matrix.xml_14098803345205977001.txt.gz
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/device_compatibility_matrix.xml_11071567840879840773.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "HapticsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/HapticsDeviceInfo.deviceinfo.json_12686812530741016844.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/ScreenDeviceInfo.deviceinfo.json_7106643466882450709.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/GraphicsDeviceInfo.deviceinfo.json_6920804687364978143.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/framework_compatibility_matrix.xml_3528054692589165262.txt.gz. [size=10, elapsed=6ms]
12-20 08:47:30 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/framework_compatibility_matrix.xml_3528054692589165262.txt.gz
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/framework_compatibility_matrix.xml_14415463286443355028.txt.gz. [size=10, elapsed=6ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "framework_manifest.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/framework_manifest.xml_4276259861879404599.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/framework_manifest.xml_4276259861879404599.txt.gz
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "framework_manifest.xml_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/framework_manifest.xml_12328716054930358934.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/PackageDeviceInfo.deviceinfo.json_13484817904173492187.txt.gz. [size=22579, elapsed=12ms]
12-20 08:47:30 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
12-20 08:47:30 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/MediaDeviceInfo.deviceinfo.json_582270794947986860.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:30 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@5bc52b0c' on device: '192.168.50.100:5555' in 31s
12-20 08:47:30 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@492f0e7e' on device: '192.168.50.100:5555'
12-20 08:47:30 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@492f0e7e' on device: '192.168.50.100:5555' in 1 ms
12-20 08:47:30 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@cc1ec45' on device: '192.168.50.100:5555'
12-20 08:47:30 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
12-20 08:47:30 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@cc1ec45' on device: '192.168.50.100:5555' in 147 ms
12-20 08:47:30 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@36b93208' on device: '192.168.50.100:5555'
12-20 08:47:30 W/BusinessLogicPreparer: Option config-filename isn't set. Using empty string instead.
12-20 08:47:30 W/BusinessLogicPreparer: Option version isn't set. Using 'null' instead.
12-20 08:47:30 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
12-20 08:47:31 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.boot.hardware.sku] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.boot.product.hardware.sku] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.boot.product.vendor.sku] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.fingerprint] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.device] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.release] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.brand] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.first_api_level] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.manufacturer] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.model] with timeout: 2m 0s
12-20 08:47:32 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.name] with timeout: 2m 0s
12-20 08:47:32 D/BusinessLogicPreparer: Built param string: "suite_version=10_r2&module_version=null&oem=Foxconn&features=android.hardware.type.television&features=android.software.leanback&properties=search_client_id%3Ams-android-foxconn2-tv&properties=playax_client_id%3Aplay-ad-ms-android-foxconn2-tv&properties=program_client_id%3Apg-android-foxconn2-tv&properties=base_client_id%3Aandroid-foxconn2-tv&properties=playtx_client_id%3Aplay-ms-android-foxconn2-tv&properties=market_client_id%3Aam-android-foxconn2-tv&properties=voicesearch_client_id%3Ams-android-foxconn2-tv&properties=client_id%3Aandroid-foxconn2-tv&properties=ro.boot.hardware.sku%3Anull&properties=ro.boot.product.hardware.sku%3Anull&properties=ro.boot.product.vendor.sku%3Anull&properties=ro.build.fingerprint%3ASKB%2FBFX_AT100%2FBFX-AT100%3A12%2FSTTC.220724.001%2Fk000c-534r203-20221219%3Auser%2Frelease-keys&properties=ro.product.device%3ABFX-AT100&properties=ro.build.version.release%3A12&properties=ro.build.version.sdk%3A31&properties=ro.product.brand%3ASKB&properties=ro.product.first_api_level%3A28&properties=ro.product.manufacturer%3AFoxconn&properties=ro.product.model%3ABFX-AT100&properties=ro.product.name%3ABFX_AT100&packages=com.google.android.gms&packages=com.android.vending&device_info=MemoryDeviceInfo%3Atotal_memory%3A3085017088"
12-20 08:47:32 I/BusinessLogicPreparer: Attempting to connect to business logic service...
12-20 08:47:34 D/BusinessLogicPreparer: Business Logic Service Response Code : 200
12-20 08:47:35 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:35 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:35 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:35 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:35 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:36 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:36 D/TradefedContentProvider.apk2498726374339059169.apk: Uploading TradefedContentProvider.apk2498726374339059169.apk onto device '192.168.50.100:5555'
12-20 08:47:36 D/Device: Uploading file onto device '192.168.50.100:5555'
12-20 08:47:36 D/RunUtil: Running command [aapt, dump, badging, /tmp/TradefedContentProvider.apk2498726374339059169.apk] with timeout: 1m 0s
12-20 08:47:36 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/TradefedContentProvider.apk2498726374339059169.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:47:36 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, appops, write-settings] with timeout: 2m 0s
12-20 08:47:36 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, cmd, appops, set, android.tradefed.contentprovider, android:legacy_storage, allow] with timeout: 2m 0s
12-20 08:47:36 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, cmd, appops, get, android.tradefed.contentprovider] with timeout: 2m 0s
12-20 08:47:36 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:37 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:37 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, content, write, --user, 0, --uri, content://android.tradefed.contentprovider/%252Fsdcard%252Fbl] with timeout: 2m 0s
12-20 08:47:38 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@36b93208' on device: '192.168.50.100:5555' in 8s
12-20 08:47:38 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@12782630' on device: '192.168.50.100:5555'
12-20 08:47:38 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.type] with timeout: 2m 0s
12-20 08:47:38 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@12782630' on device: '192.168.50.100:5555' in 55 ms
12-20 08:47:38 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@41264121' on device: '192.168.50.100:5555'
12-20 08:47:38 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.locale] with timeout: 2m 0s
12-20 08:47:39 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
12-20 08:47:39 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@41264121' on device: '192.168.50.100:5555' in 81 ms
12-20 08:47:39 D/InvocationExecution: Done with setup of device: '192.168.50.100:5555'
12-20 08:47:39 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, uname, -a] with timeout: 2m 0s
12-20 08:47:39 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.system.build.fingerprint] with timeout: 2m 0s
12-20 08:47:39 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.vendor.build.fingerprint] with timeout: 2m 0s
12-20 08:47:39 D/InvocationExecution: Total setup duration: 49s'
12-20 08:47:39 D/LogFileSaver: Log data for device_logcat_setup_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:39 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "device_logcat_setup_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:39 I/LogFileSaver: Saved log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_setup_192.168.50.100:5555_5849130855891476458.txt. [size=2919326, elapsed=4ms]
12-20 08:47:39 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_192.168.50.100:5555 in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_setup_192.168.50.100:5555_5849130855891476458.txt
12-20 08:47:39 D/LogFileSaver: Log data for device_logcat_setup_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:39 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "device_logcat_setup_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:39 I/LogFileSaver: Saved log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/device_logcat_setup_192.168.50.100:5555_8914513662452169209.txt. [size=2919326, elapsed=5ms]
12-20 08:47:39 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
12-20 08:47:39 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, cmd, device_state, print-states] with timeout: 2m 0s
12-20 08:47:39 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.product.cpu.abilist] with timeout: 2m 0s
12-20 08:47:39 D/ITestSuite: abi 'armeabi' is supported by device but not by this suite build ([arm64-v8a, armeabi-v7a, x86_64, x86]), tests will not run against it.
12-20 08:47:39 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "suite-exclude-filters2503067207901073749.txt_" suffix ".txt.gz"
12-20 08:47:39 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/suite-exclude-filters2503067207901073749.txt_449925793905128398.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:39 D/CertificationSuiteResultReporter: Saved logs for suite-exclude-filters2503067207901073749.txt in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/suite-exclude-filters2503067207901073749.txt_449925793905128398.txt.gz
12-20 08:47:39 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "suite-exclude-filters2503067207901073749.txt_" suffix ".txt.gz"
12-20 08:47:39 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/suite-exclude-filters2503067207901073749.txt_6803675854455384225.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:39 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[]
Module Args:[CtsPermission3TestCases:include-annotation:com.android.compatibility.common.util.CtsDownstreamingTest]
Includes: {armeabi-v7a GtsWebViewTestCases=[GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsSystem, GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled, GtsWebViewTestCases com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys]}
Excludes: See suite-exclude-filters2503067207901073749.txt
12-20 08:47:39 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
12-20 08:47:41 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
12-20 08:47:41 D/ITestSuite: [Total Unique Modules = 1]
12-20 08:47:41 D/BaseTestSuite: Filters for 'armeabi-v7a GtsWebViewTestCases': []
12-20 08:47:41 I/ITestSuite: Running system status checker before module execution: armeabi-v7a GtsWebViewTestCases
12-20 08:47:41 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.type] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:42 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:43 D/ModuleDefinition: Running module armeabi-v7a GtsWebViewTestCases
12-20 08:47:43 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
12-20 08:47:43 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:43 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:43 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:43 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.id] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.revision] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, gsm.sim.state] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.id] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.revision] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, gsm.sim.state] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, gsm.operator.alpha] with timeout: 2m 0s
12-20 08:47:44 D/RunUtil: Running command [aapt, dump, badging, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsWebViewTestCases.apk] with timeout: 1m 0s
12-20 08:47:44 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsWebViewTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:47:44 D/TestAppInstallSetup: Installing apk com.google.android.webview.gts with [./../../android-gts/testcases/GtsWebViewTestCases.apk] ...
12-20 08:47:44 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:44 D/GtsWebViewTestCases.apk: Uploading GtsWebViewTestCases.apk onto device '192.168.50.100:5555'
12-20 08:47:44 D/Device: Uploading file onto device '192.168.50.100:5555'
12-20 08:47:46 D/RunUtil: Running command [aapt, dump, badging, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsWebViewTestCases.apk] with timeout: 1m 0s
12-20 08:47:46 D/RunUtil: Running command [aapt, dump, xmltree, /home/xts/Downloads/android-gts/tools/./../../android-gts/testcases/GtsWebViewTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
12-20 08:47:46 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, appops, write-settings] with timeout: 2m 0s
12-20 08:47:46 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
12-20 08:47:46 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
12-20 08:47:46 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
12-20 08:47:46 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include_" suffix ".txt.gz"
12-20 08:47:46 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include_12264303384845314913.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:46 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include_12264303384845314913.txt.gz
12-20 08:47:46 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include_" suffix ".txt.gz"
12-20 08:47:46 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/filter-armeabi-v7a%20GtsWebViewTestCases8282854063658096010.include_14651045094481723131.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:47 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
12-20 08:47:47 D/ListInstrumentationParser: instrumentation:com.google.android.webview.gts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.webview.gts)
12-20 08:47:47 D/ListInstrumentationParser: 
12-20 08:47:47 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
12-20 08:47:47 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:47 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:47 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:47 D/InstrumentationTest: Collecting test info for com.google.android.webview.gts on device 192.168.50.100:5555
12-20 08:47:47 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log true -e timeout_msec 300000 com.google.android.webview.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.50.100:5555
12-20 08:47:48 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e newRunListenerMode true -e log false -e timeout_msec 300000 com.google.android.webview.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.50.100:5555
12-20 08:47:49 D/ModuleListener: ModuleListener.testRunStarted(com.google.android.webview.gts, 3, 0)
12-20 08:47:49 D/ModuleListener: ModuleListener.testStarted(com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled)
12-20 08:47:49 I/TestFailureListener: FailureListener.testFailed com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled false
12-20 08:47:49 I/ModuleListener: [1/3] com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled FAILURE: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsPreinstalled(WebViewTest.java:437)

12-20 08:47:49 D/ModuleListener: ModuleListener.testStarted(com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys)
12-20 08:47:49 I/TestFailureListener: FailureListener.testFailed com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys false
12-20 08:47:49 I/ModuleListener: [2/3] com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys FAILURE: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsSignedWithCorrectKeys(WebViewTest.java:617)

12-20 08:47:49 D/ModuleListener: ModuleListener.testStarted(com.google.android.webview.gts.WebViewTest#testWebViewIsSystem)
12-20 08:47:49 I/TestFailureListener: FailureListener.testFailed com.google.android.webview.gts.WebViewTest#testWebViewIsSystem false
12-20 08:47:49 I/ModuleListener: [3/3] com.google.android.webview.gts.WebViewTest#testWebViewIsSystem FAILURE: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsSystem(WebViewTest.java:448)

12-20 08:47:50 D/ModuleListener: ModuleListener.testRunEnded(940)
12-20 08:47:50 D/TestDevice: Uninstalling com.google.android.webview.gts with extra args 
12-20 08:47:51 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:47:51 I/NativeDeviceStateMonitor: Waiting 359999 ms for device 192.168.50.100:5555 boot complete
12-20 08:47:51 I/DeviceStateMonitor: Waiting 359856 ms for device 192.168.50.100:5555 package manager
12-20 08:47:51 I/NativeDeviceStateMonitor: Waiting 359698 ms for device 192.168.50.100:5555 external store
12-20 08:47:51 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:52 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, persist.sys.boot.reason.history] with timeout: 2m 0s
12-20 08:47:52 W/NativeDevice: There is no reboot history since 1671497266000
12-20 08:47:52 E/ModuleDefinition: Detected a soft-restart after module armeabi-v7a GtsWebViewTestCases
12-20 08:47:52 I/ConsoleReporter: [192.168.50.100:5555] Starting armeabi-v7a GtsWebViewTestCases with 3 tests
12-20 08:47:52 I/ConsoleReporter: [1/3 armeabi-v7a GtsWebViewTestCases 192.168.50.100:5555] com.google.android.webview.gts.WebViewTest#testWebViewIsPreinstalled fail: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsPreinstalled(WebViewTest.java:437)

12-20 08:47:52 I/ConsoleReporter: [2/3 armeabi-v7a GtsWebViewTestCases 192.168.50.100:5555] com.google.android.webview.gts.WebViewTest#testWebViewIsSignedWithCorrectKeys fail: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsSignedWithCorrectKeys(WebViewTest.java:617)

12-20 08:47:52 I/ConsoleReporter: [3/3 armeabi-v7a GtsWebViewTestCases 192.168.50.100:5555] com.google.android.webview.gts.WebViewTest#testWebViewIsSystem fail: java.lang.AssertionError: A single WebViewGoogle APK must be installed expected:<1> but was:<0>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at com.google.android.webview.gts.WebViewTest.testWebViewIsSystem(WebViewTest.java:448)

12-20 08:47:52 I/ConsoleReporter: [192.168.50.100:5555] armeabi-v7a GtsWebViewTestCases completed in 5s. 0 passed, 3 failed, 0 not executed
12-20 08:47:52 I/ITestSuite: Running system status checker after module execution: armeabi-v7a GtsWebViewTestCases
12-20 08:47:52 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:47:52 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.50.100:5555 boot complete
12-20 08:47:52 I/DeviceStateMonitor: Waiting 359933 ms for device 192.168.50.100:5555 package manager
12-20 08:47:52 I/NativeDeviceStateMonitor: Waiting 359866 ms for device 192.168.50.100:5555 external store
12-20 08:47:54 I/MonitoringUtils: Wifi Connectivity: passed check.
12-20 08:47:54 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

12-20 08:47:54 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:54 D/SystemServerFileDescriptorChecker: Skipping system_server fd check on user builds.
12-20 08:47:54 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/results/2022.12.20_08.46.49/proto with prefix "tmp-proto" suffix ""
12-20 08:47:54 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
12-20 08:47:54 D/LogFileSaver: Log data for device_logcat_test_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:54 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "device_logcat_test_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:54 I/LogFileSaver: Saved log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_test_192.168.50.100:5555_6698689427359567611.txt. [size=210646, elapsed=1ms]
12-20 08:47:54 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_192.168.50.100:5555 in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_test_192.168.50.100:5555_6698689427359567611.txt
12-20 08:47:54 D/LogFileSaver: Log data for device_logcat_test_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:54 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "device_logcat_test_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:54 I/LogFileSaver: Saved log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/device_logcat_test_192.168.50.100:5555_9180052307177377921.txt. [size=210646, elapsed=1ms]
12-20 08:47:54 I/CommandInterrupter: Interrupt blocked
12-20 08:47:54 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "executeShellCommandLog_192.168.50.100:5555_" suffix ".txt.gz"
12-20 08:47:54 I/LogFileSaver: Saved gzip log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/executeShellCommandLog_192.168.50.100:5555_5254822879424577985.txt.gz. [size=10, elapsed=0ms]
12-20 08:47:54 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_192.168.50.100:5555 in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/executeShellCommandLog_192.168.50.100:5555_5254822879424577985.txt.gz
12-20 08:47:54 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "executeShellCommandLog_192.168.50.100:5555_" suffix ".txt.gz"
12-20 08:47:54 I/LogFileSaver: Saved gzip log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/executeShellCommandLog_192.168.50.100:5555_6590232565231155737.txt.gz. [size=10, elapsed=1ms]
12-20 08:47:54 D/TestInvocation: Checking that devices are online.
12-20 08:47:54 I/NativeDeviceStateMonitor: Device 192.168.50.100:5555 is already ONLINE
12-20 08:47:54 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.50.100:5555 boot complete
12-20 08:47:54 I/DeviceStateMonitor: Waiting 359939 ms for device 192.168.50.100:5555 package manager
12-20 08:47:54 I/NativeDeviceStateMonitor: Waiting 359847 ms for device 192.168.50.100:5555 external store
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@41264121' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@41264121' on device: '192.168.50.100:5555' in 1 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@12782630' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@12782630' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@36b93208' on device: '192.168.50.100:5555'
12-20 08:47:55 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:55 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@36b93208' on device: '192.168.50.100:5555' in 243 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@cc1ec45' on device: '192.168.50.100:5555'
12-20 08:47:55 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@cc1ec45' on device: '192.168.50.100:5555' in 100 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@492f0e7e' on device: '192.168.50.100:5555'
12-20 08:47:55 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.codename] with timeout: 2m 0s
12-20 08:47:55 D/RunUtil: Running command [adb, -s, 192.168.50.100:5555, shell, getprop, ro.build.version.sdk] with timeout: 2m 0s
12-20 08:47:55 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
12-20 08:47:55 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@492f0e7e' on device: '192.168.50.100:5555' in 272 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@5bc52b0c' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@5bc52b0c' on device: '192.168.50.100:5555' in 2 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3f6951f9' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@3f6951f9' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@12e4d8fa' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@12e4d8fa' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@283d0e1e' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@283d0e1e' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@37e0317a' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@37e0317a' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@29ac1574' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@29ac1574' on device: '192.168.50.100:5555' in 1 ms
12-20 08:47:55 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5703cae6' on device: '192.168.50.100:5555'
12-20 08:47:55 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5703cae6' on device: '192.168.50.100:5555' in 0 ms
12-20 08:47:55 D/TestDevice: Uninstalling android.tradefed.contentprovider with extra args 
12-20 08:47:56 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
12-20 08:47:57 D/WifiHelper: Successfully clean up WifiHelper.
12-20 08:47:57 D/RunUtil: Running command [id, -u, xts] with timeout: 1m 0s
12-20 08:47:57 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
12-20 08:47:57 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
12-20 08:47:57 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "host_adb_log_" suffix ".txt"
12-20 08:47:57 I/LogFileSaver: Saved log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/host_adb_log_13510025136954067617.txt. [size=2428, elapsed=1ms]
12-20 08:47:57 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/host_adb_log_13510025136954067617.txt
12-20 08:47:57 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
12-20 08:47:57 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "host_adb_log_" suffix ".txt"
12-20 08:47:57 I/LogFileSaver: Saved log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/host_adb_log_8358069425045216350.txt. [size=2428, elapsed=1ms]
12-20 08:47:57 D/LogFileSaver: Log data for device_logcat_teardown_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:57 D/FileUtil: Creating temp file at /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916 with prefix "device_logcat_teardown_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:57 I/LogFileSaver: Saved log file /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_teardown_192.168.50.100:5555_2456224253885522523.txt. [size=51865, elapsed=0ms]
12-20 08:47:57 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_192.168.50.100:5555 in /home/xts/Downloads/android-gts/tools/./../../android-gts/logs/2022.12.20_08.46.49/inv_11868782598449720916/device_logcat_teardown_192.168.50.100:5555_2456224253885522523.txt
12-20 08:47:57 D/LogFileSaver: Log data for device_logcat_teardown_192.168.50.100:5555 is already compressed, skipping compression
12-20 08:47:57 D/FileUtil: Creating temp file at /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724 with prefix "device_logcat_teardown_192.168.50.100:5555_" suffix ".txt"
12-20 08:47:57 I/LogFileSaver: Saved log file /tmp/9265057/gts/inv_4613012397366354256/inv_13935842388857111724/device_logcat_teardown_192.168.50.100:5555_6397773526672860057.txt. [size=51865, elapsed=1ms]
12-20 08:47:57 I/TestInvocation: Done stopping logcat for 192.168.50.100:5555
12-20 08:47:57 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on 192.168.50.100:5555. May see duplicated content in log.
12-20 08:47:57 D/BackgroundDeviceAction: Waiting for device 192.168.50.100:5555 online before starting.
12-20 08:47:57 D/CommandScheduler: TestDeviceState for releasing '192.168.50.100:5555(class com.android.tradefed.device.RemoteAndroidDevice)' is 'ONLINE'
12-20 08:47:57 I/NativeDeviceStateMonitor: Waiting 30000 ms for device 192.168.50.100:5555 shell to be responsive
12-20 08:47:57 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.RemoteAndroidDevice@3f918d13=AVAILABLE}
12-20 08:47:57 W/NativeDevice: Attempting to stop logcat when not capturing for 192.168.50.100:5555
