2019-09-03 14:36:17:147 [Appium] Welcome to Appium v1.14.0 2019-09-03 14:36:17:149 [Appium] Non-default server args: 2019-09-03 14:36:17:149 [Appium] allowCors: true 2019-09-03 14:36:17:149 [Appium] port: 4923 2019-09-03 14:36:17:149 [Appium] sessionOverride: true 2019-09-03 14:36:17:150 [Appium] logFile: /Users/berg/appiumLog.txt 2019-09-03 14:36:17:150 [Appium] loglevel: debug:debug 2019-09-03 14:36:17:150 [Appium] logTimestamp: true 2019-09-03 14:36:17:150 [Appium] debugLogSpacing: true 2019-09-03 14:36:17:150 [Appium] relaxedSecurityEnabled: true 2019-09-03 14:36:17:170 [Appium] You have enabled CORS requests from any host. Be careful not to visit sites which could maliciously try to start Appium sessions on your machine 2019-09-03 14:36:17:171 [Appium] Appium REST http interface listener started on 0.0.0.0:4923 2019-09-03 14:36:19:665 [HTTP] --> POST /wd/hub/session 2019-09-03 14:36:19:666 [HTTP] {"desiredCapabilities":{"app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","noReset":"false","xcodeOrgId":"","language":"en","locale":"US","deviceName":"GalaxyS9+","fullReset":"true","xcodeSigningId":"iPhone Developer","newCommandTimeout":300000,"platformVersion":"9.0.0","automationName":"UIAutomator2","platformName":"Android","udid":""},"capabilities":{"firstMatch":[{"appium:app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","appium:automationName":"UIAutomator2","appium:deviceName":"GalaxyS9+","appium:fullReset":"true","appium:language":"en","appium:locale":"US","appium:newCommandTimeout":300000,"appium:noReset":"false","platformName":"android","appium:platformVersion":"9.0.0","appium:udid":"","appium:xcodeOrgId":"","appium:xcodeSigningId":"iPhone Developer"}]}} 2019-09-03 14:36:19:667 [W3C] Calling AppiumDriver.createSession() with args: [{"app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","noReset":"false","xcodeOrgId":"","language":"en","locale":"US","deviceName":"GalaxyS9+","fullReset":"true","xcodeSigningId":"iPhone Developer","newCommandTimeout":300000,"platformVersion":"9.0.0","automationName":"UIAutomator2","platformName":"Android","udid":""},null,{"firstMatch":[{"appium:app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","appium:automationName":"UIAutomator2","appium:deviceName":"GalaxyS9+","appium:fullReset":"true","appium:language":"en","appium:locale":"US","appium:newCommandTimeout":300000,"appium:noReset":"false","platformName":"android","appium:platformVersion":"9.0.0","appium:udid":"","appium:xcodeOrgId":"","appium:xcodeSigningId":"iPhone Developer"}]}] 2019-09-03 14:36:19:668 [BaseDriver] Event 'newSessionRequested' logged at 1567521379668 (09:36:19 GMT-0500 (Central Daylight Time)) 2019-09-03 14:36:19:749 [Appium] Appium v1.14.0 creating new AndroidUiautomator2Driver (v1.33.1) session 2019-09-03 14:36:19:750 [Appium] Capabilities: 2019-09-03 14:36:19:750 [Appium] platformName: android 2019-09-03 14:36:19:750 [Appium] app: //Users/berg/App/Release_2.1.0/com.company.app.apk 2019-09-03 14:36:19:750 [Appium] automationName: UIAutomator2 2019-09-03 14:36:19:750 [Appium] deviceName: GalaxyS9+ 2019-09-03 14:36:19:751 [Appium] fullReset: true 2019-09-03 14:36:19:751 [Appium] language: en 2019-09-03 14:36:19:751 [Appium] locale: US 2019-09-03 14:36:19:751 [Appium] newCommandTimeout: 300000 2019-09-03 14:36:19:752 [Appium] noReset: false 2019-09-03 14:36:19:752 [Appium] platformVersion: 9.0.0 2019-09-03 14:36:19:752 [Appium] udid: 2019-09-03 14:36:19:752 [Appium] xcodeOrgId: 2019-09-03 14:36:19:752 [Appium] xcodeSigningId: iPhone Developer 2019-09-03 14:36:19:754 [Appium] Applying relaxed security to 'AndroidUiautomator2Driver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure 2019-09-03 14:36:19:755 [BaseDriver] W3C capabilities {"alwaysMatch":{"platformNa... and MJSONWP desired capabilities {"app":"//Users/berg/One... were provided 2019-09-03 14:36:19:755 [BaseDriver] Creating session with W3C capabilities: {"alwaysMatch":{"platformNa... 2019-09-03 14:36:19:757 [BaseDriver] Capability 'noReset' changed from string to boolean. This may cause unexpected behavior 2019-09-03 14:36:19:758 [BaseDriver] Capability 'fullReset' changed from string to boolean. This may cause unexpected behavior 2019-09-03 14:36:19:760 [BaseDriver] The following capabilities were provided, but are not recognized by Appium: 2019-09-03 14:36:19:760 [BaseDriver] xcodeOrgId 2019-09-03 14:36:19:760 [BaseDriver] xcodeSigningId 2019-09-03 14:36:19:761 [BaseDriver] Session created with session id: bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:19:776 [BaseDriver] Using local app '//Users/berg/App/Release_2.1.0/com.company.app.apk' 2019-09-03 14:36:19:776 [UiAutomator2] Checking whether app is actually present 2019-09-03 14:36:19:784 [ADB] Found 1 'build-tools' folders under '/usr/local/share/android-sdk' (newest first): 2019-09-03 14:36:19:785 [ADB] /usr/local/share/android-sdk/build-tools/29.0.1 2019-09-03 14:36:19:785 [ADB] Using 'adb' from '/usr/local/share/android-sdk/platform-tools/adb' 2019-09-03 14:36:19:785 [AndroidDriver] Retrieving device list 2019-09-03 14:36:19:785 [ADB] Trying to find a connected android device 2019-09-03 14:36:19:786 [ADB] Getting connected devices... 2019-09-03 14:36:19:795 [ADB] 2 device(s) connected 2019-09-03 14:36:19:795 [AndroidDriver] Using device: 2019-09-03 14:36:19:796 [ADB] Using 'adb' from '/usr/local/share/android-sdk/platform-tools/adb' 2019-09-03 14:36:19:797 [ADB] Setting device id to 2019-09-03 14:36:19:798 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell getprop ro.build.version.sdk' 2019-09-03 14:36:19:856 [ADB] Current device property 'ro.build.version.sdk': 28 2019-09-03 14:36:19:857 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell getprop ro.build.version.release' 2019-09-03 14:36:19:912 [ADB] Current device property 'ro.build.version.release': 9 2019-09-03 14:36:19:912 [ADB] Device API level: 28 2019-09-03 14:36:19:912 [UiAutomator2] Relaxing hidden api policy 2019-09-03 14:36:19:913 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell settings put global hidden_api_policy_pre_p_apps 1' 2019-09-03 14:36:20:018 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell settings put global hidden_api_policy_p_apps 1' 2019-09-03 14:36:20:139 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell settings put global hidden_api_policy 1' 2019-09-03 14:36:20:259 [AndroidDriver] Parsing package and activity from app manifest 2019-09-03 14:36:20:261 [ADB] Using 'apkanalyzer' from '/usr/local/share/android-sdk/tools/bin/apkanalyzer' 2019-09-03 14:36:20:262 [ADB] Starting '/usr/local/share/android-sdk/tools/bin/apkanalyzer' with args ["-h","manifest","print","//Users/berg/App/Release_2.1.0/com.company.app.apk"] 2019-09-03 14:36:20:921 [ADB] Package name: 'com.company.app' 2019-09-03 14:36:20:921 [ADB] Main activity name: 'md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity' 2019-09-03 14:36:20:922 [AndroidDriver] Parsed package and activity are: com.company.app/md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity 2019-09-03 14:36:20:922 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s wait-for-device' 2019-09-03 14:36:20:928 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell echo ping' 2019-09-03 14:36:20:990 [AndroidDriver] Pushing settings apk to device... 2019-09-03 14:36:20:992 [ADB] Getting install status for io.appium.settings 2019-09-03 14:36:20:993 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package io.appium.settings' 2019-09-03 14:36:21:123 [ADB] 'io.appium.settings' is installed 2019-09-03 14:36:21:125 [ADB] Getting package info for 'io.appium.settings' 2019-09-03 14:36:21:125 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package io.appium.settings' 2019-09-03 14:36:21:248 [ADB] Using 'aapt' from '/usr/local/share/android-sdk/build-tools/29.0.1/aapt' 2019-09-03 14:36:21:263 [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('2.14.1' >= '2.14.1') 2019-09-03 14:36:21:264 [ADB] There is no need to install/upgrade '/usr/local/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' 2019-09-03 14:36:21:264 [ADB] Getting IDs of all 'io.appium.settings' processes 2019-09-03 14:36:21:265 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell 'pgrep --help; echo $?'' 2019-09-03 14:36:21:351 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f io\\.appium\\.settings' 2019-09-03 14:36:21:472 [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions. 2019-09-03 14:36:21:473 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell appops set io.appium.settings android\:mock_location allow' 2019-09-03 14:36:21:613 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell getprop persist.sys.locale' 2019-09-03 14:36:21:673 [ADB] Current device property 'persist.sys.locale': en-US 2019-09-03 14:36:21:674 [ADB] Current locale: 'en-US'; requested locale: 'en-US' 2019-09-03 14:36:21:676 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell getprop persist.sys.locale' 2019-09-03 14:36:21:737 [ADB] Current device property 'persist.sys.locale': en-US 2019-09-03 14:36:21:738 [ADB] Requested locale is equal to current locale: 'en-us' 2019-09-03 14:36:21:740 [Logcat] Starting logcat capture 2019-09-03 14:36:21:809 [ADB] Getting install status for io.appium.uiautomator2.server 2019-09-03 14:36:21:810 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package io.appium.uiautomator2.server' 2019-09-03 14:36:21:879 [ADB] 'io.appium.uiautomator2.server' is installed 2019-09-03 14:36:21:879 [ADB] Getting package info for 'io.appium.uiautomator2.server' 2019-09-03 14:36:21:880 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package io.appium.uiautomator2.server' 2019-09-03 14:36:21:947 [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('3.7.0' >= '3.7.0') 2019-09-03 14:36:21:948 [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled 2019-09-03 14:36:21:948 [ADB] Checking app cert for /usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk 2019-09-03 14:36:21:949 [ADB] Using 'apksigner' from '/usr/local/share/android-sdk/build-tools/29.0.1/apksigner' 2019-09-03 14:36:21:950 [ADB] Starting '/usr/local/share/android-sdk/build-tools/29.0.1/apksigner' with args '["verify","--print-certs","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk"]' 2019-09-03 14:36:22:408 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US 2019-09-03 14:36:22:409 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2019-09-03 14:36:22:409 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2019-09-03 14:36:22:409 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2019-09-03 14:36:22:409 [ADB] 2019-09-03 14:36:22:410 [ADB] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' is already signed. 2019-09-03 14:36:22:410 [ADB] Getting install status for io.appium.uiautomator2.server.test 2019-09-03 14:36:22:410 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package io.appium.uiautomator2.server.test' 2019-09-03 14:36:22:478 [ADB] 'io.appium.uiautomator2.server.test' is installed 2019-09-03 14:36:22:478 [ADB] Checking app cert for /usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk 2019-09-03 14:36:22:479 [ADB] Starting '/usr/local/share/android-sdk/build-tools/29.0.1/apksigner' with args '["verify","--print-certs","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]' 2019-09-03 14:36:22:826 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US 2019-09-03 14:36:22:826 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2019-09-03 14:36:22:826 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2019-09-03 14:36:22:827 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2019-09-03 14:36:22:827 [ADB] 2019-09-03 14:36:22:827 [ADB] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already signed. 2019-09-03 14:36:22:827 [UiAutomator2] Server packages are not going to be (re)installed 2019-09-03 14:36:22:828 [UiAutomator2] Waiting up to 30000ms for services to be available 2019-09-03 14:36:22:828 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pm list instrumentation' 2019-09-03 14:36:22:953 [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available 2019-09-03 14:36:22:953 [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8203 2019-09-03 14:36:22:954 [ADB] Forwarding system: 8203 to device: 6790 2019-09-03 14:36:22:955 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s forward tcp\:8203 tcp\:6790' 2019-09-03 14:36:22:963 [ADB] Uninstalling com.company.app 2019-09-03 14:36:22:963 [ADB] Getting install status for com.company.app 2019-09-03 14:36:22:964 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.company.app' 2019-09-03 14:36:23:097 [ADB] 'com.company.app' is installed 2019-09-03 14:36:23:098 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell am force-stop com.company.app' 2019-09-03 14:36:23:270 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s uninstall com.company.app' 2019-09-03 14:36:23:739 [ADB] 'adb uninstall com.company.app' command output: Success 2019-09-03 14:36:23:740 [ADB] com.company.app was successfully uninstalled 2019-09-03 14:36:23:741 [ADB] Checking app cert for //Users/berg/App/Release_2.1.0/com.company.app.apk 2019-09-03 14:36:23:741 [ADB] Starting '/usr/local/share/android-sdk/build-tools/29.0.1/apksigner' with args '["verify","--print-certs","//Users/berg/App/Release_2.1.0/com.company.app.apk"]' 2019-09-03 14:36:28:711 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US 2019-09-03 14:36:28:711 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2019-09-03 14:36:28:711 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2019-09-03 14:36:28:711 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2019-09-03 14:36:28:712 [ADB] 2019-09-03 14:36:28:712 [ADB] '//Users/berg/App/Release_2.1.0/com.company.app.apk' is already signed. 2019-09-03 14:36:28:712 [ADB] Getting install status for com.company.app 2019-09-03 14:36:28:713 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.company.app' 2019-09-03 14:36:28:774 [ADB] 'com.company.app' is not installed 2019-09-03 14:36:28:774 [AndroidDriver] Running full reset on 'com.company.app' (reinstall) 2019-09-03 14:36:29:074 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_'' 2019-09-03 14:36:29:132 [ADB] The count of applications in the cache: 10 2019-09-03 14:36:29:132 [ADB] The application at '//Users/berg/App/Release_2.1.0/com.company.app.apk' is already cached to '/data/local/tmp/appium_cache/e4d73473bf16fc52d1284d5af43c8f8fc69e95b1.apk' 2019-09-03 14:36:29:134 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pm install -r /data/local/tmp/appium_cache/e4d73473bf16fc52d1284d5af43c8f8fc69e95b1.apk' 2019-09-03 14:36:33:121 [ADB] The installation of 'com.company.app.apk' took 3.987s 2019-09-03 14:36:33:122 [ADB] Install command stdout: Success 2019-09-03 14:36:33:123 [UiAutomator2] Performing shallow cleanup of automation leftovers 2019-09-03 14:36:33:165 [UiAutomator2] The following obsolete sessions are still running: ["7b482aae-a71e-4ffa-9c43-d74eeb9191c7"] 2019-09-03 14:36:33:165 [UiAutomator2] Cleaning up the obsolete sessions 2019-09-03 14:36:34:203 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell am force-stop io.appium.uiautomator2.server.test' 2019-09-03 14:36:34:293 [UiAutomator2] Starting UIAutomator2 server 3.7.0 2019-09-03 14:36:34:294 [UiAutomator2] Using UIAutomator2 server from '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' and test from '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' 2019-09-03 14:36:34:294 [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online... 2019-09-03 14:36:34:295 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"] 2019-09-03 14:36:35:303 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-03 14:36:35:305 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8203/wd/hub/status] with no body 2019-09-03 14:36:35:313 [WD Proxy] Got an unexpected response: {"code":"ECONNRESET"} 2019-09-03 14:36:35:349 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server: 2019-09-03 14:36:36:320 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-03 14:36:36:321 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8203/wd/hub/status] with no body 2019-09-03 14:36:36:331 [WD Proxy] Got an unexpected response: {"code":"ECONNRESET"} 2019-09-03 14:36:37:334 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-03 14:36:37:335 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8203/wd/hub/status] with no body 2019-09-03 14:36:37:443 [WD Proxy] Got response with status 200: "{\"sessionId\":\"None\",\"status\":0,\"value\":\"Status Invoked\"}" 2019-09-03 14:36:37:445 [WD Proxy] Determined that the downstream protocol for proxy is MJSONWP 2019-09-03 14:36:37:445 [UiAutomator2] The initialization of the instrumentation process took 3152ms 2019-09-03 14:36:37:446 [WD Proxy] Matched '/session' to command name 'createSession' 2019-09-03 14:36:37:447 [WD Proxy] Proxying [POST /session] to [POST http://localhost:8203/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"GalaxyS9+","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9.0.0","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer"},"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9.0.0","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer","deviceUDID":"","appPackage":"com.company.app... 2019-09-03 14:36:37:517 [WD Proxy] Got response with status 200: {"sessionId":"d725266e-9d96-4491-b6d1-ef3b6d7e3910","status":0,"value":"Created Session"} 2019-09-03 14:36:37:527 [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/appium/device/info] with no body 2019-09-03 14:36:37:600 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"androidId\":\"\",\"manufacturer\":\"samsung\",\"model\":\"SM-G965U1\",\"brand\":\"samsung\",\"apiVersion\":\"28\",\"platformVersion\":\"9\",\"carrierName\":\"\",\"realDisplaySize\":\"1080x2220\",\"displayDensity\":420,\"networks\":[{\"type\":13,\"typeName\":\"WIFI_P2P\",\"subtype\":0,\"subtypeName\":\"\",\"isConnected\":false,\"detailedState\":\"IDLE\",\"state\":\"UNKNOWN\",\"extraInfo\":null,\"isAvailable\":true,\"isFailover\":false,\"isRoaming\":false,\"capabilities\":{\"transportTypes\":\"NET_CAPABILITY_SUPL\",\"networkCapabilities\":\"\",\"linkUpstreamBandwidthKbps\":1048576,\"linkDownBandwidthKbps\":1048576,\"signalStrength\":-2147483648,\"networkSpecifier\":null,\"SSID\":null}},{\"type\":1,\"typeName\":\"WIFI\",\"subtype\":0,\"subtypeName\":\"\",\"isConnected\":true,\"detailedState\":\"CONNECTED\",\"state\":\"CONNECTED\",\"extraInfo\":null,\"isAvailable\":true,\"isFailover\":false,\"isRoaming\":false,\"... 2019-09-03 14:36:37:602 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window' 2019-09-03 14:36:37:731 [AndroidDriver] Screen already unlocked, doing nothing 2019-09-03 14:36:37:732 [UiAutomator2] UiAutomator2 did not start the activity we were waiting for, 'com.company.app/md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity'. Starting it ourselves 2019-09-03 14:36:37:733 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell am start -W -n com.company.app/md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000' 2019-09-03 14:36:43:283 [ADB] Waiting up to 20000ms for activity matching pkg: 'com.company.app' and activity: 'md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity' to be focused 2019-09-03 14:36:43:284 [ADB] Possible activities, to be checked: 'md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity', 'com.company.app.md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity' 2019-09-03 14:36:43:285 [ADB] Getting focused package and activity 2019-09-03 14:36:43:286 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:43:420 [ADB] Found package: 'com.company.app' and fully qualified activity name : 'md5a517e1cb56b47aead83ad1a63cc948f3.MainActivity' 2019-09-03 14:36:43:429 [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/appium/device/pixel_ratio] with body: {} 2019-09-03 14:36:43:473 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":2.625}" 2019-09-03 14:36:43:475 [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars' 2019-09-03 14:36:43:475 [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/appium/device/system_bars] with body: {} 2019-09-03 14:36:43:502 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"statusBar\":63}}" 2019-09-03 14:36:43:504 [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize' 2019-09-03 14:36:43:505 [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/window/current/size] with body: {} 2019-09-03 14:36:43:545 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"height\":2094,\"width\":1080}}" 2019-09-03 14:36:43:547 [Appium] New AndroidUiautomator2Driver session created successfully, session bb5683b0-c044-42ff-8921-9dcab1d85be0 added to master session list 2019-09-03 14:36:43:549 [BaseDriver] Event 'newSessionStarted' logged at 1567521403549 (09:36:43 GMT-0500 (Central Daylight Time)) 2019-09-03 14:36:43:551 [W3C (bb5683b0)] Cached the protocol value 'W3C' for the new session bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:43:552 [W3C (bb5683b0)] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"GalaxyS9+","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9.0.0","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer"},"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer","deviceUDID":"","appPackage":"com.company.app","devic... 2019-09-03 14:36:43:556 [HTTP] <-- POST /wd/hub/session 200 23889 ms - 1311 2019-09-03 14:36:43:556 [HTTP] 2019-09-03 14:36:43:642 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:43:642 [HTTP] {} 2019-09-03 14:36:43:644 [W3C (bb5683b0)] Calling AppiumDriver.getSession() with args: ["bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:43:683 [UiAutomator2] Getting session details from server to mix in 2019-09-03 14:36:43:683 [WD Proxy] Matched '/' to command name 'getSession' 2019-09-03 14:36:43:684 [WD Proxy] Proxying [GET /] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910] with body: {} 2019-09-03 14:36:43:721 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{}}" 2019-09-03 14:36:43:724 [W3C (bb5683b0)] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"GalaxyS9+","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9.0.0","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer"},"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer","deviceUDID":"","appPackage":"com.company.app","deviceApiLevel":28,"d... 2019-09-03 14:36:43:726 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0 200 83 ms - 1243 2019-09-03 14:36:43:727 [HTTP] 2019-09-03 14:36:43:761 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:43:762 [HTTP] {} 2019-09-03 14:36:43:762 [W3C (bb5683b0)] Calling AppiumDriver.getSession() with args: ["bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:43:764 [UiAutomator2] Getting session details from server to mix in 2019-09-03 14:36:43:765 [WD Proxy] Matched '/' to command name 'getSession' 2019-09-03 14:36:43:765 [WD Proxy] Proxying [GET /] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910] with body: {} 2019-09-03 14:36:43:817 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{}}" 2019-09-03 14:36:43:819 [W3C (bb5683b0)] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"GalaxyS9+","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9.0.0","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer"},"platformName":"android","app":"//Users/berg/App/Release_2.1.0/com.company.app.apk","automationName":"UIAutomator2","deviceName":"","fullReset":true,"language":"en","locale":"US","newCommandTimeout":300000,"noReset":false,"platformVersion":"9","udid":"","xcodeOrgId":"","xcodeSigningId":"iPhone Developer","deviceUDID":"","appPackage":"com.company.app","deviceApiLevel":28,"d... 2019-09-03 14:36:43:822 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0 200 59 ms - 1243 2019-09-03 14:36:43:822 [HTTP] 2019-09-03 14:36:43:925 [HTTP] --> POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/execute/sync 2019-09-03 14:36:43:926 [HTTP] {"script":"mobile: startLogsBroadcast","args":[]} 2019-09-03 14:36:43:933 [W3C (bb5683b0)] Calling AppiumDriver.execute() with args: ["mobile: startLogsBroadcast",[],"bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:43:934 [AndroidDriver] Executing native command 'mobile: startLogsBroadcast' 2019-09-03 14:36:43:935 [AndroidDriver] Assigning logcat broadcasting web socket server to /ws/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/appium/device/logcat 2019-09-03 14:36:43:935 [W3C (bb5683b0)] Responding to client with driver.execute() result: null 2019-09-03 14:36:43:936 [HTTP] <-- POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/execute/sync 200 11 ms - 14 2019-09-03 14:36:43:936 [HTTP] 2019-09-03 14:36:43:966 [AndroidDriver] Established a new logcat listener web socket connection from 10.44.120.158 2019-09-03 14:36:44:410 [HTTP] --> POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element 2019-09-03 14:36:44:411 [HTTP] {"using":"accessibility id","value":"button_skip"} 2019-09-03 14:36:44:412 [W3C (bb5683b0)] Calling AppiumDriver.findElement() with args: ["accessibility id","button_skip","bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:44:414 [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator 2019-09-03 14:36:44:415 [BaseDriver] Waiting up to 0 ms for condition 2019-09-03 14:36:44:416 [WD Proxy] Matched '/element' to command name 'findElement' 2019-09-03 14:36:44:416 [WD Proxy] Proxying [POST /element] to [POST http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/element] with body: {"strategy":"accessibility id","selector":"button_skip","context":"","multiple":false} 2019-09-03 14:36:44:773 [WD Proxy] Got response with status 200: {"sessionId":"d725266e-9d96-4491-b6d1-ef3b6d7e3910","status":0,"value":{"ELEMENT":"cf943328-237d-4160-9b68-dd3d450199a3"}} 2019-09-03 14:36:44:774 [W3C (bb5683b0)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"cf943328-237d-4160-9b68-dd3d450199a3","ELEMENT":"cf943328-237d-4160-9b68-dd3d450199a3"} 2019-09-03 14:36:44:775 [HTTP] <-- POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element 200 365 ms - 137 2019-09-03 14:36:44:776 [HTTP] 2019-09-03 14:36:44:819 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location 2019-09-03 14:36:44:820 [HTTP] {} 2019-09-03 14:36:44:821 [W3C (bb5683b0)] Driver proxy active, passing request on via HTTP proxy 2019-09-03 14:36:44:822 [WD Proxy] Matched '/wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location' to command name 'getLocation' 2019-09-03 14:36:44:822 [WD Proxy] Proxying [GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/element/cf943328-237d-4160-9b68-dd3d450199a3/location] with body: {} 2019-09-03 14:36:44:883 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"x\":68,\"y\":1902}}" 2019-09-03 14:36:44:885 [WD Proxy] Replacing sessionId d725266e-9d96-4491-b6d1-ef3b6d7e3910 with bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:44:886 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location 200 66 ms - 89 2019-09-03 14:36:44:886 [HTTP] 2019-09-03 14:36:44:893 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location 2019-09-03 14:36:44:894 [HTTP] {} 2019-09-03 14:36:44:895 [W3C (bb5683b0)] Driver proxy active, passing request on via HTTP proxy 2019-09-03 14:36:44:897 [WD Proxy] Matched '/wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location' to command name 'getLocation' 2019-09-03 14:36:44:898 [WD Proxy] Proxying [GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/element/cf943328-237d-4160-9b68-dd3d450199a3/location] with body: {} 2019-09-03 14:36:44:960 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"x\":68,\"y\":1902}}" 2019-09-03 14:36:44:960 [WD Proxy] Replacing sessionId d725266e-9d96-4491-b6d1-ef3b6d7e3910 with bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:44:962 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/location 200 68 ms - 89 2019-09-03 14:36:44:962 [HTTP] 2019-09-03 14:36:44:996 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size 2019-09-03 14:36:44:997 [HTTP] {} 2019-09-03 14:36:45:001 [W3C (bb5683b0)] Driver proxy active, passing request on via HTTP proxy 2019-09-03 14:36:45:002 [WD Proxy] Matched '/wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size' to command name 'getSize' 2019-09-03 14:36:45:003 [WD Proxy] Proxying [GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/element/cf943328-237d-4160-9b68-dd3d450199a3/size] with body: {} 2019-09-03 14:36:45:060 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"width\":944,\"height\":126}}" 2019-09-03 14:36:45:061 [WD Proxy] Replacing sessionId d725266e-9d96-4491-b6d1-ef3b6d7e3910 with bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:45:061 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size 200 65 ms - 98 2019-09-03 14:36:45:061 [HTTP] 2019-09-03 14:36:45:077 [HTTP] --> GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size 2019-09-03 14:36:45:077 [HTTP] {} 2019-09-03 14:36:45:078 [W3C (bb5683b0)] Driver proxy active, passing request on via HTTP proxy 2019-09-03 14:36:45:080 [WD Proxy] Matched '/wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size' to command name 'getSize' 2019-09-03 14:36:45:081 [WD Proxy] Proxying [GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size] to [GET http://localhost:8203/wd/hub/session/d725266e-9d96-4491-b6d1-ef3b6d7e3910/element/cf943328-237d-4160-9b68-dd3d450199a3/size] with body: {} 2019-09-03 14:36:45:132 [WD Proxy] Got response with status 200: "{\"sessionId\":\"d725266e-9d96-4491-b6d1-ef3b6d7e3910\",\"status\":0,\"value\":{\"width\":944,\"height\":126}}" 2019-09-03 14:36:45:133 [WD Proxy] Replacing sessionId d725266e-9d96-4491-b6d1-ef3b6d7e3910 with bb5683b0-c044-42ff-8921-9dcab1d85be0 2019-09-03 14:36:45:134 [HTTP] <-- GET /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/element/cf943328-237d-4160-9b68-dd3d450199a3/size 200 57 ms - 98 2019-09-03 14:36:45:134 [HTTP] 2019-09-03 14:36:45:204 [HTTP] --> POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/appium/device/activate_app 2019-09-03 14:36:45:204 [HTTP] {"bundleId":"com.android.settings"} 2019-09-03 14:36:45:206 [W3C (bb5683b0)] Calling AppiumDriver.activateApp() with args: ["com.android.settings",null,null,null,"bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:45:207 [AndroidDriver] Activating 'com.android.settings' with 'adb shell monkey -p com.android.settings -c android.intent.category.LAUNCHER 1' command 2019-09-03 14:36:45:208 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell monkey -p com.android.settings -c android.intent.category.LAUNCHER 1' 2019-09-03 14:36:45:697 [AndroidDriver] Command stdout: bash arg: -p 2019-09-03 14:36:45:698 [AndroidDriver] bash arg: com.android.settings 2019-09-03 14:36:45:698 [AndroidDriver] bash arg: -c 2019-09-03 14:36:45:699 [AndroidDriver] bash arg: android.intent.category.LAUNCHER 2019-09-03 14:36:45:699 [AndroidDriver] bash arg: 1 2019-09-03 14:36:45:699 [AndroidDriver] Events injected: 1 2019-09-03 14:36:45:700 [AndroidDriver] ## Network stats: elapsed time=31ms (0ms mobile, 0ms wifi, 31ms not connected) 2019-09-03 14:36:45:702 [W3C (bb5683b0)] Responding to client with driver.activateApp() result: null 2019-09-03 14:36:45:703 [HTTP] <-- POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/appium/device/activate_app 200 499 ms - 14 2019-09-03 14:36:45:704 [HTTP] 2019-09-03 14:36:48:816 [HTTP] --> POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/appium/device/terminate_app 2019-09-03 14:36:48:816 [HTTP] {"bundleId":"com.android.settings","options":{"timeout":10000}} 2019-09-03 14:36:48:818 [W3C (bb5683b0)] Calling AppiumDriver.terminateApp() with args: ["com.android.settings",{"timeout":10000},null,null,"bb5683b0-c044-42ff-8921-9dcab1d85be0"] 2019-09-03 14:36:48:819 [AndroidDriver] Terminating 'com.android.settings' 2019-09-03 14:36:48:820 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:48:821 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:48:936 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell am force-stop com.android.settings' 2019-09-03 14:36:49:089 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:49:089 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:49:090 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:49:169 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:49:169 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:49:170 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:49:250 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:49:425 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:49:426 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:49:426 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:49:502 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:49:503 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:49:504 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:49:599 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:49:847 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:49:847 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:49:848 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:49:969 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:49:970 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:49:971 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:50:086 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:50:326 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:50:326 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:50:327 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:50:447 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:50:447 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:50:448 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:50:568 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:50:806 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:50:807 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:50:807 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:50:938 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:50:938 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:50:939 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:51:055 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:51:251 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:51:252 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:51:253 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:51:353 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:51:354 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:51:354 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:51:467 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:51:676 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:51:677 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:51:678 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:51:767 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:51:767 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:51:768 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:51:890 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:52:091 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:52:092 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:52:093 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:52:210 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:52:210 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:52:211 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:52:336 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:52:602 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:52:602 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:52:603 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:52:742 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:52:743 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:52:743 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:52:856 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:53:125 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:53:126 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:53:126 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:53:291 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:53:292 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:53:292 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:53:410 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:53:691 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:53:692 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:53:692 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:53:820 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:53:820 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:53:821 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:53:917 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:54:166 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:54:167 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:54:168 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:54:273 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:54:273 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:54:274 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:54:388 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:54:674 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:54:675 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:54:675 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:54:830 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:54:831 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:54:831 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:54:973 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:55:249 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:55:249 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:55:250 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:55:395 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:55:395 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:55:396 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:55:486 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:55:699 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:55:700 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:55:701 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:55:793 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:55:794 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:55:794 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:55:918 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:56:116 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:56:117 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:56:118 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:56:276 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:56:276 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:56:277 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:56:389 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:56:666 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:56:667 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:56:668 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:56:800 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:56:801 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:56:802 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:56:913 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:57:114 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:57:114 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:57:115 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:57:209 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:57:210 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:57:210 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:57:352 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:57:560 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:57:561 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:57:562 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:57:673 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:57:673 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:57:673 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:57:789 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:57:994 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:57:995 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:57:995 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:58:113 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:58:114 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:58:114 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:58:247 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:58:461 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:58:462 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:58:463 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:58:579 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:58:579 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:58:580 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:58:705 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:58:909 [AndroidDriver] Querying the state of 'com.android.settings' 2019-09-03 14:36:58:913 [ADB] Getting install status for com.android.settings 2019-09-03 14:36:58:914 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys package com.android.settings' 2019-09-03 14:36:59:032 [ADB] 'com.android.settings' is installed 2019-09-03 14:36:59:033 [ADB] Getting IDs of all 'com.android.settings' processes 2019-09-03 14:36:59:033 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell pgrep -f com\\.android\\.settings' 2019-09-03 14:36:59:161 [ADB] Running '/usr/local/share/android-sdk/platform-tools/adb -P 5037 -s shell dumpsys window windows' 2019-09-03 14:36:59:270 [AndroidDriver] Error: 'com.android.settings' is still running after 10000ms timeout 2019-09-03 14:36:59:270 [AndroidDriver] at Object.wrappedLogger.errorAndThrow (/usr/local/lib/node_modules/appium/node_modules/appium-support/lib/logging.js:78:13) 2019-09-03 14:36:59:270 [AndroidDriver] at AndroidUiautomator2Driver.errorAndThrow [as terminateApp] (/usr/local/lib/node_modules/appium/node_modules/appium-android-driver/lib/commands/app-management.js:125:9) 2019-09-03 14:36:59:271 [W3C (bb5683b0)] Encountered internal error running command: Error: 'com.android.settings' is still running after 10000ms timeout 2019-09-03 14:36:59:271 [W3C (bb5683b0)] at Object.wrappedLogger.errorAndThrow (/usr/local/lib/node_modules/appium/node_modules/appium-support/lib/logging.js:78:13) 2019-09-03 14:36:59:271 [W3C (bb5683b0)] at AndroidUiautomator2Driver.errorAndThrow [as terminateApp] (/usr/local/lib/node_modules/appium/node_modules/appium-android-driver/lib/commands/app-management.js:125:9) 2019-09-03 14:36:59:277 [HTTP] <-- POST /wd/hub/session/bb5683b0-c044-42ff-8921-9dcab1d85be0/appium/device/terminate_app 500 10461 ms - 619 2019-09-03 14:36:59:277 [HTTP] 2019-09-03 14:37:21:029 [AndroidDriver] Logcat listener web socket is closed.