08-22 13:19:59 D/Configuration: Resolve and download remote files from @Option
08-22 13:19:59 I/TestInvocation: Invocation was started with cmd: gts -m GtsSystemNavigationTestCases -s 192.168.0.187:5555
08-22 13:19:59 D/InvocationExecution: Skip linking external directory as FileProperty was set.
08-22 13:19:59 D/RunUtil: Running command [adb, version] with timeout: 2m 0s
08-22 13:19:59 D/TestInvocation: Fetch build duration: 12 ms
08-22 13:19:59 D/ArtifactsAnalyzer: Build analysis report: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=false]
08-22 13:19:59 D/ArtifactsAnalyzer: Analysis report after test analysis: BuildAnalysis [mDeviceImageChanged=true, mHasTestsArtifacts=true, mHasChangesInTests=true]
08-22 13:19:59 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.sdk] with timeout: 45s
08-22 13:19:59 D/GlobalTestFilter: Setting up global filters
08-22 13:19:59 D/BackgroundDeviceAction: Sleep for 5000 before starting logcat for 192.168.0.187:5555.
08-22 13:19:59 D/InvocationExecution: IShardHelper selected: com.android.tradefed.invoker.shard.StrictShardHelper@86c8649
08-22 13:19:59 I/TestInvocation: Starting invocation for 'gts' with '[ DeviceBuildInfo{bid=12124045, serial=192.168.0.187:5555} on device '192.168.0.187:5555'] 
08-22 13:19:59 D/FileUtil: Creating temp directory at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts with prefix "inv_"
08-22 13:19:59 D/RunUtil: Running command [chmod, ug+rwx, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657] with timeout: 10s
08-22 13:19:59 D/FileSystemLogSaver: Using log file directory /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657
08-22 13:19:59 D/FileUtil: Creating temp directory at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657 with prefix "inv_"
08-22 13:19:59 I/LogFileSaver: Using log file directory /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010
08-22 13:19:59 D/CompatibilityProtoResultReporter: Proto Results Directory: /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/results/2024.08.22_13.19.59/proto
08-22 13:19:59 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/results/2024.08.22_13.19.59/proto with prefix "tmp-proto" suffix ""
08-22 13:19:59 D/CompatibilityProtoResultReporter: Proto Results Directory: /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/results/2024.08.22_13.19.59/proto
08-22 13:19:59 D/CertificationSuiteResultReporter: Initializing result directory
08-22 13:19:59 D/CertificationSuiteResultReporter: Results Directory: /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/results/2024.08.22_13.19.59
08-22 13:19:59 D/CertificationSuiteResultReporter: Created log dir /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59
08-22 13:19:59 D/FileUtil: Creating temp directory at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59 with prefix "inv_"
08-22 13:19:59 I/LogFileSaver: Using log file directory /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065
08-22 13:19:59 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
08-22 13:19:59 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "tradefed-expanded-config_" suffix ".xml"
08-22 13:19:59 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/tradefed-expanded-config_14109801662719693389.xml. [size=16469, elapsed=0ms]
08-22 13:19:59 D/CertificationSuiteResultReporter: Saved logs for tradefed-expanded-config in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/tradefed-expanded-config_14109801662719693389.xml
08-22 13:19:59 D/LogFileSaver: Log data for tradefed-expanded-config is already compressed, skipping compression
08-22 13:19:59 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "tradefed-expanded-config_" suffix ".xml"
08-22 13:19:59 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/tradefed-expanded-config_7043544124674687455.xml. [size=16469, elapsed=0ms]
08-22 13:19:59 D/InvocationExecution: Starting device pre invocation setup for : '192.168.0.187:5555'
08-22 13:19:59 D/DefaultConnection: Instance type for connection: GCE
08-22 13:19:59 D/NativeDevice: Using connection: com.android.tradefed.device.connection.AdbTcpConnection@453ba36a (192.168.0.187:5555)
08-22 13:19:59 I/CommandInterrupter: Interrupt allowed
08-22 13:19:59 D/InvocationExecution: Starting setup for device: '192.168.0.187:5555'
08-22 13:19:59 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5546b81c' on device: '192.168.0.187:5555'
08-22 13:19:59 I/UrlReplacement: UrlReplacement file [/Users/tonight0210/Downloads/google_xts/android-gts/android-gts-12-R1/android-gts/tools/UrlReplacement.xml] does not exist
08-22 13:20:00 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5546b81c' on device: '192.168.0.187:5555' in 524 ms
08-22 13:20:00 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@40a6d5ea' on device: '192.168.0.187:5555'
08-22 13:20:00 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@40a6d5ea' on device: '192.168.0.187:5555' in 116 ms
08-22 13:20:00 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@120a7a5a' on device: '192.168.0.187:5555'
08-22 13:20:00 D/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
08-22 13:20:00 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@120a7a5a' on device: '192.168.0.187:5555' in 83 ms
08-22 13:20:00 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@3519607c' on device: '192.168.0.187:5555'
08-22 13:20:00 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:20:00 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.0.187:5555 boot complete
08-22 13:20:00 I/DeviceStateMonitor: Waiting 359957 ms for device 192.168.0.187:5555 package manager
08-22 13:20:00 I/NativeDeviceStateMonitor: Waiting 359887 ms for device 192.168.0.187:5555 external store
08-22 13:20:01 I/ApkInstrumentationPreparer: Instrumenting package: com.android.preconditions.gts
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:01 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.codename] with timeout: 45s
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:01 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:01 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:01 D/GtsPreconditions.apk: Uploading GtsPreconditions.apk onto device '192.168.0.187:5555'
08-22 13:20:01 D/Device: Uploading file onto device '192.168.0.187:5555'
08-22 13:20:02 D/RunUtil: Running command [aapt, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsPreconditions.apk] with timeout: 1m 0s
08-22 13:20:02 D/RunUtil: Running command [aapt, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsPreconditions.apk, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:20:02 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, appops, write-settings] with timeout: 2m 0s
08-22 13:20:03 D/ListInstrumentationParser: instrumentation:com.android.preconditions.gts/androidx.test.runner.AndroidJUnitRunner (target=com.android.preconditions.gts)
08-22 13:20:03 D/ListInstrumentationParser: instrumentation:com.google.devtools.mobileharness.platform.android.app.binary.wifiutil/.WifiUtil (target=com.google.devtools.mobileharness.platform.android.app.binary.wifiutil)
08-22 13:20:03 D/ListInstrumentationParser: 
08-22 13:20:03 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
08-22 13:20:03 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:03 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:03 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:03 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
08-22 13:20:03 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.0.187:5555
08-22 13:20:04 D/BackgroundDeviceAction: Waiting for device 192.168.0.187:5555 online before starting.
08-22 13:20:04 D/BackgroundDeviceAction: Device 192.168.0.187:5555 now online.
08-22 13:20:04 D/BackgroundDeviceAction: Starting logcat for 192.168.0.187:5555.
08-22 13:20:05 D/TestDevice: Uninstalling com.android.preconditions.gts with extra args 
08-22 13:20:06 D/ApkInstrumentationPreparer: Target preparation successful
08-22 13:20:06 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@3519607c' on device: '192.168.0.187:5555' in 5s
08-22 13:20:06 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@2760c551' on device: '192.168.0.187:5555'
08-22 13:20:06 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:20:06 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.0.187:5555 boot complete
08-22 13:20:06 I/DeviceStateMonitor: Waiting 359906 ms for device 192.168.0.187:5555 package manager
08-22 13:20:06 I/NativeDeviceStateMonitor: Waiting 359698 ms for device 192.168.0.187:5555 external store
08-22 13:20:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:06 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:06 D/WifiUtil.apk6055400961333044192.apk: Uploading WifiUtil.apk6055400961333044192.apk onto device '192.168.0.187:5555'
08-22 13:20:06 D/Device: Uploading file onto device '192.168.0.187:5555'
08-22 13:20:07 D/RunUtil: Running command [aapt, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/WifiUtil.apk6055400961333044192.apk] with timeout: 1m 0s
08-22 13:20:07 D/RunUtil: Running command [aapt, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/WifiUtil.apk6055400961333044192.apk, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:20:07 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, appops, write-settings] with timeout: 2m 0s
08-22 13:20:09 I/WifiCheck: Wifi is connected
08-22 13:20:09 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@2760c551' on device: '192.168.0.187:5555' in 3s
08-22 13:20:09 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5eb2f6b2' on device: '192.168.0.187:5555'
08-22 13:20:09 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, rm, -rf, /sdcard/device-info-files] with timeout: 2m 0s
08-22 13:20:09 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, rm, -rf, /sdcard/report-log-files] with timeout: 2m 0s
08-22 13:20:10 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5eb2f6b2' on device: '192.168.0.187:5555' in 376 ms
08-22 13:20:10 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@daf4377' on device: '192.168.0.187:5555'
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.model] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.device] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.id] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.bootimage.build.fingerprint] with timeout: 45s
08-22 13:20:10 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.manufacturer] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.serialno] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.security_patch] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.cpu.abilist32] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.reference.fingerprint] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.cpu.abi] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.cpu.abilist64] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.name] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.tags] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.incremental] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.brand] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.fingerprint] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.vendor.build.fingerprint] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.base_os] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.cpu.abilist] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.board] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.type] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.release] with timeout: 45s
08-22 13:20:10 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.cpu.abi2] with timeout: 45s
08-22 13:20:11 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:20:11 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.0.187:5555 boot complete
08-22 13:20:11 I/DeviceStateMonitor: Waiting 359957 ms for device 192.168.0.187:5555 package manager
08-22 13:20:11 I/NativeDeviceStateMonitor: Waiting 359884 ms for device 192.168.0.187:5555 external store
08-22 13:20:11 I/ApkInstrumentationPreparer: Instrumenting package: com.android.compatibility.common.deviceinfo
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:11 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:11 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:11 D/GtsDeviceInfo.apk: Uploading GtsDeviceInfo.apk onto device '192.168.0.187:5555'
08-22 13:20:11 D/Device: Uploading file onto device '192.168.0.187:5555'
08-22 13:20:14 D/RunUtil: Running command [aapt, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsDeviceInfo.apk] with timeout: 1m 0s
08-22 13:20:14 D/RunUtil: Running command [aapt, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsDeviceInfo.apk, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:20:14 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, appops, write-settings] with timeout: 2m 0s
08-22 13:20:15 D/ListInstrumentationParser: instrumentation:com.android.compatibility.common.deviceinfo/androidx.test.runner.AndroidJUnitRunner (target=com.android.compatibility.common.deviceinfo)
08-22 13:20:15 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
08-22 13:20:15 D/ListInstrumentationParser: instrumentation:com.google.devtools.mobileharness.platform.android.app.binary.wifiutil/.WifiUtil (target=com.google.devtools.mobileharness.platform.android.app.binary.wifiutil)
08-22 13:20:15 D/ListInstrumentationParser: 
08-22 13:20:15 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
08-22 13:20:15 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:15 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:15 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:15 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
08-22 13:20:15 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.0.187:5555
08-22 13:20:43 D/TestDevice: Uninstalling com.android.compatibility.common.deviceinfo with extra args 
08-22 13:20:43 D/ApkInstrumentationPreparer: Target preparation successful
08-22 13:20:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:44 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/device-info-files/)
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "WidevineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/WidevineDeviceInfo.deviceinfo.json_14162706611807564386.txt.gz. [size=10, elapsed=2ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "PackageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/PackageDeviceInfo.deviceinfo.json_8653798481480503809.txt.gz. [size=22220, elapsed=9ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "GraphicsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/GraphicsDeviceInfo.deviceinfo.json_13116477854712307027.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "StorageDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/StorageDeviceInfo.deviceinfo.json_3357733062260673478.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "FeatureDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/FeatureDeviceInfo.deviceinfo.json_14964191057621009042.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "HapticsDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/HapticsDeviceInfo.deviceinfo.json_16085260713622974792.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "TelephonyDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/TelephonyDeviceInfo.deviceinfo.json_5399988210782184571.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "ConfigurationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/ConfigurationDeviceInfo.deviceinfo.json_5455538640317438071.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "MainlineDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/MainlineDeviceInfo.deviceinfo.json_8171522494390595997.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "KeystoreAttestationDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/KeystoreAttestationDeviceInfo.deviceinfo.json_14928880812399673726.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "UserDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/UserDeviceInfo.deviceinfo.json_15040486803274093080.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "PermissionDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/PermissionDeviceInfo.deviceinfo.json_1189833549795452574.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "CpuDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/CpuDeviceInfo.deviceinfo.json_15468090788107692302.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "device_manifest.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_manifest.xml_242272568632778281.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/CertificationSuiteResultReporter: Saved logs for device_manifest.xml in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_manifest.xml_242272568632778281.txt.gz
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "device_manifest.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/device_manifest.xml_13078164718528998755.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "framework_manifest.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/framework_manifest.xml_6610157536761363780.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/CertificationSuiteResultReporter: Saved logs for framework_manifest.xml in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/framework_manifest.xml_6610157536761363780.txt.gz
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "framework_manifest.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/framework_manifest.xml_15146926161643150255.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "ClientIdDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/ClientIdDeviceInfo.deviceinfo.json_9066327942252718488.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "MediaDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/MediaDeviceInfo.deviceinfo.json_3829036755178965448.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "LocaleDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/LocaleDeviceInfo.deviceinfo.json_768205427700074193.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_compatibility_matrix.xml_5391460256813977699.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/CertificationSuiteResultReporter: Saved logs for device_compatibility_matrix.xml in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_compatibility_matrix.xml_5391460256813977699.txt.gz
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "device_compatibility_matrix.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/device_compatibility_matrix.xml_15272145621230888771.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "SecurityDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/SecurityDeviceInfo.deviceinfo.json_1823394237921709337.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "ScreenDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/ScreenDeviceInfo.deviceinfo.json_10539748272621676888.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "GenericDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/GenericDeviceInfo.deviceinfo.json_16482102503748091494.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "VendorApexDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/VendorApexDeviceInfo.deviceinfo.json_13810349079398875984.txt.gz. [size=10, elapsed=1ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "BackupPermissionHoldersDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/BackupPermissionHoldersDeviceInfo.deviceinfo.json_11908459623412131358.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/framework_compatibility_matrix.xml_6300992383297718423.txt.gz. [size=10, elapsed=3ms]
08-22 13:20:45 D/CertificationSuiteResultReporter: Saved logs for framework_compatibility_matrix.xml in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/framework_compatibility_matrix.xml_6300992383297718423.txt.gz
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "framework_compatibility_matrix.xml_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/framework_compatibility_matrix.xml_2087891480091506376.txt.gz. [size=10, elapsed=3ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "MemoryDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/MemoryDeviceInfo.deviceinfo.json_8800312856829029087.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "VintfDeviceInfo.deviceinfo.json_" suffix ".txt.gz"
08-22 13:20:45 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/VintfDeviceInfo.deviceinfo.json_5868529577756163452.txt.gz. [size=10, elapsed=0ms]
08-22 13:20:45 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@daf4377' on device: '192.168.0.187:5555' in 35s
08-22 13:20:45 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@32e8988a' on device: '192.168.0.187:5555'
08-22 13:20:45 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@32e8988a' on device: '192.168.0.187:5555' in 1 ms
08-22 13:20:45 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@26603982' on device: '192.168.0.187:5555'
08-22 13:20:45 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, settings, put, global, package_verifier_enable, 0] with timeout: 2m 0s
08-22 13:20:45 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@26603982' on device: '192.168.0.187:5555' in 95 ms
08-22 13:20:45 D/InvocationExecution: starting preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1f698196' on device: '192.168.0.187:5555'
08-22 13:20:45 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, settings, put, global, verifier_engprod, 1] with timeout: 2m 0s
08-22 13:20:45 D/InvocationExecution: done with preparer 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1f698196' on device: '192.168.0.187:5555' in 73 ms
08-22 13:20:45 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@8b5e9ba' on device: '192.168.0.187:5555'
08-22 13:20:45 W/BusinessLogicPreparer: Option config-filename isn't set. Using empty string instead.
08-22 13:20:45 W/BusinessLogicPreparer: Option version isn't set. Using 'null' instead.
08-22 13:20:45 D/BusinessLogicPreparer: original version: 12_r1, extracted version: 12_r1
08-22 13:20:45 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
08-22 13:20:45 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
08-22 13:20:45 I/DynamicConfigFileReader: config files: {gts=/var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/gts1401807819333565242.dynamic}
08-22 13:20:47 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
08-22 13:20:47 I/DynamicConfigFileReader: config files: {gts=/var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/gts1401807819333565242.dynamic}
08-22 13:20:47 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.boot.hardware.sku] with timeout: 45s
08-22 13:20:47 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.boot.product.hardware.sku] with timeout: 45s
08-22 13:20:47 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.boot.product.vendor.sku] with timeout: 45s
08-22 13:20:47 D/NativeDevice: Using property ro.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/515.542.20-20240820:user/release-keys from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.product.device=BFX-AT100 from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.build.version.release=12 from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.product.brand=SKB from cache.
08-22 13:20:47 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.first_api_level] with timeout: 45s
08-22 13:20:47 D/NativeDevice: Using property ro.product.manufacturer=Foxconn from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.product.model=BFX-AT100 from cache.
08-22 13:20:47 D/NativeDevice: Using property ro.product.name=BFX_AT100 from cache.
08-22 13:20:47 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
08-22 13:20:47 I/DynamicConfigFileReader: config files: {gts=/var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/gts1401807819333565242.dynamic}
08-22 13:20:47 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
08-22 13:20:47 I/DynamicConfigFileReader: config files: {gts=/var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/gts1401807819333565242.dynamic}
08-22 13:20:47 D/BusinessLogicPreparer: Built param string: "suite_version=12_r1&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%2F515.542.20-20240820%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%3A3083898880"
08-22 13:20:47 I/BusinessLogicPreparer: Using gts from TestSuiteInfo to get value from dynamic config
08-22 13:20:47 I/BusinessLogicPreparer: Attempting to connect to business logic service...
08-22 13:20:47 D/BusinessLogicPreparer: Unable to fetch the service key because neither environment variable APE_API_KEY is set nor the key file is dynamically downloaded.
08-22 13:20:47 D/BusinessLogicPreparer: Business Logic Service Response Code : 200
08-22 13:20:48 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:48 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:48 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:48 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:48 D/TradefedContentProvider.apk7316362395296627641.apk: Uploading TradefedContentProvider.apk7316362395296627641.apk onto device '192.168.0.187:5555'
08-22 13:20:48 D/Device: Uploading file onto device '192.168.0.187:5555'
08-22 13:20:49 D/RunUtil: Running command [aapt, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/TradefedContentProvider.apk7316362395296627641.apk] with timeout: 1m 0s
08-22 13:20:49 D/RunUtil: Running command [aapt, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/TradefedContentProvider.apk7316362395296627641.apk, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:20:49 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, appops, write-settings] with timeout: 2m 0s
08-22 13:20:49 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, cmd, appops, set, android.tradefed.contentprovider, android:legacy_storage, allow] with timeout: 2m 0s
08-22 13:20:49 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, cmd, appops, get, android.tradefed.contentprovider] with timeout: 2m 0s
08-22 13:20:49 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, content, write, --user, 0, --uri, content://android.tradefed.contentprovider/%252Fsdcard%252Fbl] with timeout: 2m 0s
08-22 13:20:51 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@8b5e9ba' on device: '192.168.0.187:5555' in 5s
08-22 13:20:51 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@55383d1b' on device: '192.168.0.187:5555'
08-22 13:20:51 D/NativeDevice: Using property ro.build.type=user from cache.
08-22 13:20:51 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@55383d1b' on device: '192.168.0.187:5555' in 1 ms
08-22 13:20:51 D/InvocationExecution: starting preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53f3ad8e' on device: '192.168.0.187:5555'
08-22 13:20:51 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.product.locale] with timeout: 45s
08-22 13:20:51 W/PropertyCheck: Expected "en-US" but found "ko-KR" for property: ro.product.locale
08-22 13:20:51 D/InvocationExecution: done with preparer 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53f3ad8e' on device: '192.168.0.187:5555' in 59 ms
08-22 13:20:51 D/InvocationExecution: Done with setup of device: '192.168.0.187:5555'
08-22 13:20:51 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, uname, -a] with timeout: 2m 0s
08-22 13:20:51 I/InvocationExecution: Device 192.168.0.187:5555 kernel information: 'Linux localhost 4.9.269-abk000c-542r20-20240820 #1 SMP PREEMPT Tue Aug 20 17:39:33 KST 2024 armv8l'
08-22 13:20:51 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.system.build.fingerprint] with timeout: 45s
08-22 13:20:51 I/InvocationExecution: Device 192.168.0.187:5555 system image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/515.542.20-20240820:user/release-keys'
08-22 13:20:51 D/NativeDevice: Using property ro.vendor.build.fingerprint=SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/515.542.20-20240820:user/release-keys from cache.
08-22 13:20:51 I/InvocationExecution: Device 192.168.0.187:5555 vendor image build information: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/515.542.20-20240820:user/release-keys'
08-22 13:20:51 D/InvocationExecution: Total setup duration: 51s'
08-22 13:20:51 D/LogFileSaver: Log data for device_logcat_setup_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:20:51 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "device_logcat_setup_192.168.0.187:5555_" suffix ".txt"
08-22 13:20:51 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_setup_192.168.0.187:5555_15937245668160040833.txt. [size=790838, elapsed=1ms]
08-22 13:20:51 D/CertificationSuiteResultReporter: Saved logs for device_logcat_setup_192.168.0.187:5555 in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_setup_192.168.0.187:5555_15937245668160040833.txt
08-22 13:20:51 D/LogFileSaver: Log data for device_logcat_setup_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:20:51 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "device_logcat_setup_192.168.0.187:5555_" suffix ".txt"
08-22 13:20:51 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/device_logcat_setup_192.168.0.187:5555_825601391102608128.txt. [size=790838, elapsed=1ms]
08-22 13:20:51 D/TestDevice: No snapuserd notification in progress for BLOCK_BEFORE_TEST
08-22 13:20:51 D/PrettyPrintDelimiter: 
===============================
===== TEST PHASE STARTING =====
===============================
08-22 13:20:51 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, cmd, device_state, print-states] with timeout: 2m 0s
08-22 13:20:51 D/NativeDevice: Using property ro.product.cpu.abilist=armeabi-v7a,armeabi from cache.
08-22 13:20:51 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.
08-22 13:20:51 D/BaseTestSuite: Initializing ModuleRepo
ABIs:[{armeabi-v7a, bitness=32}]
Test Args:[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 GtsSystemNavigationTestCases=[GtsSystemNavigationTestCases], armeabi-v7a GtsSystemNavigationTestCases[instant]=[GtsSystemNavigationTestCases[instant]], armeabi-v7a GtsSystemNavigationTestCases[run-on-secondary-user]=[GtsSystemNavigationTestCases[run-on-secondary-user]], armeabi-v7a GtsSystemNavigationTestCases[run-on-clone-profile]=[GtsSystemNavigationTestCases[run-on-clone-profile]], armeabi-v7a GtsSystemNavigationTestCases[run-on-work-profile]=[GtsSystemNavigationTestCases[run-on-work-profile]], armeabi-v7a GtsSystemNavigationTestCases[run-on-private-profile]=[GtsSystemNavigationTestCases[run-on-private-profile]]}

08-22 13:20:51 D/BaseTestSuite: Foldable states: [foldable:0:DEFAULT]
08-22 13:20:52 D/ITestSuite: No remote build info, skipping stageTestArtifacts
08-22 13:20:52 D/ITestSuite: Resource not found for allowed preparers: /suite/google-allowed-preparers.txt
08-22 13:20:52 D/ITestSuite: [Total Unique Modules = 1]
08-22 13:20:52 I/ITestSuite: 192.168.0.187:5555 running 1 modules: [armeabi-v7a GtsSystemNavigationTestCases]
08-22 13:20:52 D/BaseTestSuite: Filters for 'armeabi-v7a GtsSystemNavigationTestCases': []
08-22 13:20:52 D/LogFileSaver: Log data for module-configuration is already compressed, skipping compression
08-22 13:20:52 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "module-configuration_" suffix ".xml"
08-22 13:20:52 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/module-configuration_888236263595776341.xml. [size=1366, elapsed=0ms]
08-22 13:20:52 D/CertificationSuiteResultReporter: Saved logs for module-configuration in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/module-configuration_888236263595776341.xml
08-22 13:20:52 D/LogFileSaver: Log data for module-configuration is already compressed, skipping compression
08-22 13:20:52 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "module-configuration_" suffix ".xml"
08-22 13:20:52 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/module-configuration_1749584022147907027.xml. [size=1366, elapsed=0ms]
08-22 13:20:52 I/ITestSuite: Running system status checker before module execution: armeabi-v7a GtsSystemNavigationTestCases
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.type=user from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 I/DeviceBaselineChecker: Enable device baselines disable_os_auto_update,hide_error_dialogs,keep_screen_on,disable_usb_app_verification,clear_lock_screen in 1524 millis.
08-22 13:20:54 D/ModuleDefinition: Running module armeabi-v7a GtsSystemNavigationTestCases
08-22 13:20:54 D/ModuleDefinition: Running setup preparer: SuiteApkInstaller
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, pm, list, features] with timeout: 2m 0s
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:20:54 D/RunUtil: Running command [aapt2, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsSystemNavigationTestCases.apk] with timeout: 1m 0s
08-22 13:20:54 D/RunUtil: Running command [aapt2, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsSystemNavigationTestCases.apk, --file, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:20:54 D/TestAppInstallSetup: Installing apk com.google.android.systemui.gts with [/var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsSystemNavigationTestCases.apk] ...
08-22 13:20:54 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:20:54 D/GtsSystemNavigationTestCases.apk: Uploading GtsSystemNavigationTestCases.apk onto device '192.168.0.187:5555'
08-22 13:20:54 D/Device: Uploading file onto device '192.168.0.187:5555'
08-22 13:21:05 D/RunUtil: Running command [aapt, dump, badging, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsSystemNavigationTestCases.apk] with timeout: 1m 0s
08-22 13:21:05 D/RunUtil: Running command [aapt, dump, xmltree, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/testcases/GtsSystemNavigationTestCases.apk, AndroidManifest.xml] with timeout: 1m 0s
08-22 13:21:05 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, appops, write-settings] with timeout: 2m 0s
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:21:06 D/AndroidJUnitTest: Disabled test storage as it's not supported on that branch.
08-22 13:21:06 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
08-22 13:21:06 D/ListInstrumentationParser: instrumentation:com.google.android.systemui.gts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.systemui.gts)
08-22 13:21:06 D/ListInstrumentationParser: instrumentation:com.google.devtools.mobileharness.platform.android.app.binary.wifiutil/.WifiUtil (target=com.google.devtools.mobileharness.platform.android.app.binary.wifiutil)
08-22 13:21:06 D/ListInstrumentationParser: 
08-22 13:21:06 I/InstrumentationTest: No runner name specified. Using: androidx.test.runner.AndroidJUnitRunner.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.codename=REL from cache.
08-22 13:21:06 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:06 D/InstrumentationTest: Collecting test info for com.google.android.systemui.gts on device 192.168.0.187:5555
08-22 13:21:06 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e debug false -e newRunListenerMode true -e log true -e notAnnotation com.android.xts.root.annotations.RequireAdbRoot,com.android.interactive.annotations.Interactive -e timeout_msec 300000 com.google.android.systemui.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.0.187:5555
08-22 13:21:10 D/InstrumentationTest: Initializing com.android.tradefed.device.metric.CountTestCasesCollector for instrumentation.
08-22 13:21:10 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e debug false -e newRunListenerMode true -e log false -e notAnnotation com.android.xts.root.annotations.RequireAdbRoot,com.android.interactive.annotations.Interactive -e timeout_msec 300000 com.google.android.systemui.gts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-192.168.0.187:5555
08-22 13:21:15 D/ModuleListener: ModuleListener.testRunStarted(com.google.android.systemui.gts, 2, 0) on 192.168.0.187:5555
08-22 13:21:15 D/ModuleListener: ModuleListener.testStarted(com.google.android.systemui.gts.SystemNavigationModeTest#testRequired3ButtonNavMode) on 192.168.0.187:5555
08-22 13:21:15 I/ModuleListener: [1/2] 192.168.0.187:5555 com.google.android.systemui.gts.SystemNavigationModeTest#testRequired3ButtonNavMode FAILURE: java.lang.AssertionError: Specified package: com.android.internal.systemui.navbar.threebutton is not installed in this test device.
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at com.google.android.systemui.gts.SystemNavigationModeTest.testRequired3ButtonNavMode(SystemNavigationModeTest.java:59)

08-22 13:21:15 D/ModuleListener: ModuleListener.testStarted(com.google.android.systemui.gts.SystemNavigationModeTest#testRequiredGesturalNavMode) on 192.168.0.187:5555
08-22 13:21:15 I/ModuleListener: [2/2] 192.168.0.187:5555 com.google.android.systemui.gts.SystemNavigationModeTest#testRequiredGesturalNavMode FAILURE: java.lang.AssertionError: Specified package: com.android.internal.systemui.navbar.gestural is not installed in this test device.
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at com.google.android.systemui.gts.SystemNavigationModeTest.testRequiredGesturalNavMode(SystemNavigationModeTest.java:69)

08-22 13:21:16 D/ModuleListener: ModuleListener.testRunEnded(1102) on 192.168.0.187:5555
08-22 13:21:16 D/TestDevice: Uninstalling com.google.android.systemui.gts with extra args 
08-22 13:21:17 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:21:17 I/NativeDeviceStateMonitor: Waiting 359999 ms for device 192.168.0.187:5555 boot complete
08-22 13:21:17 I/DeviceStateMonitor: Waiting 359846 ms for device 192.168.0.187:5555 package manager
08-22 13:21:17 I/NativeDeviceStateMonitor: Waiting 359650 ms for device 192.168.0.187:5555 external store
08-22 13:21:18 I/ConsoleReporter: [192.168.0.187:5555] Starting armeabi-v7a GtsSystemNavigationTestCases with 2 tests
08-22 13:21:18 I/ConsoleReporter: [1/2 armeabi-v7a GtsSystemNavigationTestCases 192.168.0.187:5555] com.google.android.systemui.gts.SystemNavigationModeTest#testRequired3ButtonNavMode fail: java.lang.AssertionError: Specified package: com.android.internal.systemui.navbar.threebutton is not installed in this test device.
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at com.google.android.systemui.gts.SystemNavigationModeTest.testRequired3ButtonNavMode(SystemNavigationModeTest.java:59)

08-22 13:21:18 I/ConsoleReporter: [2/2 armeabi-v7a GtsSystemNavigationTestCases 192.168.0.187:5555] com.google.android.systemui.gts.SystemNavigationModeTest#testRequiredGesturalNavMode fail: java.lang.AssertionError: Specified package: com.android.internal.systemui.navbar.gestural is not installed in this test device.
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at com.google.android.systemui.gts.SystemNavigationModeTest.testRequiredGesturalNavMode(SystemNavigationModeTest.java:69)

08-22 13:21:18 I/ConsoleReporter: [192.168.0.187:5555] armeabi-v7a GtsSystemNavigationTestCases completed in 11s. 0 passed, 2 failed, 0 not executed
08-22 13:21:18 I/ITestSuite: Running system status checker after module execution: armeabi-v7a GtsSystemNavigationTestCases
08-22 13:21:18 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:21:18 I/NativeDeviceStateMonitor: Waiting 360000 ms for device 192.168.0.187:5555 boot complete
08-22 13:21:18 I/DeviceStateMonitor: Waiting 359956 ms for device 192.168.0.187:5555 package manager
08-22 13:21:18 I/NativeDeviceStateMonitor: Waiting 359883 ms for device 192.168.0.187:5555 external store
08-22 13:21:20 I/MonitoringUtils: Wifi Connectivity: passed check.
08-22 13:21:20 D/TestDevice: Output from KeyguardController:   KeyguardController:
    mKeyguardShowing=false
    mAodShowing=false
    mKeyguardGoingAway=false

08-22 13:21:20 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:20 D/SystemServerFileDescriptorChecker: Skipping system_server fd check on user builds.
08-22 13:21:20 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/results/2024.08.22_13.19.59/proto with prefix "tmp-proto" suffix ""
08-22 13:21:20 D/PrettyPrintDelimiter: 
=============================
===== TEST PHASE ENDING =====
=============================
08-22 13:21:20 D/LogFileSaver: Log data for device_logcat_test_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:21:20 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "device_logcat_test_192.168.0.187:5555_" suffix ".txt"
08-22 13:21:20 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_test_192.168.0.187:5555_13497745384478003437.txt. [size=208953, elapsed=0ms]
08-22 13:21:20 D/CertificationSuiteResultReporter: Saved logs for device_logcat_test_192.168.0.187:5555 in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_test_192.168.0.187:5555_13497745384478003437.txt
08-22 13:21:20 D/LogFileSaver: Log data for device_logcat_test_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:21:20 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "device_logcat_test_192.168.0.187:5555_" suffix ".txt"
08-22 13:21:20 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/device_logcat_test_192.168.0.187:5555_14390039384989212953.txt. [size=208953, elapsed=1ms]
08-22 13:21:20 I/CommandInterrupter: Interrupt blocked
08-22 13:21:20 D/TestInvocation: Testing minimum responsiveness.
08-22 13:21:20 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:21:20 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, getprop, ro.build.version.sdk] with timeout: 45s
08-22 13:21:20 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "executeShellCommandLog_192.168.0.187:5555_" suffix ".txt.gz"
08-22 13:21:20 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/executeShellCommandLog_192.168.0.187:5555_7121842719369772641.txt.gz. [size=10, elapsed=1ms]
08-22 13:21:20 D/CertificationSuiteResultReporter: Saved logs for executeShellCommandLog_192.168.0.187:5555 in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/executeShellCommandLog_192.168.0.187:5555_7121842719369772641.txt.gz
08-22 13:21:20 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "executeShellCommandLog_192.168.0.187:5555_" suffix ".txt.gz"
08-22 13:21:20 I/LogFileSaver: Saved gzip log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/executeShellCommandLog_192.168.0.187:5555_8342622712274145708.txt.gz. [size=10, elapsed=0ms]
08-22 13:21:20 D/TestInvocation: Checking that devices are online.
08-22 13:21:20 I/NativeDeviceStateMonitor: Device 192.168.0.187:5555 is already ONLINE
08-22 13:21:20 I/NativeDeviceStateMonitor: Waiting 180000 ms for device 192.168.0.187:5555 boot complete
08-22 13:21:20 I/DeviceStateMonitor: Waiting 179957 ms for device 192.168.0.187:5555 package manager
08-22 13:21:20 I/NativeDeviceStateMonitor: Waiting 179891 ms for device 192.168.0.187:5555 external store
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53f3ad8e' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@53f3ad8e' on device: '192.168.0.187:5555' in 1 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@55383d1b' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.PropertyCheck@55383d1b' on device: '192.168.0.187:5555' in 1 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@8b5e9ba' on device: '192.168.0.187:5555'
08-22 13:21:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.BusinessLogicPreparer@8b5e9ba' on device: '192.168.0.187:5555' in 155 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1f698196' on device: '192.168.0.187:5555'
08-22 13:21:21 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, settings, put, global, verifier_engprod, 0] with timeout: 2m 0s
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@1f698196' on device: '192.168.0.187:5555' in 77 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@26603982' on device: '192.168.0.187:5555'
08-22 13:21:21 D/RunUtil: Running command [adb, -s, 192.168.0.187:5555, shell, settings, put, global, package_verifier_enable, 1] with timeout: 2m 0s
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@26603982' on device: '192.168.0.187:5555' in 81 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@32e8988a' on device: '192.168.0.187:5555'
08-22 13:21:21 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
08-22 13:21:21 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/report-log-files/)
08-22 13:21:21 E/NativeDevice: Device path /sdcard/report-log-files/ does not exist to be pulled.
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ReportLogCollector@32e8988a' on device: '192.168.0.187:5555' in 157 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@daf4377' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DeviceInfoCollector@daf4377' on device: '192.168.0.187:5555' in 12 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5eb2f6b2' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.tradefed.targetprep.RunCommandTargetPreparer@5eb2f6b2' on device: '192.168.0.187:5555' in 0 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@2760c551' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.WifiCheck@2760c551' on device: '192.168.0.187:5555' in 0 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@3519607c' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.ApkPreconditionCheck@3519607c' on device: '192.168.0.187:5555' in 0 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@120a7a5a' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.SettingsPreparer@120a7a5a' on device: '192.168.0.187:5555' in 0 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@40a6d5ea' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.StayAwakePreparer@40a6d5ea' on device: '192.168.0.187:5555' in 0 ms
08-22 13:21:21 D/InvocationExecution: starting tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5546b81c' on device: '192.168.0.187:5555'
08-22 13:21:21 D/InvocationExecution: done with tearDown 'com.android.compatibility.common.tradefed.targetprep.DynamicConfigPusher@5546b81c' on device: '192.168.0.187:5555' in 1 ms
08-22 13:21:21 D/TestDevice: Uninstalling android.tradefed.contentprovider with extra args 
08-22 13:21:22 D/TestDevice: Uninstalling com.android.tradefed.utils.wifi with extra args 
08-22 13:21:22 D/WifiHelper: Successfully clean up WifiHelper.
08-22 13:21:22 D/RunUtil: Running command [id, -u, tonight0210] with timeout: 1m 0s
08-22 13:21:22 D/RunUtil: Running command [tail, -c, 10MB, /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/adb.501.log] with timeout: 1m 0s
08-22 13:21:23 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
08-22 13:21:23 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "host_adb_log_" suffix ".txt"
08-22 13:21:23 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/host_adb_log_2382007923391713094.txt. [size=23704, elapsed=1ms]
08-22 13:21:23 D/CertificationSuiteResultReporter: Saved logs for host_adb_log in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/host_adb_log_2382007923391713094.txt
08-22 13:21:23 D/LogFileSaver: Log data for host_adb_log is already compressed, skipping compression
08-22 13:21:23 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "host_adb_log_" suffix ".txt"
08-22 13:21:23 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/host_adb_log_8710525084521687537.txt. [size=23704, elapsed=0ms]
08-22 13:21:23 D/LogFileSaver: Log data for device_logcat_teardown_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:21:23 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065 with prefix "device_logcat_teardown_192.168.0.187:5555_" suffix ".txt"
08-22 13:21:23 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_teardown_192.168.0.187:5555_17708571480342260830.txt. [size=37637, elapsed=0ms]
08-22 13:21:23 D/CertificationSuiteResultReporter: Saved logs for device_logcat_teardown_192.168.0.187:5555 in /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/xts-root-dir-92cd911f-7049-4d0f-83db-a4965355620b/android-gts/logs/2024.08.22_13.19.59/inv_7850780228818816065/device_logcat_teardown_192.168.0.187:5555_17708571480342260830.txt
08-22 13:21:23 D/LogFileSaver: Log data for device_logcat_teardown_192.168.0.187:5555 is already compressed, skipping compression
08-22 13:21:23 D/FileUtil: Creating temp file at /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010 with prefix "device_logcat_teardown_192.168.0.187:5555_" suffix ".txt"
08-22 13:21:23 I/LogFileSaver: Saved log file /var/folders/pm/_7kxf9n140134myzyj_jgd_00000gn/T/12124045/gts/inv_13158081663774634657/inv_17754072232448642010/device_logcat_teardown_192.168.0.187:5555_6367774936004332798.txt. [size=37637, elapsed=1ms]
08-22 13:21:23 I/TestInvocation: Done stopping logcat for 192.168.0.187:5555
08-22 13:21:23 D/BackgroundDeviceAction: com.android.ddmlib.TimeoutException while running logcat on 192.168.0.187:5555. May see duplicated content in log.
08-22 13:21:23 D/BackgroundDeviceAction: Waiting for device 192.168.0.187:5555 online before starting.
08-22 13:21:23 D/CommandScheduler: Release map of the devices: {com.android.tradefed.device.RemoteAndroidDevice@6cddae7c=AVAILABLE}
08-22 13:21:23 W/NativeDevice: Attempting to stop logcat when not capturing for 192.168.0.187:5555
08-22 13:21:23 D/TestDevice: No snapuserd notification in progress for BLOCK_BEFORE_RELEASING
