11-20 19:26:31 D/Configuration: Resolve and download remote files from @Option
11-20 19:26:31 I/TestInvocation: Invocation was started with cmd: gts -m GtsMediaRouterTestCases -t com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi -s SKBFXYZO0DKW
11-20 19:26:31 D/InvocationExecution: Skip linking external directory as FileProperty was set.
11-20 19:26:31 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
11-20 19:26:31 D/TestInvocation: Fetch build duration: 19 ms
11-20 19:26:31 D/ArtifactsAnalyzer: Build analysis report: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=false, imageDigests={}]
11-20 19:26:31 D/ArtifactsAnalyzer: Analysis report after test analysis: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=true, imageDigests={}]
11-20 19:26:31 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.sdk] with timeout: 45s
11-20 19:26:31 D/GlobalTestFilter: Setting up global filters
11-20 19:26:31 D/InvocationExecution: IShardHelper selected: com.android.tradefed.invoker.shard.StrictShardHelper@6b3c05eb
11-20 19:26:31 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for SKBFXYZO0DKW.
11-20 19:26:31 I/TestInvocation: Starting invocation for 'gts' with '[ DeviceBuildInfo{bid=12590200, serial=SKBFXYZO0DKW} on device 'SKBFXYZO0DKW'] 
11-20 19:26:31 D/RunUtil: Running command [chmod, ug+rwx, /tmp/12590200] with timeout: 10s
11-20 19:26:31 D/RunUtil: Running command [chmod, ug+rwx, /tmp/12590200/gts] with timeout: 10s
11-20 19:26:31 D/FileUtil: Creating temp directory at /tmp/12590200/gts with prefix "inv_"
11-20 19:26:31 D/RunUtil: Running command [chmod, ug+rwx, /tmp/12590200/gts/inv_15755369287843088386] with timeout: 10s
11-20 19:26:31 D/FileSystemLogSaver: Using log file directory /tmp/12590200/gts/inv_15755369287843088386
11-20 19:26:31 D/FileUtil: Creating temp directory at /tmp/12590200/gts/inv_15755369287843088386 with prefix "inv_"
11-20 19:26:31 I/LogFileSaver: Using log file directory /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223
11-20 19:26:31 D/CompatibilityProtoResultReporter: Proto Results Directory: /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/results/2024.11.20_19.26.31/proto
11-20 19:26:31 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/results/2024.11.20_19.26.31/proto with prefix "tmp-proto" suffix ""
11-20 19:26:31 D/CompatibilityProtoResultReporter: Proto Results Directory: /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/results/2024.11.20_19.26.31/proto
11-20 19:26:31 D/CertificationSuiteResultReporter: Initializing result directory
11-20 19:26:31 D/CertificationSuiteResultReporter: Results Directory: /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/results/2024.11.20_19.26.31
11-20 19:26:31 D/CertificationSuiteResultReporter: Created log dir /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31
11-20 19:26:31 D/FileUtil: Creating temp directory at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31 with prefix "inv_"
11-20 19:26:31 I/LogFileSaver: Using log file directory /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980
11-20 19:26:31 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
11-20 19:26:31 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "tradefed-expanded-config_" suffix ".xml"
11-20 19:26:31 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/tradefed-expanded-config_4258272225707061137.xml. [size=17731, elapsed=1ms]
11-20 19:26:31 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/tradefed-expanded-config_4258272225707061137.xml
11-20 19:26:31 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
11-20 19:26:31 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "tradefed-expanded-config_" suffix ".xml"
11-20 19:26:31 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/tradefed-expanded-config_3765533967609034907.xml. [size=17731, elapsed=1ms]
11-20 19:26:31 D/InvocationExecution: Starting device pre invocation setup for : 'SKBFXYZO0DKW'
11-20 19:26:31 D/DefaultConnection: Instance type for connection: GCE
11-20 19:26:31 D/DefaultConnection: Instance type for connection: GCE
11-20 19:26:31 D/NativeDevice: Using connection: com.android.tradefed.device.connection.DefaultConnection@3976306b (SKBFXYZO0DKW)
11-20 19:26:31 I/CommandInterrupter: Interrupt allowed
11-20 19:26:31 D/InvocationExecution: Starting setup for device: 'SKBFXYZO0DKW'
11-20 19:26:31 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@14aba4f5' on device: 'SKBFXYZO0DKW'
11-20 19:26:31 I/UrlReplacement: UrlReplacement file [/home/dev2-google03/android-gts/android-gts-12-R2-12590200/android-gts/tools/UrlReplacement.xml] does not exist
11-20 19:26:32 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@14aba4f5' on device: 'SKBFXYZO0DKW' in 483 ms
11-20 19:26:32 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@4f0e9b06' on device: 'SKBFXYZO0DKW'
11-20 19:26:32 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@4f0e9b06' on device: 'SKBFXYZO0DKW' in 125 ms
11-20 19:26:32 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@2013c587' on device: 'SKBFXYZO0DKW'
11-20 19:26:32 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
11-20 19:26:32 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@2013c587' on device: 'SKBFXYZO0DKW' in 93 ms
11-20 19:26:32 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@6261f510' on device: 'SKBFXYZO0DKW'
11-20 19:26:32 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:26:32 I/NativeDeviceStateMonitor: Waiting 359998 ms for device SKBFXYZO0DKW boot complete
11-20 19:26:32 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYZO0DKW package manager
11-20 19:26:32 I/NativeDeviceStateMonitor: Waiting 359896 ms for device SKBFXYZO0DKW external store
11-20 19:26:33 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.gts
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:33 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.codename] with timeout: 45s
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:33 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:33 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:35 D/RunUtil: Running command [aapt, dump, badging, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsPreconditions.apk] with timeout: 1m 0s
11-20 19:26:35 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:26:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
11-20 19:26:35 D/ListInstrumentationParser: instrumentation:com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.gts)
11-20 19:26:35 D/ListInstrumentationParser: 
11-20 19:26:35 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-20 19:26:35 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:35 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:35 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:35 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-20 19:26:35 I/RemoteAndroidTestRunner: 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-SKBFXYZO0DKW
11-20 19:26:36 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0DKW online before starting.
11-20 19:26:36 D/BackgroundDeviceAction: Device SKBFXYZO0DKW now online.
11-20 19:26:36 D/BackgroundDeviceAction: Starting logcat for SKBFXYZO0DKW.
11-20 19:26:38 D/TestDevice: Uninstalling com.android.preconditions.gts with extra args 
11-20 19:26:38 D/ApkInstrumentationPreparer: Target preparation successful
11-20 19:26:38 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@6261f510' on device: 'SKBFXYZO0DKW' in 6s
11-20 19:26:38 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@697fca27' on device: 'SKBFXYZO0DKW'
11-20 19:26:39 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:26:39 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
11-20 19:26:39 I/DeviceStateMonitor: Waiting 359837 ms for device SKBFXYZO0DKW package manager
11-20 19:26:39 I/NativeDeviceStateMonitor: Waiting 359694 ms for device SKBFXYZO0DKW external store
11-20 19:26:39 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:39 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:39 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:40 D/RunUtil: Running command [aapt, dump, badging, /tmp/WifiUtil.apk14916518354863408376.apk] with timeout: 1m 0s
11-20 19:26:40 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/WifiUtil.apk14916518354863408376.apk, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:26:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
11-20 19:26:42 I/WifiCheck: Wifi is connected
11-20 19:26:42 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@697fca27' on device: 'SKBFXYZO0DKW' in 3s
11-20 19:26:42 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5a5083e2' on device: 'SKBFXYZO0DKW'
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
11-20 19:26:42 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5a5083e2' on device: 'SKBFXYZO0DKW' in 98 ms
11-20 19:26:42 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@9b7f801' on device: 'SKBFXYZO0DKW'
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.model] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.device] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.id] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 45s
11-20 19:26:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.manufacturer] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.serialno] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.security_patch] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist32] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.reference.fingerprint] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abi] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist64] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.name] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.tags] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.incremental] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.brand] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.fingerprint] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.vendor.build.fingerprint] with timeout: 45s
11-20 19:26:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.base_os] with timeout: 45s
11-20 19:26:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abilist] with timeout: 45s
11-20 19:26:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.board] with timeout: 45s
11-20 19:26:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.type] with timeout: 45s
11-20 19:26:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.release] with timeout: 45s
11-20 19:26:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.cpu.abi2] with timeout: 45s
11-20 19:26:43 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:26:43 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
11-20 19:26:43 I/DeviceStateMonitor: Waiting 359962 ms for device SKBFXYZO0DKW package manager
11-20 19:26:43 I/NativeDeviceStateMonitor: Waiting 359901 ms for device SKBFXYZO0DKW external store
11-20 19:26:43 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:43 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:46 D/RunUtil: Running command [aapt, dump, badging, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsDeviceInfo.apk] with timeout: 1m 0s
11-20 19:26:46 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:26:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
11-20 19:26:46 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
11-20 19:26:46 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
11-20 19:26:46 D/ListInstrumentationParser: 
11-20 19:26:46 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-20 19:26:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:46 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:26:46 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:26:46 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-20 19:26:46 I/RemoteAndroidTestRunner: 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-SKBFXYZO0DKW
11-20 19:27:15 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
11-20 19:27:15 D/ApkInstrumentationPreparer: Target preparation successful
11-20 19:27:15 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:15 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
11-20 19:27:15 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, ls, '/storage/emulated/0/device-info-files/'] with timeout: 2m 0s
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/UserDeviceInfo.deviceinfo.json_10286387283753538072.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_compatibility_matrix.xml_18076419218252896201.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_compatibility_matrix.xml_18076419218252896201.txt.gz
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/device_compatibility_matrix.xml_10295521712673268680.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/ConfigurationDeviceInfo.deviceinfo.json_4295977616162866113.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "BackupPermissionHoldersDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/BackupPermissionHoldersDeviceInfo.deviceinfo.json_17067826613781607233.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/MemoryDeviceInfo.deviceinfo.json_18388786540502727742.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/CpuDeviceInfo.deviceinfo.json_2906948906708085120.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/MediaDeviceInfo.deviceinfo.json_16166709513411039312.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/VintfDeviceInfo.deviceinfo.json_1801441528824520362.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "WidevineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/WidevineDeviceInfo.deviceinfo.json_1697637352348663370.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "MainlineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/MainlineDeviceInfo.deviceinfo.json_7491870289686321865.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/GraphicsDeviceInfo.deviceinfo.json_4663620817605558839.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "KeystoreAttestationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/KeystoreAttestationDeviceInfo.deviceinfo.json_17116486368236906560.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "VendorApexDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/VendorApexDeviceInfo.deviceinfo.json_16537408608590276669.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/framework_compatibility_matrix.xml_14803002906725552829.txt.gz. [size=10, elapsed=2ms]
11-20 19:27:16 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/framework_compatibility_matrix.xml_14803002906725552829.txt.gz
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/framework_compatibility_matrix.xml_7526450496796639116.txt.gz. [size=10, elapsed=2ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "HapticsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/HapticsDeviceInfo.deviceinfo.json_4193121613254641369.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "TelephonyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/TelephonyDeviceInfo.deviceinfo.json_8383174060694938272.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/GenericDeviceInfo.deviceinfo.json_1295728827642912926.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/FeatureDeviceInfo.deviceinfo.json_15287982136875464257.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/PackageDeviceInfo.deviceinfo.json_7304635569185521869.txt.gz. [size=22332, elapsed=5ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/LocaleDeviceInfo.deviceinfo.json_5779388132477069012.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/StorageDeviceInfo.deviceinfo.json_2393067048664354339.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/ScreenDeviceInfo.deviceinfo.json_11775550116979818475.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "PermissionDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/PermissionDeviceInfo.deviceinfo.json_10917886265327570106.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "framework_manifest.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/framework_manifest.xml_5657079950544828202.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/framework_manifest.xml_5657079950544828202.txt.gz
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "framework_manifest.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/framework_manifest.xml_13633712933162716046.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "SecurityDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/SecurityDeviceInfo.deviceinfo.json_10505259593280837813.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "device_manifest.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_manifest.xml_14131875152745695400.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_manifest.xml_14131875152745695400.txt.gz
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "device_manifest.xml_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/device_manifest.xml_13461048007669122152.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:16 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
11-20 19:27:16 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/ClientIdDeviceInfo.deviceinfo.json_7151081848279011235.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:16 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@9b7f801' on device: 'SKBFXYZO0DKW' in 34s
11-20 19:27:16 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@75501899' on device: 'SKBFXYZO0DKW'
11-20 19:27:16 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@75501899' on device: 'SKBFXYZO0DKW' in 2 ms
11-20 19:27:16 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7315ef3f' on device: 'SKBFXYZO0DKW'
11-20 19:27:16 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
11-20 19:27:16 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7315ef3f' on device: 'SKBFXYZO0DKW' in 92 ms
11-20 19:27:16 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7842c296' on device: 'SKBFXYZO0DKW'
11-20 19:27:16 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, verifier_engprod, 1] with timeout: 2m 0s
11-20 19:27:17 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7842c296' on device: 'SKBFXYZO0DKW' in 66 ms
11-20 19:27:17 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@33d7335f' on device: 'SKBFXYZO0DKW'
11-20 19:27:17 W/BusinessLogicPreparer: Option config-filename isn't set. Using empty string instead.
11-20 19:27:17 W/BusinessLogicPreparer: Option version isn't set. Using 'null' instead.
11-20 19:27:17 D/BusinessLogicPreparer: original version: 12_r2, extracted version: 12_r2
11-20 19:27:17 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
11-20 19:27:17 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
11-20 19:27:17 I/DynamicConfigFileReader: config files: {gts=/tmp/gts9493134756449932676.dynamic}
11-20 19:27:18 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
11-20 19:27:18 I/DynamicConfigFileReader: config files: {gts=/tmp/gts9493134756449932676.dynamic}
11-20 19:27:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.hardware.sku] with timeout: 45s
11-20 19:27:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.product.hardware.sku] with timeout: 45s
11-20 19:27:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.boot.product.vendor.sku] with timeout: 45s
11-20 19:27:18 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.543.20-20241115:user/release-keys from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.product.device=BFX-AT100 from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.build.version.release=12 from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.product.brand=SKB from cache.
11-20 19:27:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.first_api_level] with timeout: 45s
11-20 19:27:18 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.product.model=BFX-AT100 from cache.
11-20 19:27:18 D/NativeDevice: Using property ro.product.name=BFX_AT100 from cache.
11-20 19:27:18 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
11-20 19:27:18 I/DynamicConfigFileReader: config files: {gts=/tmp/gts9493134756449932676.dynamic}
11-20 19:27:18 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
11-20 19:27:18 I/DynamicConfigFileReader: config files: {gts=/tmp/gts9493134756449932676.dynamic}
11-20 19:27:18 D/BusinessLogicPreparer: Built param string: "suite_version=12_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%2F15.543.20-20241115%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%3A3083894784"
11-20 19:27:18 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
11-20 19:27:18 I/BusinessLogicPreparer: Attempting to connect to business logic service...
11-20 19:27:18 D/BusinessLogicPreparer: Unable to fetch the service key because neither environment variable APE_API_KEY is set nor the key file is dynamically downloaded.
11-20 19:27:19 D/BusinessLogicPreparer: Business Logic Service Response Code : 200
11-20 19:27:19 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:19 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:20 D/RunUtil: Running command [aapt, dump, badging, /tmp/TradefedContentProvider.apk9475332982310949472.apk] with timeout: 1m 0s
11-20 19:27:20 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/TradefedContentProvider.apk9475332982310949472.apk, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:27:20 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, appops, set, android.tradefed.contentprovider, android:legacy_storage, allow] with timeout: 2m 0s
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, appops, get, android.tradefed.contentprovider] with timeout: 2m 0s
11-20 19:27:21 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@33d7335f' on device: 'SKBFXYZO0DKW' in 4s
11-20 19:27:21 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@289d4293' on device: 'SKBFXYZO0DKW'
11-20 19:27:21 D/NativeDevice: Using property ro.build.type=user from cache.
11-20 19:27:21 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@289d4293' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:21 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@35a5acf6' on device: 'SKBFXYZO0DKW'
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.product.locale] with timeout: 45s
11-20 19:27:21 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
11-20 19:27:21 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@35a5acf6' on device: 'SKBFXYZO0DKW' in 44 ms
11-20 19:27:21 D/InvocationExecution: Done with setup of device: 'SKBFXYZO0DKW'
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, uname, -a] with timeout: 2m 0s
11-20 19:27:21 I/InvocationExecution: Device SKBFXYZO0DKW kernel information: 'Linux localhost 4.9.269-abk000c-543r20-20241115 #1 SMP PREEMPT Fri Nov 15 17:19:23 KST 2024 armv8l'
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.system.build.fingerprint] with timeout: 45s
11-20 19:27:21 I/InvocationExecution: Device SKBFXYZO0DKW system image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.543.20-20241115:user/release-keys'
11-20 19:27:21 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.543.20-20241115:user/release-keys from cache.
11-20 19:27:21 I/InvocationExecution: Device SKBFXYZO0DKW vendor image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.543.20-20241115:user/release-keys'
11-20 19:27:21 D/InvocationExecution: Total setup duration: 49s'
11-20 19:27:21 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:21 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "device_logcat_setup_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:21 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_setup_SKBFXYZO0DKW_11526197547609018525.txt. [size=846486, elapsed=3ms]
11-20 19:27:21 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_SKBFXYZO0DKW in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_setup_SKBFXYZO0DKW_11526197547609018525.txt
11-20 19:27:21 D/LogFileSaver: Log data for device_logcat_setup_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:21 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "device_logcat_setup_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:21 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/device_logcat_setup_SKBFXYZO0DKW_17692952170450322125.txt. [size=846486, elapsed=3ms]
11-20 19:27:21 D/TestDevice: No snapuserd notification in progress for BLOCK_BEFORE_TEST
11-20 19:27:21 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
11-20 19:27:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, cmd, device_state, print-states] with timeout: 2m 0s
11-20 19:27:21 D/NativeDevice: Using property ro.product.cpu.abilist=armeabi-v7a,armeabi from cache.
11-20 19:27:21 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.
11-20 19:27:21 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[com.android.tradefed.testtype.AndroidJUnitTest:exclude-annotation:com.android.interactive.annotations.Interactive, com.android.tradefed.testtype.HostTest:exclude-annotation:com.android.interactive.annotations.Interactive, com.android.compatibility.common.tradefed.testtype.JarHostTest:exclude-annotation:com.android.interactive.annotations.Interactive, com.android.tradefed.testtype.AndroidJUnitTest:exclude-annotation:com.android.xts.root.annotations.RequireAdbRoot, com.android.tradefed.testtype.HostTest:exclude-annotation:com.android.xts.root.annotations.RequireAdbRoot, com.android.compatibility.common.tradefed.testtype.JarHostTest:exclude-annotation:com.android.xts.root.annotations.RequireAdbRoot]
Module Args:[]
Includes: {armeabi-v7a GtsMediaRouterTestCases=[GtsMediaRouterTestCases com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi], armeabi-v7a GtsMediaRouterTestCases[instant]=[GtsMediaRouterTestCases[instant] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi], armeabi-v7a GtsMediaRouterTestCases[run-on-secondary-user]=[GtsMediaRouterTestCases[run-on-secondary-user] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi], armeabi-v7a GtsMediaRouterTestCases[run-on-work-profile]=[GtsMediaRouterTestCases[run-on-work-profile] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi], armeabi-v7a GtsMediaRouterTestCases[run-on-private-profile]=[GtsMediaRouterTestCases[run-on-private-profile] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi], armeabi-v7a GtsMediaRouterTestCases[run-on-clone-profile]=[GtsMediaRouterTestCases[run-on-clone-profile] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi]}

11-20 19:27:21 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
11-20 19:27:21 D/BaseTestSuite: Loading tests from [/tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases]
11-20 19:27:22 D/ITestSuite: No remote build info, skipping stageTestArtifacts
11-20 19:27:22 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
11-20 19:27:22 D/ITestSuite: [Total Unique Modules = 1]
11-20 19:27:22 I/ITestSuite: SKBFXYZO0DKW running 1 modules: [armeabi-v7a GtsMediaRouterTestCases]
11-20 19:27:22 D/TradefedFeatureClient: invoking feature 'skipFeature'
11-20 19:27:22 D/TradefedFeatureClient: Using reference id from metrics.
11-20 19:27:22 D/TradefedFeatureClient: name: "skipFeature"
reference_id: "5e8c53c8-a466-462f-9f84-4ea49b034eb4"

11-20 19:27:23 D/TradefedFeatureServer: Executing com.android.tradefed.result.skipped.SkipFeature@5d874236 with [name: "skipFeature"
reference_id: "5e8c53c8-a466-462f-9f84-4ea49b034eb4"
]
11-20 19:27:23 D/TradefedFeatureClient: Feature name: skipFeature. response: multi_part_response {
  response_part {
    key: "delimiter"
    value: "\\+,"
  }
  response_part {
    key: "presubmit"
    value: "false"
  }
  response_part {
    key: "skipModules"
  }
  response_part {
    key: "imageDigests"
  }
}

11-20 19:27:23 D/BaseTestSuite: Filters for 'armeabi-v7a GtsMediaRouterTestCases': []
11-20 19:27:23 D/FileUtil: Creating temp file at /tmp/tf-workfolder4308156167963383723 with prefix "GtsMediaRouterTestCases" suffix ".xml"
11-20 19:27:23 D/LogFileSaver: Log data for module-configuration is already compressed, skipping compression
11-20 19:27:23 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "module-configuration_" suffix ".xml"
11-20 19:27:23 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/module-configuration_8475225964405750884.xml. [size=1151, elapsed=1ms]
11-20 19:27:23 D/CertificationSuiteResultReporter: Saved logs for module-configuration in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/module-configuration_8475225964405750884.xml
11-20 19:27:23 D/LogFileSaver: Log data for module-configuration is already compressed, skipping compression
11-20 19:27:23 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "module-configuration_" suffix ".xml"
11-20 19:27:23 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/module-configuration_18340977603162767453.xml. [size=1151, elapsed=0ms]
11-20 19:27:23 I/ITestSuite: Running system status checker before module execution: armeabi-v7a GtsMediaRouterTestCases
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.type=user from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, locksettings, get-disabled] with timeout: 2m 0s
11-20 19:27:24 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, locksettings, get-disabled] with timeout: 2m 0s
11-20 19:27:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, input, keyevent, KEYCODE_MENU] with timeout: 2m 0s
11-20 19:27:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, input, keyevent, KEYCODE_HOME] with timeout: 2m 0s
11-20 19:27:26 I/DeviceBaselineChecker: Enable device baselines disable_os_auto_update,hide_error_dialogs,keep_screen_on,disable_usb_app_verification,clear_lock_screen in 3104 millis.
11-20 19:27:26 D/ModuleDefinition: Running module armeabi-v7a GtsMediaRouterTestCases
11-20 19:27:26 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, pm, list, features] with timeout: 2m 0s
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:26 W/SearchArtifactUtil: we have a module name: GtsMediaRouterTestCases but no directory found in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases.
11-20 19:27:26 D/RunUtil: Running command [aapt2, dump, badging, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsMediaRouterTestCases.apk] with timeout: 1m 0s
11-20 19:27:26 D/RunUtil: Running command [aapt2, dump, xmltree, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsMediaRouterTestCases.apk, --file, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:27:26 D/TestAppInstallSetup: Installing apk com.google.android.gts.media.router with [/tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsMediaRouterTestCases.apk] ...
11-20 19:27:26 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:28 D/RunUtil: Running command [aapt, dump, badging, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsMediaRouterTestCases.apk] with timeout: 1m 0s
11-20 19:27:28 D/RunUtil: Running command [aapt, dump, xmltree, /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/testcases/GtsMediaRouterTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
11-20 19:27:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, appops, write-settings] with timeout: 2m 0s
11-20 19:27:28 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:28 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:28 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:28 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:28 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
11-20 19:27:28 D/AndroidJUnitTest: Attempting to push filters to /data/local/tmp/ajur/includes.txt
11-20 19:27:28 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur)
11-20 19:27:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, ls, '/data/local/tmp/ajur'] with timeout: 2m 0s
11-20 19:27:28 D/NativeDevice: File /data/local/tmp/ajur does not exist.
stdout: 
stderr: ls: /data/local/tmp/ajur: No such file or directory

11-20 19:27:29 D/NativeDevice: Using 'ls' to check doesFileExist(/data/local/tmp/ajur/includes.txt)
11-20 19:27:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, ls, '/data/local/tmp/ajur/includes.txt'] with timeout: 2m 0s
11-20 19:27:29 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "filter-armeabi-v7a%20GtsMediaRouterTestCases.include_" suffix ".txt.gz"
11-20 19:27:29 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/filter-armeabi-v7a%20GtsMediaRouterTestCases.include_13551934339214887613.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:29 D/CertificationSuiteResultReporter: Saved logs for filter-armeabi-v7a%20GtsMediaRouterTestCases.include in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/filter-armeabi-v7a%20GtsMediaRouterTestCases.include_13551934339214887613.txt.gz
11-20 19:27:29 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "filter-armeabi-v7a%20GtsMediaRouterTestCases.include_" suffix ".txt.gz"
11-20 19:27:29 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/filter-armeabi-v7a%20GtsMediaRouterTestCases.include_2818107958859734247.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:29 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
11-20 19:27:29 D/ListInstrumentationParser: instrumentation:com.google.android.gts.media.router/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.gts.media.router)
11-20 19:27:29 D/ListInstrumentationParser: 
11-20 19:27:29 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
11-20 19:27:29 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:29 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
11-20 19:27:29 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:29 W/InstrumentationTest: shell-timeout should be larger than test-timeout 600000; NOTE: extending shell-timeout to 660000, please consider fixing this!
11-20 19:27:29 D/InstrumentationTest: Collecting test info for com.google.android.gts.media.router on device SKBFXYZO0DKW
11-20 19:27:29 I/RemoteAndroidTestRunner: 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 notAnnotation com.android.interactive.annotations.Interactive,com.android.xts.root.annotations.RequireAdbRoot -e timeout_msec 600000 com.google.android.gts.media.router/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DKW
11-20 19:27:30 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
11-20 19:27:30 I/RemoteAndroidTestRunner: 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 notAnnotation com.android.interactive.annotations.Interactive,com.android.xts.root.annotations.RequireAdbRoot -e timeout_msec 600000 com.google.android.gts.media.router/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DKW
11-20 19:27:31 D/ModuleListener: ModuleListener.testRunStarted(com.google.android.gts.media.router, 1, 0) on SKBFXYZO0DKW
11-20 19:27:31 D/ModuleListener: ModuleListener.testStarted(com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi) on SKBFXYZO0DKW
11-20 19:27:32 I/ModuleListener: [1/1] SKBFXYZO0DKW com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi FAILURE: SystemUI or Settings crashed.
expected to be false
	at com.google.android.gts.media.router.MediaRouter2Test.publishAddresslessBluetoothRoute_doesNotCrashSystemUi(MediaRouter2Test.java:196)

11-20 19:27:33 D/ModuleListener: ModuleListener.testRunEnded(2313) on SKBFXYZO0DKW
11-20 19:27:33 D/TestDevice: Uninstalling com.google.android.gts.media.router with extra args 
11-20 19:27:34 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:27:34 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
11-20 19:27:34 I/DeviceStateMonitor: Waiting 359842 ms for device SKBFXYZO0DKW package manager
11-20 19:27:34 I/NativeDeviceStateMonitor: Waiting 359671 ms for device SKBFXYZO0DKW external store
11-20 19:27:34 I/ConsoleReporter: [SKBFXYZO0DKW] Starting armeabi-v7a GtsMediaRouterTestCases with 1 test
11-20 19:27:34 I/ConsoleReporter: [1/1 armeabi-v7a GtsMediaRouterTestCases SKBFXYZO0DKW] com.google.android.gts.media.router.MediaRouter2Test#publishAddresslessBluetoothRoute_doesNotCrashSystemUi fail: SystemUI or Settings crashed.
expected to be false
	at com.google.android.gts.media.router.MediaRouter2Test.publishAddresslessBluetoothRoute_doesNotCrashSystemUi(MediaRouter2Test.java:196)

11-20 19:27:34 I/ConsoleReporter: [SKBFXYZO0DKW] armeabi-v7a GtsMediaRouterTestCases completed in 6s. 0 passed, 1 failed, 0 not executed
11-20 19:27:34 I/ITestSuite: Running system status checker after module execution: armeabi-v7a GtsMediaRouterTestCases
11-20 19:27:34 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:27:34 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DKW boot complete
11-20 19:27:34 I/DeviceStateMonitor: Waiting 359958 ms for device SKBFXYZO0DKW package manager
11-20 19:27:35 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DKW external store
11-20 19:27:36 I/MonitoringUtils: Wifi Connectivity: passed check.
11-20 19:27:37 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

11-20 19:27:37 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:37 D/SystemServerFileDescriptorChecker: Skipping system_server fd check on user builds.
11-20 19:27:37 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/results/2024.11.20_19.26.31/proto with prefix "tmp-proto" suffix ""
11-20 19:27:37 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
11-20 19:27:37 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:37 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "device_logcat_test_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:37 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_test_SKBFXYZO0DKW_6139011128953867030.txt. [size=434226, elapsed=2ms]
11-20 19:27:37 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_SKBFXYZO0DKW in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_test_SKBFXYZO0DKW_6139011128953867030.txt
11-20 19:27:37 D/LogFileSaver: Log data for device_logcat_test_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:37 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "device_logcat_test_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:37 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/device_logcat_test_SKBFXYZO0DKW_17615929303618057724.txt. [size=434226, elapsed=2ms]
11-20 19:27:37 I/CommandInterrupter: Interrupt blocked
11-20 19:27:37 D/TestInvocation: Testing minimum responsiveness.
11-20 19:27:37 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:27:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, getprop, ro.build.version.sdk] with timeout: 45s
11-20 19:27:37 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "executeShellCommandLog_SKBFXYZO0DKW_" suffix ".txt.gz"
11-20 19:27:37 I/LogFileSaver: Saved gzip log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/executeShellCommandLog_SKBFXYZO0DKW_449328138428120181.txt.gz. [size=10, elapsed=1ms]
11-20 19:27:37 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_SKBFXYZO0DKW in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/executeShellCommandLog_SKBFXYZO0DKW_449328138428120181.txt.gz
11-20 19:27:37 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "executeShellCommandLog_SKBFXYZO0DKW_" suffix ".txt.gz"
11-20 19:27:37 I/LogFileSaver: Saved gzip log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/executeShellCommandLog_SKBFXYZO0DKW_13917477004383978318.txt.gz. [size=10, elapsed=0ms]
11-20 19:27:37 D/TestInvocation: Checking that devices are online.
11-20 19:27:37 I/NativeDeviceStateMonitor: Device SKBFXYZO0DKW is already ONLINE
11-20 19:27:37 I/NativeDeviceStateMonitor: Waiting 180000 ms for device SKBFXYZO0DKW boot complete
11-20 19:27:37 I/DeviceStateMonitor: Waiting 179964 ms for device SKBFXYZO0DKW package manager
11-20 19:27:37 I/NativeDeviceStateMonitor: Waiting 179902 ms for device SKBFXYZO0DKW external store
11-20 19:27:37 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@35a5acf6' on device: 'SKBFXYZO0DKW'
11-20 19:27:37 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@35a5acf6' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:37 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@289d4293' on device: 'SKBFXYZO0DKW'
11-20 19:27:37 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@289d4293' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:37 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@33d7335f' on device: 'SKBFXYZO0DKW'
11-20 19:27:37 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:37 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@33d7335f' on device: 'SKBFXYZO0DKW' in 156 ms
11-20 19:27:37 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7842c296' on device: 'SKBFXYZO0DKW'
11-20 19:27:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, verifier_engprod, 0] with timeout: 2m 0s
11-20 19:27:37 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7842c296' on device: 'SKBFXYZO0DKW' in 68 ms
11-20 19:27:37 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7315ef3f' on device: 'SKBFXYZO0DKW'
11-20 19:27:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@7315ef3f' on device: 'SKBFXYZO0DKW' in 66 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@75501899' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-20 19:27:38 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
11-20 19:27:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DKW, shell, ls, '/storage/emulated/0/report-log-files/'] with timeout: 2m 0s
11-20 19:27:38 D/NativeDevice: File /storage/emulated/0/report-log-files/ does not exist.
stdout: 
stderr: ls: /storage/emulated/0/report-log-files/: No such file or directory

11-20 19:27:38 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@75501899' on device: 'SKBFXYZO0DKW' in 123 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@9b7f801' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@9b7f801' on device: 'SKBFXYZO0DKW' in 3 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5a5083e2' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5a5083e2' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@697fca27' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@697fca27' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@6261f510' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@6261f510' on device: 'SKBFXYZO0DKW' in 1 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@2013c587' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@2013c587' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@4f0e9b06' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@4f0e9b06' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:38 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@14aba4f5' on device: 'SKBFXYZO0DKW'
11-20 19:27:38 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@14aba4f5' on device: 'SKBFXYZO0DKW' in 0 ms
11-20 19:27:38 D/TestDevice: Uninstalling android.tradefed.contentprovider with extra args 
11-20 19:27:38 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
11-20 19:27:39 D/WifiHelper: Successfully clean up WifiHelper.
11-20 19:27:39 D/RunUtil: Running command [id, -u, dev2-google03] with timeout: 1m 0s
11-20 19:27:39 D/RunUtil: Running command [tail, -c, 10MB, /tmp/adb.1000.log] with timeout: 1m 0s
11-20 19:27:39 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
11-20 19:27:39 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "host_adb_log_" suffix ".txt"
11-20 19:27:39 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/host_adb_log_16509404050256070869.txt. [size=914881, elapsed=2ms]
11-20 19:27:39 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/host_adb_log_16509404050256070869.txt
11-20 19:27:39 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
11-20 19:27:39 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "host_adb_log_" suffix ".txt"
11-20 19:27:39 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/host_adb_log_12819432715409641095.txt. [size=914881, elapsed=1ms]
11-20 19:27:39 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:39 D/FileUtil: Creating temp file at /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980 with prefix "device_logcat_teardown_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:39 I/LogFileSaver: Saved log file /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_teardown_SKBFXYZO0DKW_1113217318776105394.txt. [size=50182, elapsed=0ms]
11-20 19:27:39 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_SKBFXYZO0DKW in /tmp/xts-root-dir-01be039c-0fea-4d7b-acce-08cb4d9a0079/android-gts/logs/2024.11.20_19.26.31/inv_6320968380473535980/device_logcat_teardown_SKBFXYZO0DKW_1113217318776105394.txt
11-20 19:27:39 D/LogFileSaver: Log data for device_logcat_teardown_SKBFXYZO0DKW is already compressed, skipping compression
11-20 19:27:39 D/FileUtil: Creating temp file at /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223 with prefix "device_logcat_teardown_SKBFXYZO0DKW_" suffix ".txt"
11-20 19:27:39 I/LogFileSaver: Saved log file /tmp/12590200/gts/inv_15755369287843088386/inv_17978410763942235223/device_logcat_teardown_SKBFXYZO0DKW_18167779586133267553.txt. [size=50182, elapsed=1ms]
11-20 19:27:39 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on SKBFXYZO0DKW. May see duplicated content in log.
11-20 19:27:39 D/BackgroundDeviceAction: Waiting for device SKBFXYZO0DKW online before starting.
11-20 19:27:39 I/TestInvocation: Done stopping logcat for SKBFXYZO0DKW
11-20 19:27:39 D/CommandScheduler: TestDeviceState for releasing 'SKBFXYZO0DKW(class com.android.tradefed.device.TestDevice)' is 'ONLINE'
11-20 19:27:39 I/NativeDeviceStateMonitor: Waiting 30000 ms for device SKBFXYZO0DKW shell to be responsive
11-20 19:27:39 I/NativeDeviceStateMonitor: shell ready. id output: uid=2000(shell) gid=2000(shell) groups=2000(shell),1004(input),1007(log),1011(adb),1015(sdcard_rw),1028(sdcard_r),1078(ext_data_rw),1079(ext_obb_rw),3001(net_bt_admin),3002(net_bt),3003(inet),3006(net_bw_stats),3009(readproc),3011(uhid) context=u:r:shell:s0

11-20 19:27:39 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.TestDevice@5b78bec7=AVAILABLE}
11-20 19:27:39 W/NativeDevice: Attempting to stop logcat when not capturing for SKBFXYZO0DKW
11-20 19:27:39 D/TestDevice: No snapuserd notification in progress for BLOCK_BEFORE_RELEASING
