[GH-ISSUE #197] Chrome launch with data:, #140

Open
opened 2026-03-01 15:40:51 +03:00 by kerem · 4 comments
Owner

Originally created by @ahafid01 on GitHub (Sep 4, 2019).
Original GitHub issue: https://github.com/budtmo/docker-android/issues/197

🐛 Bug Report

Operating System:
Ubuntu 18.04

Docker Image:
budtmo/docker-android-x86-9.0

Docker Version:
18.09.7

Docker-compose version (Only if you use it):
1.24.1

When I run tests (java) on chrome, I have "data:," on browser instead of my website.

docker-appium

Originally created by @ahafid01 on GitHub (Sep 4, 2019). Original GitHub issue: https://github.com/budtmo/docker-android/issues/197 ## 🐛 Bug Report Operating System: Ubuntu 18.04 Docker Image: budtmo/docker-android-x86-9.0 Docker Version: 18.09.7 Docker-compose version (Only if you use it): 1.24.1 When I run tests (java) on chrome, I have "data:," on browser instead of my website. ![docker-appium](https://user-images.githubusercontent.com/41122764/64272656-18a78880-cf40-11e9-9f3e-23269eba8cf7.png)
Author
Owner

@budtmo commented on GitHub (Sep 11, 2019):

Hi @ahafid01 ,

could you maybe share your test code?

<!-- gh-comment-id:530319827 --> @budtmo commented on GitHub (Sep 11, 2019): Hi @ahafid01 , could you maybe share your test code?
Author
Owner

@ahafid01 commented on GitHub (Sep 11, 2019):

When I use a non-docker appium, It work fine.

DesiredCapabilities caps = new DesiredCapabilities();
caps.setCapability(MobileCapabilityType.PLATFORM_NAME, MobilePlatform.ANDROID);
caps.setCapability(MobileCapabilityType.DEVICE_NAME,"Android Emulator");
caps.setCapability(MobileCapabilityType.BROWSER_NAME,"chrome");
driver = new AppiumDriver(new URL(REMOTE_IP:PORT), caps);
driver.get(url);
<!-- gh-comment-id:530581664 --> @ahafid01 commented on GitHub (Sep 11, 2019): When I use a non-docker appium, It work fine. ``` DesiredCapabilities caps = new DesiredCapabilities(); caps.setCapability(MobileCapabilityType.PLATFORM_NAME, MobilePlatform.ANDROID); caps.setCapability(MobileCapabilityType.DEVICE_NAME,"Android Emulator"); caps.setCapability(MobileCapabilityType.BROWSER_NAME,"chrome"); driver = new AppiumDriver(new URL(REMOTE_IP:PORT), caps); driver.get(url); ```
Author
Owner

@mikk150 commented on GitHub (Sep 24, 2019):

Same issue, but with budtmo/docker-android-x86-8.1

<!-- gh-comment-id:534592862 --> @mikk150 commented on GitHub (Sep 24, 2019): Same issue, but with budtmo/docker-android-x86-8.1
Author
Owner

@mikk150 commented on GitHub (Sep 24, 2019):

2019-09-24 17:15:57:214 [Appium] Welcome to Appium v1.14.0
2019-09-24 17:15:57:215 [Appium] Non-default server args:
2019-09-24 17:15:57:215 [Appium]   logFile: /var/log/supervisor/appium.log
2019-09-24 17:15:57:215 [Appium]   nodeconfig: /root/src/nodeconfig.json
2019-09-24 17:15:57:216 [Appium]   chromedriverExecutable: /root/chromedriver
2019-09-24 17:15:57:236 [Appium] Starting auto register thread for grid. Will try to register every 5000 ms.
2019-09-24 17:15:57:236 [Appium] Appium REST http interface listener started on 0.0.0.0:4723
2019-09-24 17:16:01:702 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:01:702 [HTTP] {}
2019-09-24 17:16:01:704 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:01:705 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:01:710 [HTTP] <-- GET /wd/hub/status 200 5 ms - 68
2019-09-24 17:16:01:710 [HTTP] 
2019-09-24 17:16:02:250 [Appium] Appium successfully registered with the grid on http://selenium:4444
2019-09-24 17:16:07:252 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:07:253 [HTTP] {}
2019-09-24 17:16:07:253 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:07:253 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:07:255 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68
2019-09-24 17:16:07:256 [HTTP] 
2019-09-24 17:16:12:257 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:12:258 [HTTP] {}
2019-09-24 17:16:12:258 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:12:258 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:12:259 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68
2019-09-24 17:16:12:259 [HTTP] 
2019-09-24 17:16:17:262 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:17:262 [HTTP] {}
2019-09-24 17:16:17:263 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:17:263 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:17:264 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:17:264 [HTTP] 
2019-09-24 17:16:22:266 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:22:266 [HTTP] {}
2019-09-24 17:16:22:266 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:22:267 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:22:268 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68
2019-09-24 17:16:22:268 [HTTP] 
2019-09-24 17:16:27:270 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:27:270 [HTTP] {}
2019-09-24 17:16:27:270 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:27:271 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:27:271 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:27:271 [HTTP] 
2019-09-24 17:16:28:694 [HTTP] --> POST /wd/hub/session
2019-09-24 17:16:28:694 [HTTP] {"desiredCapabilities":{"browserName":"chrome","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","platformName":"android","deviceName":"nexus_5_8.1"},"capabilities":{"firstMatch":[{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d"}]}}
2019-09-24 17:16:28:694 [W3C] Calling AppiumDriver.createSession() with args: [{"browserName":"chrome","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","platformName":"android","deviceName":"nexus_5_8.1"},null,{"firstMatch":[{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d"}]}]
2019-09-24 17:16:28:694 [BaseDriver] Event 'newSessionRequested' logged at 1569345388694 (10:16:28 GMT-0700 (Pacific Daylight Time))
2019-09-24 17:16:28:698 [Appium] Could not parse W3C capabilities: 'deviceName' can't be blank
2019-09-24 17:16:28:699 [Appium] Trying to fix W3C capabilities by merging them with JSONWP caps
2019-09-24 17:16:28:699 [BaseDriver] The capabilities ["deviceName"] are not standard capabilities and should have an extension prefix
2019-09-24 17:16:28:700 [Appium] 
2019-09-24 17:16:28:700 [Appium] ======================================================================
2019-09-24 17:16:28:700 [Appium]   DEPRECATION WARNING:
2019-09-24 17:16:28:700 [Appium] 
2019-09-24 17:16:28:700 [Appium]   The 'automationName' capability was not provided in the desired 
2019-09-24 17:16:28:700 [Appium]   capabilities for this Android session
2019-09-24 17:16:28:701 [Appium] 
2019-09-24 17:16:28:701 [Appium]   Setting 'automationName=UiAutomator2' by default and using the 
2019-09-24 17:16:28:701 [Appium]   UiAutomator2 Driver
2019-09-24 17:16:28:701 [Appium] 
2019-09-24 17:16:28:701 [Appium]   The next major version of Appium (2.x) will **require** the 
2019-09-24 17:16:28:701 [Appium]   'automationName' capability to be set for all sessions on all 
2019-09-24 17:16:28:701 [Appium]   platforms
2019-09-24 17:16:28:701 [Appium] 
2019-09-24 17:16:28:701 [Appium]   In previous versions (Appium <= 1.13.x), the default was 
2019-09-24 17:16:28:701 [Appium]   'automationName=UiAutomator1'
2019-09-24 17:16:28:701 [Appium] 
2019-09-24 17:16:28:701 [Appium]   If you wish to use that automation instead of UiAutomator2, please 
2019-09-24 17:16:28:702 [Appium]   add 'automationName=UiAutomator1' to your desired capabilities
2019-09-24 17:16:28:702 [Appium] 
2019-09-24 17:16:28:702 [Appium]   For more information about drivers, please visit 
2019-09-24 17:16:28:702 [Appium]   http://appium.io/docs/en/about-appium/intro/ and explore the 
2019-09-24 17:16:28:702 [Appium]   'Drivers' menu
2019-09-24 17:16:28:702 [Appium] 
2019-09-24 17:16:28:702 [Appium] ======================================================================
2019-09-24 17:16:28:702 [Appium] 
2019-09-24 17:16:28:770 [Appium] Appium v1.14.0 creating new AndroidUiautomator2Driver (v1.33.1) session
2019-09-24 17:16:28:770 [Appium] Capabilities:
2019-09-24 17:16:28:770 [Appium]   deviceName: nexus_5_8.1
2019-09-24 17:16:28:770 [Appium]   browserName: chrome
2019-09-24 17:16:28:770 [Appium]   platformName: android
2019-09-24 17:16:28:770 [Appium]   server:CONFIG_UUID: dc2e971b-d6ef-4501-8f8c-8167437bbe8d
2019-09-24 17:16:28:772 [BaseDriver] W3C capabilities {"alwaysMatch":{"appium:dev... and MJSONWP desired capabilities {"browserName":"chrome","CO... were provided
2019-09-24 17:16:28:772 [BaseDriver] Creating session with W3C capabilities: {"alwaysMatch":{"appium:dev...
2019-09-24 17:16:28:776 [BaseDriver] The following capabilities were provided, but are not recognized by Appium:
2019-09-24 17:16:28:776 [BaseDriver]   server:CONFIG_UUID
2019-09-24 17:16:28:776 [BaseDriver] Session created with session id: 067869b4-113f-48c6-a7f0-136b0524222d
2019-09-24 17:16:28:777 [UiAutomator2] We're going to run a Chrome-based session
2019-09-24 17:16:28:777 [UiAutomator2] Chrome-type package and activity are com.android.chrome and com.google.android.apps.chrome.Main
2019-09-24 17:16:28:799 [ADB] Found 1 'build-tools' folders under '/root' (newest first):
2019-09-24 17:16:28:800 [ADB]     /root/build-tools/29.0.0
2019-09-24 17:16:28:800 [ADB] Using 'adb' from '/root/platform-tools/adb'
2019-09-24 17:16:28:801 [AndroidDriver] Retrieving device list
2019-09-24 17:16:28:801 [ADB] Trying to find a connected android device
2019-09-24 17:16:28:801 [ADB] Getting connected devices...
2019-09-24 17:16:28:811 [ADB] 1 device(s) connected
2019-09-24 17:16:28:812 [AndroidDriver] Using device: emulator-5554
2019-09-24 17:16:28:812 [ADB] Using 'adb' from '/root/platform-tools/adb'
2019-09-24 17:16:28:813 [ADB] Setting device id to emulator-5554
2019-09-24 17:16:28:814 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk'
2019-09-24 17:16:28:825 [ADB] Current device property 'ro.build.version.sdk': 27
2019-09-24 17:16:28:825 [ADB] Device API level: 27
2019-09-24 17:16:28:826 [AndroidDriver] No app sent in, not parsing package/activity
2019-09-24 17:16:28:827 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 wait-for-device'
2019-09-24 17:16:28:832 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell echo ping'
2019-09-24 17:16:28:840 [AndroidDriver] Pushing settings apk to device...
2019-09-24 17:16:28:841 [ADB] Getting install status for io.appium.settings
2019-09-24 17:16:28:841 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'
2019-09-24 17:16:28:867 [ADB] 'io.appium.settings' is not installed
2019-09-24 17:16:28:867 [ADB] App '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' is not installed
2019-09-24 17:16:28:867 [ADB] Installing '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk'
2019-09-24 17:16:28:872 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
2019-09-24 17:16:28:893 [ADB] Got an error 'ls: /data/local/tmp/appium_cache: No such file or directory' while getting the list of files in the cache. Assuming the cache does not exist yet
2019-09-24 17:16:28:894 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache'
2019-09-24 17:16:28:908 [ADB] The count of applications in the cache: 0
2019-09-24 17:16:28:908 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' to '/data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk'
2019-09-24 17:16:28:909 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache'
2019-09-24 17:16:28:925 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk /data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk'
2019-09-24 17:16:28:945 [ADB] The upload of 'settings_apk-debug.apk' (1.16 MB) took 0.036s
2019-09-24 17:16:28:947 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install -g /data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk'
2019-09-24 17:16:29:583 [ADB] The installation of 'settings_apk-debug.apk' took 0.637s
2019-09-24 17:16:29:584 [ADB] Install command stdout: Success
2019-09-24 17:16:29:584 [ADB] Getting IDs of all 'io.appium.settings' processes
2019-09-24 17:16:29:585 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'pgrep --help; echo $?''
2019-09-24 17:16:29:615 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings'
2019-09-24 17:16:29:657 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am start -n io.appium.settings/.Settings -a android.intent.action.MAIN -c android.intent.category.LAUNCHER'
2019-09-24 17:16:29:751 [ADB] Getting IDs of all 'io.appium.settings' processes
2019-09-24 17:16:29:751 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings'
2019-09-24 17:16:30:089 [ADB] Getting IDs of all 'io.appium.settings' processes
2019-09-24 17:16:30:089 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings'
2019-09-24 17:16:30:110 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell appops set io.appium.settings android\:mock_location allow'
2019-09-24 17:16:30:128 [Logcat] Starting logcat capture
2019-09-24 17:16:30:155 [ADB] Getting install status for io.appium.uiautomator2.server
2019-09-24 17:16:30:155 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server'
2019-09-24 17:16:30:196 [ADB] 'io.appium.uiautomator2.server' is not installed
2019-09-24 17:16:30:196 [ADB] App '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' is not installed
2019-09-24 17:16:30:196 [UiAutomator2] io.appium.uiautomator2.server installation state: notInstalled
2019-09-24 17:16:30:196 [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk
2019-09-24 17:16:30:197 [ADB] Using 'apksigner' from '/root/build-tools/29.0.0/apksigner'
2019-09-24 17:16:30:198 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["verify","--print-certs","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk"]'
2019-09-24 17:16:30:482 [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-24 17:16:30:482 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2019-09-24 17:16:30:482 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2019-09-24 17:16:30:483 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2019-09-24 17:16:30:483 [ADB] 
2019-09-24 17:16:30:483 [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' is already signed.
2019-09-24 17:16:30:483 [ADB] Getting install status for io.appium.uiautomator2.server.test
2019-09-24 17:16:30:484 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server.test'
2019-09-24 17:16:30:501 [ADB] 'io.appium.uiautomator2.server.test' is not installed
2019-09-24 17:16:30:502 [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
2019-09-24 17:16:30:502 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["verify","--print-certs","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]'
2019-09-24 17:16:30:697 [ADB] apksigner stdout: Signer #1 certificate DN: C=US, O=Android, CN=Android Debug
2019-09-24 17:16:30:698 [ADB] Signer #1 certificate SHA-256 digest: c3362e88ec629255c69c5c07689b41862fa8a760e888ff664a2de1e92ecb2ad1
2019-09-24 17:16:30:698 [ADB] Signer #1 certificate SHA-1 digest: 2755ea34b8ed3a89f8a83e0c24f223ce6245bf58
2019-09-24 17:16:30:698 [ADB] Signer #1 certificate MD5 digest: 2a2c3f837793c0da3c6bc4e481cdcc16
2019-09-24 17:16:30:698 [ADB] 
2019-09-24 17:16:30:698 [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is signed with non-default certificate
2019-09-24 17:16:30:700 [ADB] Using 'zipalign' from '/root/build-tools/29.0.0/zipalign'
2019-09-24 17:16:30:706 [ADB] /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already zip-aligned. Doing nothing
2019-09-24 17:16:30:706 [ADB] Signing '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' with default cert
2019-09-24 17:16:30:707 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["sign","--key","/usr/lib/node_modules/appium/node_modules/appium-adb/keys/testkey.pk8","--cert","/usr/lib/node_modules/appium/node_modules/appium-adb/keys/testkey.x509.pem","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]'
2019-09-24 17:16:30:965 [UiAutomator2] Server packages are going to be (re)installed
2019-09-24 17:16:30:971 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
2019-09-24 17:16:30:986 [ADB] The count of applications in the cache: 1
2019-09-24 17:16:30:986 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' to '/data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk'
2019-09-24 17:16:30:986 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache'
2019-09-24 17:16:30:999 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk /data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk'
2019-09-24 17:16:31:026 [ADB] The upload of 'appium-uiautomator2-server-v3.7.0.apk' (2.14 MB) took 0.039s
2019-09-24 17:16:31:026 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install /data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk'
2019-09-24 17:16:31:740 [ADB] The installation of 'appium-uiautomator2-server-v3.7.0.apk' took 0.714s
2019-09-24 17:16:31:740 [ADB] Install command stdout: Success
2019-09-24 17:16:31:743 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
2019-09-24 17:16:31:783 [ADB] The count of applications in the cache: 2
2019-09-24 17:16:31:783 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' to '/data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk'
2019-09-24 17:16:31:783 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache'
2019-09-24 17:16:31:804 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk /data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk'
2019-09-24 17:16:31:812 [ADB] The upload of 'appium-uiautomator2-server-debug-androidTest.apk' (539.10 KB) took 0.028s
2019-09-24 17:16:31:812 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install /data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk'
2019-09-24 17:16:32:172 [ADB] The installation of 'appium-uiautomator2-server-debug-androidTest.apk' took 0.360s
2019-09-24 17:16:32:173 [ADB] Install command stdout: Success
2019-09-24 17:16:32:173 [UiAutomator2] Waiting up to 30000ms for services to be available
2019-09-24 17:16:32:174 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm list instrumentation'
2019-09-24 17:16:32:274 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:32:274 [HTTP] {}
2019-09-24 17:16:32:274 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:32:275 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:32:276 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:32:276 [HTTP] 
2019-09-24 17:16:32:370 [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available
2019-09-24 17:16:32:371 [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8200
2019-09-24 17:16:32:371 [ADB] Forwarding system: 8200 to device: 6790
2019-09-24 17:16:32:371 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward tcp\:8200 tcp\:6790'
2019-09-24 17:16:32:378 [UiAutomator2] No app capability. Assuming it is already on the device
2019-09-24 17:16:32:378 [ADB] Getting install status for com.android.chrome
2019-09-24 17:16:32:378 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package com.android.chrome'
2019-09-24 17:16:32:398 [ADB] 'com.android.chrome' is installed
2019-09-24 17:16:32:398 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.android.chrome'
2019-09-24 17:16:32:446 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm clear com.android.chrome'
2019-09-24 17:16:32:679 [AndroidDriver] Performed fast reset on the installed 'com.android.chrome' application (stop and clear)
2019-09-24 17:16:32:680 [UiAutomator2] Performing shallow cleanup of automation leftovers
2019-09-24 17:16:32:684 [UiAutomator2] No obsolete sessions have been detected (Error: socket hang up)
2019-09-24 17:16:32:684 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server.test'
2019-09-24 17:16:32:708 [UiAutomator2] Starting UIAutomator2 server 3.7.0
2019-09-24 17:16:32:708 [UiAutomator2] Using UIAutomator2 server from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' and test from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk'
2019-09-24 17:16:32:708 [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
2019-09-24 17:16:32:709 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
2019-09-24 17:16:33:116 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server:
2019-09-24 17:16:33:714 [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-24 17:16:33:715 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body
2019-09-24 17:16:33:718 [WD Proxy] Got an unexpected response: {"code":"ECONNRESET"}
2019-09-24 17:16:34:720 [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-24 17:16:34:720 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body
2019-09-24 17:16:34:754 [WD Proxy] Got response with status 200: "{\"sessionId\":\"None\",\"status\":0,\"value\":\"Status Invoked\"}"
2019-09-24 17:16:34:754 [WD Proxy] Determined that the downstream protocol for proxy is MJSONWP
2019-09-24 17:16:34:754 [UiAutomator2] The initialization of the instrumentation process took 2046ms
2019-09-24 17:16:34:755 [WD Proxy] Matched '/session' to command name 'createSession'
2019-09-24 17:16:34:755 [WD Proxy] Proxying [POST /session] to [POST http://localhost:8200/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"nexus_5_8.1"},"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"emulator-5554","appPackage":"com.android.chrome","appActivity":"com.google.android.apps.chrome.Main","deviceUDID":"emulator-5554"}}
2019-09-24 17:16:34:768 [WD Proxy] Got response with status 200: {"sessionId":"2db66a9c-6999-4e82-897f-6992edb4b82e","status":0,"value":"Created Session"}
2019-09-24 17:16:34:772 [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/info] with no body
2019-09-24 17:16:34:786 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"androidId\":\"7a153feeb2816a77\",\"manufacturer\":\"Google\",\"model\":\"Android SDK built for x86\",\"brand\":\"google\",\"apiVersion\":\"27\",\"platformVersion\":\"8.1.0\",\"carrierName\":\"Android\",\"realDisplaySize\":\"1080x1920\",\"displayDensity\":480,\"networks\":[{\"type\":0,\"typeName\":\"MOBILE\",\"subtype\":13,\"subtypeName\":\"LTE\",\"isConnected\":true,\"detailedState\":\"CONNECTED\",\"state\":\"CONNECTED\",\"extraInfo\":\"epc.tmobile.com\",\"isAvailable\":true,\"isFailover\":false,\"isRoaming\":false,\"capabilities\":{\"transportTypes\":\"NET_CAPABILITY_MMS\",\"networkCapabilities\":\"\",\"linkUpstreamBandwidthKbps\":51200,\"linkDownBandwidthKbps\":102400,\"signalStrength\":-2147483648,\"networkSpecifier\":\"1\",\"SSID\":null}},{\"type\":1,\"typeName\":\"WIFI\",\"subtype\":0,\"subtypeName\":\"\",\"isConnected\":true,\"detailedState\":\"CONNECTED\",\"state\":\"CONNECTED\",\"extraInfo\":\"\\\"AndroidWifi\\\"\",\...
2019-09-24 17:16:34:787 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys window'
2019-09-24 17:16:34:804 [AndroidDriver] Screen already unlocked, doing nothing
2019-09-24 17:16:34:804 [AndroidDriver] Starting a chrome-based browser session
2019-09-24 17:16:34:808 [AndroidDriver] A port was not given, using random free port: 8000
2019-09-24 17:16:34:808 [AndroidDriver] Before starting chromedriver, androidPackage is 'com.android.chrome'
2019-09-24 17:16:34:809 [Chromedriver] Changed state to 'starting'
2019-09-24 17:16:34:809 [Chromedriver] Set chromedriver binary as: /root/chromedriver
2019-09-24 17:16:34:810 [Chromedriver] Killing any old chromedrivers, running: pkill -15 -f "/root/chromedriver.*--port=8000"
2019-09-24 17:16:34:817 [Chromedriver] No old chromedrivers seem to exist
2019-09-24 17:16:34:817 [Chromedriver] Cleaning any old adb forwarded port socket connections
2019-09-24 17:16:34:817 [ADB] List forwarding ports
2019-09-24 17:16:34:817 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward --list'
2019-09-24 17:16:34:822 [Chromedriver] Spawning chromedriver with: /root/chromedriver --url-base=wd/hub --port=8000 --adb-port=5037 --verbose
2019-09-24 17:16:34:847 [Chromedriver] Chromedriver version: '2.33.506092'
2019-09-24 17:16:34:848 [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-24 17:16:34:848 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8000/wd/hub/status] with no body
2019-09-24 17:16:34:850 [WD Proxy] Got response with status 200: "{\"sessionId\":\"\",\"status\":0,\"value\":{\"build\":{\"version\":\"alpha\"},\"os\":{\"arch\":\"x86_64\",\"name\":\"Linux\",\"version\":\"5.0.0-27-generic\"}}}"
2019-09-24 17:16:34:850 [WD Proxy] Determined that the downstream protocol for proxy is MJSONWP
2019-09-24 17:16:34:850 [WD Proxy] Matched '/session' to command name 'createSession'
2019-09-24 17:16:34:851 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8000/wd/hub/session] with body: {"desiredCapabilities":{"chromeOptions":{"androidPackage":"com.android.chrome","androidDeviceSerial":"emulator-5554"},"loggingPrefs":{"browser":"ALL"}}}
2019-09-24 17:16:37:008 [Chromedriver] Webview version: 'Chrome/69.0.3497.100'
2019-09-24 17:16:37:158 [WD Proxy] Got response with status 200: {"sessionId":"49af4128178dc39ce5c0bf524d3a929e","status":0,"value":{"acceptSslCerts":true,"applicationCacheEnabled":false,"browserConnectionEnabled":false,"browserName":"chrome","chrome":{"chromedriverVersion":"2.33.506092 (733a02544d189eeb751fe0d7ddca79a0ee28cce4)"},"cssSelectorsEnabled":true,"databaseEnabled":false,"handlesAlerts":true,"hasTouchScreen":true,"javascriptEnabled":true,"locationContextEnabled":true,"mobileEmulationEnabled":false,"nativeEvents":true,"pageLoadStrategy":"normal","platform":"ANDROID","rotatable":false,"setWindowRect":false,"takesHeapSnapshot":true,"takesScreenshot":true,"unexpectedAlertBehaviour":"","version":"69.0.3497.100","webStorageEnabled":true}}
2019-09-24 17:16:37:159 [Chromedriver] Changed state to 'online'
2019-09-24 17:16:37:160 [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/pixel_ratio] with body: {}
2019-09-24 17:16:37:177 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":3}"
2019-09-24 17:16:37:178 [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
2019-09-24 17:16:37:179 [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/system_bars] with body: {}
2019-09-24 17:16:37:193 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"statusBar\":72}}"
2019-09-24 17:16:37:193 [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize'
2019-09-24 17:16:37:194 [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/window/current/size] with body: {}
2019-09-24 17:16:37:201 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"height\":1776,\"width\":1080}}"
2019-09-24 17:16:37:202 [Appium] New AndroidUiautomator2Driver session created successfully, session 067869b4-113f-48c6-a7f0-136b0524222d added to master session list
2019-09-24 17:16:37:203 [BaseDriver] Event 'newSessionStarted' logged at 1569345397203 (10:16:37 GMT-0700 (Pacific Daylight Time))
2019-09-24 17:16:37:203 [W3C (067869b4)] Cached the protocol value 'W3C' for the new session 067869b4-113f-48c6-a7f0-136b0524222d
2019-09-24 17:16:37:204 [W3C (067869b4)] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"nexus_5_8.1"},"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"emulator-5554","appPackage":"com.android.chrome","appActivity":"com.google.android.apps.chrome.Main","deviceUDID":"emulator-5554","deviceApiLevel":27,"platformVersion":"8.1.0","deviceScreenSize":"1080x1920","deviceScreenDensity":480,"deviceModel":"Android SDK built for x86","deviceManufacturer":"Google","pixelRatio":3,"statBarHeight":72,"viewportRect":{"left":0,"top":72,"width":1080,"height":1704}}}
2019-09-24 17:16:37:205 [HTTP] <-- POST /wd/hub/session 200 8510 ms - 943
2019-09-24 17:16:37:205 [HTTP] 
2019-09-24 17:16:37:278 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:37:278 [HTTP] {}
2019-09-24 17:16:37:278 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:37:279 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:37:280 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68
2019-09-24 17:16:37:280 [HTTP] 
2019-09-24 17:16:42:282 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:42:282 [HTTP] {}
2019-09-24 17:16:42:282 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:42:283 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:42:283 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:42:284 [HTTP] 
2019-09-24 17:16:47:286 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:47:286 [HTTP] {}
2019-09-24 17:16:47:286 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:47:286 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:47:287 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:47:287 [HTTP] 
2019-09-24 17:16:52:289 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:52:290 [HTTP] {}
2019-09-24 17:16:52:290 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:52:290 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:52:291 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:52:291 [HTTP] 
2019-09-24 17:16:57:292 [HTTP] --> GET /wd/hub/status
2019-09-24 17:16:57:293 [HTTP] {}
2019-09-24 17:16:57:293 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:16:57:293 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:16:57:294 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:16:57:294 [HTTP] 
2019-09-24 17:17:02:297 [HTTP] --> GET /wd/hub/status
2019-09-24 17:17:02:297 [HTTP] {}
2019-09-24 17:17:02:298 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:17:02:298 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:17:02:299 [HTTP] <-- GET /wd/hub/status 200 3 ms - 68
2019-09-24 17:17:02:299 [HTTP] 
2019-09-24 17:17:07:253 [HTTP] --> DELETE /wd/hub/session/067869b4-113f-48c6-a7f0-136b0524222d
2019-09-24 17:17:07:253 [HTTP] {}
2019-09-24 17:17:07:254 [W3C (067869b4)] Calling AppiumDriver.deleteSession() with args: ["067869b4-113f-48c6-a7f0-136b0524222d"]
2019-09-24 17:17:07:254 [BaseDriver] Event 'quitSessionRequested' logged at 1569345427254 (10:17:07 GMT-0700 (Pacific Daylight Time))
2019-09-24 17:17:07:255 [Appium] Removing session 067869b4-113f-48c6-a7f0-136b0524222d from our master session list
2019-09-24 17:17:07:255 [UiAutomator2] Deleting UiAutomator2 session
2019-09-24 17:17:07:256 [AndroidDriver] Stopping chromedriver for context CHROMIUM
2019-09-24 17:17:07:256 [Chromedriver] Changed state to 'stopping'
2019-09-24 17:17:07:257 [WD Proxy] Proxying [DELETE /] to [DELETE http://127.0.0.1:8000/wd/hub/session/49af4128178dc39ce5c0bf524d3a929e] with no body
2019-09-24 17:17:07:300 [WD Proxy] Got response with status 200: "{\"sessionId\":\"49af4128178dc39ce5c0bf524d3a929e\",\"status\":0,\"value\":null}"
2019-09-24 17:17:07:301 [HTTP] --> GET /wd/hub/status
2019-09-24 17:17:07:301 [HTTP] {}
2019-09-24 17:17:07:302 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:17:07:302 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:17:07:302 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:17:07:303 [HTTP] 
2019-09-24 17:17:07:304 [Chromedriver] Changed state to 'stopped'
2019-09-24 17:17:07:304 [UiAutomator2] Deleting UiAutomator2 server session
2019-09-24 17:17:07:304 [WD Proxy] Matched '/' to command name 'deleteSession'
2019-09-24 17:17:07:305 [WD Proxy] Proxying [DELETE /] to [DELETE http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e] with no body
2019-09-24 17:17:07:316 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":\"Session deleted\"}"
2019-09-24 17:17:07:316 [Logcat] Stopping logcat capture
2019-09-24 17:17:07:317 [ADB] Removing forwarded port socket connection: 8200 
2019-09-24 17:17:07:317 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward --remove tcp\:8200'
2019-09-24 17:17:07:339 [BaseDriver] Event 'quitSessionFinished' logged at 1569345427338 (10:17:07 GMT-0700 (Pacific Daylight Time))
2019-09-24 17:17:07:339 [W3C (067869b4)] Received response: null
2019-09-24 17:17:07:339 [W3C (067869b4)] But deleting session, so not returning
2019-09-24 17:17:07:339 [W3C (067869b4)] Responding to client with driver.deleteSession() result: null
2019-09-24 17:17:07:340 [HTTP] <-- DELETE /wd/hub/session/067869b4-113f-48c6-a7f0-136b0524222d 200 87 ms - 14
2019-09-24 17:17:07:340 [HTTP] 
2019-09-24 17:17:07:734 [HTTP] --> GET /wd/hub/status
2019-09-24 17:17:07:734 [HTTP] {}
2019-09-24 17:17:07:735 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:17:07:735 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:17:07:736 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68
2019-09-24 17:17:07:736 [HTTP] 
2019-09-24 17:17:08:143 [Instrumentation] .
2019-09-24 17:17:08:272 [Instrumentation] Time: 35.03
2019-09-24 17:17:08:272 [Instrumentation] 
2019-09-24 17:17:08:273 [Instrumentation] OK (1 test)
2019-09-24 17:17:08:278 [Instrumentation] The process has exited with code 0
2019-09-24 17:17:12:304 [HTTP] --> GET /wd/hub/status
2019-09-24 17:17:12:304 [HTTP] {}
2019-09-24 17:17:12:305 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-09-24 17:17:12:305 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}}
2019-09-24 17:17:12:306 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68
2019-09-24 17:17:12:306 [HTTP] 
<!-- gh-comment-id:534661425 --> @mikk150 commented on GitHub (Sep 24, 2019): ``` 2019-09-24 17:15:57:214 [Appium] Welcome to Appium v1.14.0 2019-09-24 17:15:57:215 [Appium] Non-default server args: 2019-09-24 17:15:57:215 [Appium] logFile: /var/log/supervisor/appium.log 2019-09-24 17:15:57:215 [Appium] nodeconfig: /root/src/nodeconfig.json 2019-09-24 17:15:57:216 [Appium] chromedriverExecutable: /root/chromedriver 2019-09-24 17:15:57:236 [Appium] Starting auto register thread for grid. Will try to register every 5000 ms. 2019-09-24 17:15:57:236 [Appium] Appium REST http interface listener started on 0.0.0.0:4723 2019-09-24 17:16:01:702 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:01:702 [HTTP] {} 2019-09-24 17:16:01:704 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:01:705 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:01:710 [HTTP] <-- GET /wd/hub/status 200 5 ms - 68 2019-09-24 17:16:01:710 [HTTP] 2019-09-24 17:16:02:250 [Appium] Appium successfully registered with the grid on http://selenium:4444 2019-09-24 17:16:07:252 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:07:253 [HTTP] {} 2019-09-24 17:16:07:253 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:07:253 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:07:255 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68 2019-09-24 17:16:07:256 [HTTP] 2019-09-24 17:16:12:257 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:12:258 [HTTP] {} 2019-09-24 17:16:12:258 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:12:258 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:12:259 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68 2019-09-24 17:16:12:259 [HTTP] 2019-09-24 17:16:17:262 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:17:262 [HTTP] {} 2019-09-24 17:16:17:263 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:17:263 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:17:264 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:17:264 [HTTP] 2019-09-24 17:16:22:266 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:22:266 [HTTP] {} 2019-09-24 17:16:22:266 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:22:267 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:22:268 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68 2019-09-24 17:16:22:268 [HTTP] 2019-09-24 17:16:27:270 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:27:270 [HTTP] {} 2019-09-24 17:16:27:270 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:27:271 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:27:271 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:27:271 [HTTP] 2019-09-24 17:16:28:694 [HTTP] --> POST /wd/hub/session 2019-09-24 17:16:28:694 [HTTP] {"desiredCapabilities":{"browserName":"chrome","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","platformName":"android","deviceName":"nexus_5_8.1"},"capabilities":{"firstMatch":[{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d"}]}} 2019-09-24 17:16:28:694 [W3C] Calling AppiumDriver.createSession() with args: [{"browserName":"chrome","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","platformName":"android","deviceName":"nexus_5_8.1"},null,{"firstMatch":[{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d"}]}] 2019-09-24 17:16:28:694 [BaseDriver] Event 'newSessionRequested' logged at 1569345388694 (10:16:28 GMT-0700 (Pacific Daylight Time)) 2019-09-24 17:16:28:698 [Appium] Could not parse W3C capabilities: 'deviceName' can't be blank 2019-09-24 17:16:28:699 [Appium] Trying to fix W3C capabilities by merging them with JSONWP caps 2019-09-24 17:16:28:699 [BaseDriver] The capabilities ["deviceName"] are not standard capabilities and should have an extension prefix 2019-09-24 17:16:28:700 [Appium] 2019-09-24 17:16:28:700 [Appium] ====================================================================== 2019-09-24 17:16:28:700 [Appium] DEPRECATION WARNING: 2019-09-24 17:16:28:700 [Appium] 2019-09-24 17:16:28:700 [Appium] The 'automationName' capability was not provided in the desired 2019-09-24 17:16:28:700 [Appium] capabilities for this Android session 2019-09-24 17:16:28:701 [Appium] 2019-09-24 17:16:28:701 [Appium] Setting 'automationName=UiAutomator2' by default and using the 2019-09-24 17:16:28:701 [Appium] UiAutomator2 Driver 2019-09-24 17:16:28:701 [Appium] 2019-09-24 17:16:28:701 [Appium] The next major version of Appium (2.x) will **require** the 2019-09-24 17:16:28:701 [Appium] 'automationName' capability to be set for all sessions on all 2019-09-24 17:16:28:701 [Appium] platforms 2019-09-24 17:16:28:701 [Appium] 2019-09-24 17:16:28:701 [Appium] In previous versions (Appium <= 1.13.x), the default was 2019-09-24 17:16:28:701 [Appium] 'automationName=UiAutomator1' 2019-09-24 17:16:28:701 [Appium] 2019-09-24 17:16:28:701 [Appium] If you wish to use that automation instead of UiAutomator2, please 2019-09-24 17:16:28:702 [Appium] add 'automationName=UiAutomator1' to your desired capabilities 2019-09-24 17:16:28:702 [Appium] 2019-09-24 17:16:28:702 [Appium] For more information about drivers, please visit 2019-09-24 17:16:28:702 [Appium] http://appium.io/docs/en/about-appium/intro/ and explore the 2019-09-24 17:16:28:702 [Appium] 'Drivers' menu 2019-09-24 17:16:28:702 [Appium] 2019-09-24 17:16:28:702 [Appium] ====================================================================== 2019-09-24 17:16:28:702 [Appium] 2019-09-24 17:16:28:770 [Appium] Appium v1.14.0 creating new AndroidUiautomator2Driver (v1.33.1) session 2019-09-24 17:16:28:770 [Appium] Capabilities: 2019-09-24 17:16:28:770 [Appium] deviceName: nexus_5_8.1 2019-09-24 17:16:28:770 [Appium] browserName: chrome 2019-09-24 17:16:28:770 [Appium] platformName: android 2019-09-24 17:16:28:770 [Appium] server:CONFIG_UUID: dc2e971b-d6ef-4501-8f8c-8167437bbe8d 2019-09-24 17:16:28:772 [BaseDriver] W3C capabilities {"alwaysMatch":{"appium:dev... and MJSONWP desired capabilities {"browserName":"chrome","CO... were provided 2019-09-24 17:16:28:772 [BaseDriver] Creating session with W3C capabilities: {"alwaysMatch":{"appium:dev... 2019-09-24 17:16:28:776 [BaseDriver] The following capabilities were provided, but are not recognized by Appium: 2019-09-24 17:16:28:776 [BaseDriver] server:CONFIG_UUID 2019-09-24 17:16:28:776 [BaseDriver] Session created with session id: 067869b4-113f-48c6-a7f0-136b0524222d 2019-09-24 17:16:28:777 [UiAutomator2] We're going to run a Chrome-based session 2019-09-24 17:16:28:777 [UiAutomator2] Chrome-type package and activity are com.android.chrome and com.google.android.apps.chrome.Main 2019-09-24 17:16:28:799 [ADB] Found 1 'build-tools' folders under '/root' (newest first): 2019-09-24 17:16:28:800 [ADB] /root/build-tools/29.0.0 2019-09-24 17:16:28:800 [ADB] Using 'adb' from '/root/platform-tools/adb' 2019-09-24 17:16:28:801 [AndroidDriver] Retrieving device list 2019-09-24 17:16:28:801 [ADB] Trying to find a connected android device 2019-09-24 17:16:28:801 [ADB] Getting connected devices... 2019-09-24 17:16:28:811 [ADB] 1 device(s) connected 2019-09-24 17:16:28:812 [AndroidDriver] Using device: emulator-5554 2019-09-24 17:16:28:812 [ADB] Using 'adb' from '/root/platform-tools/adb' 2019-09-24 17:16:28:813 [ADB] Setting device id to emulator-5554 2019-09-24 17:16:28:814 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk' 2019-09-24 17:16:28:825 [ADB] Current device property 'ro.build.version.sdk': 27 2019-09-24 17:16:28:825 [ADB] Device API level: 27 2019-09-24 17:16:28:826 [AndroidDriver] No app sent in, not parsing package/activity 2019-09-24 17:16:28:827 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 wait-for-device' 2019-09-24 17:16:28:832 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell echo ping' 2019-09-24 17:16:28:840 [AndroidDriver] Pushing settings apk to device... 2019-09-24 17:16:28:841 [ADB] Getting install status for io.appium.settings 2019-09-24 17:16:28:841 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings' 2019-09-24 17:16:28:867 [ADB] 'io.appium.settings' is not installed 2019-09-24 17:16:28:867 [ADB] App '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' is not installed 2019-09-24 17:16:28:867 [ADB] Installing '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' 2019-09-24 17:16:28:872 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_'' 2019-09-24 17:16:28:893 [ADB] Got an error 'ls: /data/local/tmp/appium_cache: No such file or directory' while getting the list of files in the cache. Assuming the cache does not exist yet 2019-09-24 17:16:28:894 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache' 2019-09-24 17:16:28:908 [ADB] The count of applications in the cache: 0 2019-09-24 17:16:28:908 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk' to '/data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk' 2019-09-24 17:16:28:909 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache' 2019-09-24 17:16:28:925 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk /data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk' 2019-09-24 17:16:28:945 [ADB] The upload of 'settings_apk-debug.apk' (1.16 MB) took 0.036s 2019-09-24 17:16:28:947 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install -g /data/local/tmp/appium_cache/12bb63270ecd4315cfaaa8d8202b809bbfccca11.apk' 2019-09-24 17:16:29:583 [ADB] The installation of 'settings_apk-debug.apk' took 0.637s 2019-09-24 17:16:29:584 [ADB] Install command stdout: Success 2019-09-24 17:16:29:584 [ADB] Getting IDs of all 'io.appium.settings' processes 2019-09-24 17:16:29:585 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'pgrep --help; echo $?'' 2019-09-24 17:16:29:615 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings' 2019-09-24 17:16:29:657 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am start -n io.appium.settings/.Settings -a android.intent.action.MAIN -c android.intent.category.LAUNCHER' 2019-09-24 17:16:29:751 [ADB] Getting IDs of all 'io.appium.settings' processes 2019-09-24 17:16:29:751 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings' 2019-09-24 17:16:30:089 [ADB] Getting IDs of all 'io.appium.settings' processes 2019-09-24 17:16:30:089 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings' 2019-09-24 17:16:30:110 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell appops set io.appium.settings android\:mock_location allow' 2019-09-24 17:16:30:128 [Logcat] Starting logcat capture 2019-09-24 17:16:30:155 [ADB] Getting install status for io.appium.uiautomator2.server 2019-09-24 17:16:30:155 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server' 2019-09-24 17:16:30:196 [ADB] 'io.appium.uiautomator2.server' is not installed 2019-09-24 17:16:30:196 [ADB] App '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' is not installed 2019-09-24 17:16:30:196 [UiAutomator2] io.appium.uiautomator2.server installation state: notInstalled 2019-09-24 17:16:30:196 [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk 2019-09-24 17:16:30:197 [ADB] Using 'apksigner' from '/root/build-tools/29.0.0/apksigner' 2019-09-24 17:16:30:198 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["verify","--print-certs","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk"]' 2019-09-24 17:16:30:482 [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-24 17:16:30:482 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2019-09-24 17:16:30:482 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2019-09-24 17:16:30:483 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2019-09-24 17:16:30:483 [ADB] 2019-09-24 17:16:30:483 [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' is already signed. 2019-09-24 17:16:30:483 [ADB] Getting install status for io.appium.uiautomator2.server.test 2019-09-24 17:16:30:484 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server.test' 2019-09-24 17:16:30:501 [ADB] 'io.appium.uiautomator2.server.test' is not installed 2019-09-24 17:16:30:502 [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk 2019-09-24 17:16:30:502 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["verify","--print-certs","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]' 2019-09-24 17:16:30:697 [ADB] apksigner stdout: Signer #1 certificate DN: C=US, O=Android, CN=Android Debug 2019-09-24 17:16:30:698 [ADB] Signer #1 certificate SHA-256 digest: c3362e88ec629255c69c5c07689b41862fa8a760e888ff664a2de1e92ecb2ad1 2019-09-24 17:16:30:698 [ADB] Signer #1 certificate SHA-1 digest: 2755ea34b8ed3a89f8a83e0c24f223ce6245bf58 2019-09-24 17:16:30:698 [ADB] Signer #1 certificate MD5 digest: 2a2c3f837793c0da3c6bc4e481cdcc16 2019-09-24 17:16:30:698 [ADB] 2019-09-24 17:16:30:698 [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is signed with non-default certificate 2019-09-24 17:16:30:700 [ADB] Using 'zipalign' from '/root/build-tools/29.0.0/zipalign' 2019-09-24 17:16:30:706 [ADB] /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already zip-aligned. Doing nothing 2019-09-24 17:16:30:706 [ADB] Signing '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' with default cert 2019-09-24 17:16:30:707 [ADB] Starting '/root/build-tools/29.0.0/apksigner' with args '["sign","--key","/usr/lib/node_modules/appium/node_modules/appium-adb/keys/testkey.pk8","--cert","/usr/lib/node_modules/appium/node_modules/appium-adb/keys/testkey.x509.pem","/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]' 2019-09-24 17:16:30:965 [UiAutomator2] Server packages are going to be (re)installed 2019-09-24 17:16:30:971 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_'' 2019-09-24 17:16:30:986 [ADB] The count of applications in the cache: 1 2019-09-24 17:16:30:986 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' to '/data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk' 2019-09-24 17:16:30:986 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache' 2019-09-24 17:16:30:999 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk /data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk' 2019-09-24 17:16:31:026 [ADB] The upload of 'appium-uiautomator2-server-v3.7.0.apk' (2.14 MB) took 0.039s 2019-09-24 17:16:31:026 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install /data/local/tmp/appium_cache/fe3aa6662b6c999cf661d7626a50da395fd786f4.apk' 2019-09-24 17:16:31:740 [ADB] The installation of 'appium-uiautomator2-server-v3.7.0.apk' took 0.714s 2019-09-24 17:16:31:740 [ADB] Install command stdout: Success 2019-09-24 17:16:31:743 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_'' 2019-09-24 17:16:31:783 [ADB] The count of applications in the cache: 2 2019-09-24 17:16:31:783 [ADB] Caching the application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' to '/data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk' 2019-09-24 17:16:31:783 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell mkdir -p /data/local/tmp/appium_cache' 2019-09-24 17:16:31:804 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 push /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk /data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk' 2019-09-24 17:16:31:812 [ADB] The upload of 'appium-uiautomator2-server-debug-androidTest.apk' (539.10 KB) took 0.028s 2019-09-24 17:16:31:812 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm install /data/local/tmp/appium_cache/803351851ae3c5e95b3b134df12ac4dc20f5da62.apk' 2019-09-24 17:16:32:172 [ADB] The installation of 'appium-uiautomator2-server-debug-androidTest.apk' took 0.360s 2019-09-24 17:16:32:173 [ADB] Install command stdout: Success 2019-09-24 17:16:32:173 [UiAutomator2] Waiting up to 30000ms for services to be available 2019-09-24 17:16:32:174 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm list instrumentation' 2019-09-24 17:16:32:274 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:32:274 [HTTP] {} 2019-09-24 17:16:32:274 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:32:275 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:32:276 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:32:276 [HTTP] 2019-09-24 17:16:32:370 [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available 2019-09-24 17:16:32:371 [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8200 2019-09-24 17:16:32:371 [ADB] Forwarding system: 8200 to device: 6790 2019-09-24 17:16:32:371 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward tcp\:8200 tcp\:6790' 2019-09-24 17:16:32:378 [UiAutomator2] No app capability. Assuming it is already on the device 2019-09-24 17:16:32:378 [ADB] Getting install status for com.android.chrome 2019-09-24 17:16:32:378 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package com.android.chrome' 2019-09-24 17:16:32:398 [ADB] 'com.android.chrome' is installed 2019-09-24 17:16:32:398 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.android.chrome' 2019-09-24 17:16:32:446 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell pm clear com.android.chrome' 2019-09-24 17:16:32:679 [AndroidDriver] Performed fast reset on the installed 'com.android.chrome' application (stop and clear) 2019-09-24 17:16:32:680 [UiAutomator2] Performing shallow cleanup of automation leftovers 2019-09-24 17:16:32:684 [UiAutomator2] No obsolete sessions have been detected (Error: socket hang up) 2019-09-24 17:16:32:684 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server.test' 2019-09-24 17:16:32:708 [UiAutomator2] Starting UIAutomator2 server 3.7.0 2019-09-24 17:16:32:708 [UiAutomator2] Using UIAutomator2 server from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.7.0.apk' and test from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' 2019-09-24 17:16:32:708 [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online... 2019-09-24 17:16:32:709 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"] 2019-09-24 17:16:33:116 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server: 2019-09-24 17:16:33:714 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-24 17:16:33:715 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body 2019-09-24 17:16:33:718 [WD Proxy] Got an unexpected response: {"code":"ECONNRESET"} 2019-09-24 17:16:34:720 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-24 17:16:34:720 [WD Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body 2019-09-24 17:16:34:754 [WD Proxy] Got response with status 200: "{\"sessionId\":\"None\",\"status\":0,\"value\":\"Status Invoked\"}" 2019-09-24 17:16:34:754 [WD Proxy] Determined that the downstream protocol for proxy is MJSONWP 2019-09-24 17:16:34:754 [UiAutomator2] The initialization of the instrumentation process took 2046ms 2019-09-24 17:16:34:755 [WD Proxy] Matched '/session' to command name 'createSession' 2019-09-24 17:16:34:755 [WD Proxy] Proxying [POST /session] to [POST http://localhost:8200/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"nexus_5_8.1"},"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"emulator-5554","appPackage":"com.android.chrome","appActivity":"com.google.android.apps.chrome.Main","deviceUDID":"emulator-5554"}} 2019-09-24 17:16:34:768 [WD Proxy] Got response with status 200: {"sessionId":"2db66a9c-6999-4e82-897f-6992edb4b82e","status":0,"value":"Created Session"} 2019-09-24 17:16:34:772 [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/info] with no body 2019-09-24 17:16:34:786 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"androidId\":\"7a153feeb2816a77\",\"manufacturer\":\"Google\",\"model\":\"Android SDK built for x86\",\"brand\":\"google\",\"apiVersion\":\"27\",\"platformVersion\":\"8.1.0\",\"carrierName\":\"Android\",\"realDisplaySize\":\"1080x1920\",\"displayDensity\":480,\"networks\":[{\"type\":0,\"typeName\":\"MOBILE\",\"subtype\":13,\"subtypeName\":\"LTE\",\"isConnected\":true,\"detailedState\":\"CONNECTED\",\"state\":\"CONNECTED\",\"extraInfo\":\"epc.tmobile.com\",\"isAvailable\":true,\"isFailover\":false,\"isRoaming\":false,\"capabilities\":{\"transportTypes\":\"NET_CAPABILITY_MMS\",\"networkCapabilities\":\"\",\"linkUpstreamBandwidthKbps\":51200,\"linkDownBandwidthKbps\":102400,\"signalStrength\":-2147483648,\"networkSpecifier\":\"1\",\"SSID\":null}},{\"type\":1,\"typeName\":\"WIFI\",\"subtype\":0,\"subtypeName\":\"\",\"isConnected\":true,\"detailedState\":\"CONNECTED\",\"state\":\"CONNECTED\",\"extraInfo\":\"\\\"AndroidWifi\\\"\",\... 2019-09-24 17:16:34:787 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys window' 2019-09-24 17:16:34:804 [AndroidDriver] Screen already unlocked, doing nothing 2019-09-24 17:16:34:804 [AndroidDriver] Starting a chrome-based browser session 2019-09-24 17:16:34:808 [AndroidDriver] A port was not given, using random free port: 8000 2019-09-24 17:16:34:808 [AndroidDriver] Before starting chromedriver, androidPackage is 'com.android.chrome' 2019-09-24 17:16:34:809 [Chromedriver] Changed state to 'starting' 2019-09-24 17:16:34:809 [Chromedriver] Set chromedriver binary as: /root/chromedriver 2019-09-24 17:16:34:810 [Chromedriver] Killing any old chromedrivers, running: pkill -15 -f "/root/chromedriver.*--port=8000" 2019-09-24 17:16:34:817 [Chromedriver] No old chromedrivers seem to exist 2019-09-24 17:16:34:817 [Chromedriver] Cleaning any old adb forwarded port socket connections 2019-09-24 17:16:34:817 [ADB] List forwarding ports 2019-09-24 17:16:34:817 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward --list' 2019-09-24 17:16:34:822 [Chromedriver] Spawning chromedriver with: /root/chromedriver --url-base=wd/hub --port=8000 --adb-port=5037 --verbose 2019-09-24 17:16:34:847 [Chromedriver] Chromedriver version: '2.33.506092' 2019-09-24 17:16:34:848 [WD Proxy] Matched '/status' to command name 'getStatus' 2019-09-24 17:16:34:848 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8000/wd/hub/status] with no body 2019-09-24 17:16:34:850 [WD Proxy] Got response with status 200: "{\"sessionId\":\"\",\"status\":0,\"value\":{\"build\":{\"version\":\"alpha\"},\"os\":{\"arch\":\"x86_64\",\"name\":\"Linux\",\"version\":\"5.0.0-27-generic\"}}}" 2019-09-24 17:16:34:850 [WD Proxy] Determined that the downstream protocol for proxy is MJSONWP 2019-09-24 17:16:34:850 [WD Proxy] Matched '/session' to command name 'createSession' 2019-09-24 17:16:34:851 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8000/wd/hub/session] with body: {"desiredCapabilities":{"chromeOptions":{"androidPackage":"com.android.chrome","androidDeviceSerial":"emulator-5554"},"loggingPrefs":{"browser":"ALL"}}} 2019-09-24 17:16:37:008 [Chromedriver] Webview version: 'Chrome/69.0.3497.100' 2019-09-24 17:16:37:158 [WD Proxy] Got response with status 200: {"sessionId":"49af4128178dc39ce5c0bf524d3a929e","status":0,"value":{"acceptSslCerts":true,"applicationCacheEnabled":false,"browserConnectionEnabled":false,"browserName":"chrome","chrome":{"chromedriverVersion":"2.33.506092 (733a02544d189eeb751fe0d7ddca79a0ee28cce4)"},"cssSelectorsEnabled":true,"databaseEnabled":false,"handlesAlerts":true,"hasTouchScreen":true,"javascriptEnabled":true,"locationContextEnabled":true,"mobileEmulationEnabled":false,"nativeEvents":true,"pageLoadStrategy":"normal","platform":"ANDROID","rotatable":false,"setWindowRect":false,"takesHeapSnapshot":true,"takesScreenshot":true,"unexpectedAlertBehaviour":"","version":"69.0.3497.100","webStorageEnabled":true}} 2019-09-24 17:16:37:159 [Chromedriver] Changed state to 'online' 2019-09-24 17:16:37:160 [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/pixel_ratio] with body: {} 2019-09-24 17:16:37:177 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":3}" 2019-09-24 17:16:37:178 [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars' 2019-09-24 17:16:37:179 [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/appium/device/system_bars] with body: {} 2019-09-24 17:16:37:193 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"statusBar\":72}}" 2019-09-24 17:16:37:193 [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize' 2019-09-24 17:16:37:194 [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e/window/current/size] with body: {} 2019-09-24 17:16:37:201 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":{\"height\":1776,\"width\":1080}}" 2019-09-24 17:16:37:202 [Appium] New AndroidUiautomator2Driver session created successfully, session 067869b4-113f-48c6-a7f0-136b0524222d added to master session list 2019-09-24 17:16:37:203 [BaseDriver] Event 'newSessionStarted' logged at 1569345397203 (10:16:37 GMT-0700 (Pacific Daylight Time)) 2019-09-24 17:16:37:203 [W3C (067869b4)] Cached the protocol value 'W3C' for the new session 067869b4-113f-48c6-a7f0-136b0524222d 2019-09-24 17:16:37:204 [W3C (067869b4)] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"nexus_5_8.1"},"browserName":"chrome","platformName":"android","server:CONFIG_UUID":"dc2e971b-d6ef-4501-8f8c-8167437bbe8d","deviceName":"emulator-5554","appPackage":"com.android.chrome","appActivity":"com.google.android.apps.chrome.Main","deviceUDID":"emulator-5554","deviceApiLevel":27,"platformVersion":"8.1.0","deviceScreenSize":"1080x1920","deviceScreenDensity":480,"deviceModel":"Android SDK built for x86","deviceManufacturer":"Google","pixelRatio":3,"statBarHeight":72,"viewportRect":{"left":0,"top":72,"width":1080,"height":1704}}} 2019-09-24 17:16:37:205 [HTTP] <-- POST /wd/hub/session 200 8510 ms - 943 2019-09-24 17:16:37:205 [HTTP] 2019-09-24 17:16:37:278 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:37:278 [HTTP] {} 2019-09-24 17:16:37:278 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:37:279 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:37:280 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68 2019-09-24 17:16:37:280 [HTTP] 2019-09-24 17:16:42:282 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:42:282 [HTTP] {} 2019-09-24 17:16:42:282 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:42:283 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:42:283 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:42:284 [HTTP] 2019-09-24 17:16:47:286 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:47:286 [HTTP] {} 2019-09-24 17:16:47:286 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:47:286 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:47:287 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:47:287 [HTTP] 2019-09-24 17:16:52:289 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:52:290 [HTTP] {} 2019-09-24 17:16:52:290 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:52:290 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:52:291 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:52:291 [HTTP] 2019-09-24 17:16:57:292 [HTTP] --> GET /wd/hub/status 2019-09-24 17:16:57:293 [HTTP] {} 2019-09-24 17:16:57:293 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:16:57:293 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:16:57:294 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:16:57:294 [HTTP] 2019-09-24 17:17:02:297 [HTTP] --> GET /wd/hub/status 2019-09-24 17:17:02:297 [HTTP] {} 2019-09-24 17:17:02:298 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:17:02:298 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:17:02:299 [HTTP] <-- GET /wd/hub/status 200 3 ms - 68 2019-09-24 17:17:02:299 [HTTP] 2019-09-24 17:17:07:253 [HTTP] --> DELETE /wd/hub/session/067869b4-113f-48c6-a7f0-136b0524222d 2019-09-24 17:17:07:253 [HTTP] {} 2019-09-24 17:17:07:254 [W3C (067869b4)] Calling AppiumDriver.deleteSession() with args: ["067869b4-113f-48c6-a7f0-136b0524222d"] 2019-09-24 17:17:07:254 [BaseDriver] Event 'quitSessionRequested' logged at 1569345427254 (10:17:07 GMT-0700 (Pacific Daylight Time)) 2019-09-24 17:17:07:255 [Appium] Removing session 067869b4-113f-48c6-a7f0-136b0524222d from our master session list 2019-09-24 17:17:07:255 [UiAutomator2] Deleting UiAutomator2 session 2019-09-24 17:17:07:256 [AndroidDriver] Stopping chromedriver for context CHROMIUM 2019-09-24 17:17:07:256 [Chromedriver] Changed state to 'stopping' 2019-09-24 17:17:07:257 [WD Proxy] Proxying [DELETE /] to [DELETE http://127.0.0.1:8000/wd/hub/session/49af4128178dc39ce5c0bf524d3a929e] with no body 2019-09-24 17:17:07:300 [WD Proxy] Got response with status 200: "{\"sessionId\":\"49af4128178dc39ce5c0bf524d3a929e\",\"status\":0,\"value\":null}" 2019-09-24 17:17:07:301 [HTTP] --> GET /wd/hub/status 2019-09-24 17:17:07:301 [HTTP] {} 2019-09-24 17:17:07:302 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:17:07:302 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:17:07:302 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:17:07:303 [HTTP] 2019-09-24 17:17:07:304 [Chromedriver] Changed state to 'stopped' 2019-09-24 17:17:07:304 [UiAutomator2] Deleting UiAutomator2 server session 2019-09-24 17:17:07:304 [WD Proxy] Matched '/' to command name 'deleteSession' 2019-09-24 17:17:07:305 [WD Proxy] Proxying [DELETE /] to [DELETE http://localhost:8200/wd/hub/session/2db66a9c-6999-4e82-897f-6992edb4b82e] with no body 2019-09-24 17:17:07:316 [WD Proxy] Got response with status 200: "{\"sessionId\":\"2db66a9c-6999-4e82-897f-6992edb4b82e\",\"status\":0,\"value\":\"Session deleted\"}" 2019-09-24 17:17:07:316 [Logcat] Stopping logcat capture 2019-09-24 17:17:07:317 [ADB] Removing forwarded port socket connection: 8200 2019-09-24 17:17:07:317 [ADB] Running '/root/platform-tools/adb -P 5037 -s emulator-5554 forward --remove tcp\:8200' 2019-09-24 17:17:07:339 [BaseDriver] Event 'quitSessionFinished' logged at 1569345427338 (10:17:07 GMT-0700 (Pacific Daylight Time)) 2019-09-24 17:17:07:339 [W3C (067869b4)] Received response: null 2019-09-24 17:17:07:339 [W3C (067869b4)] But deleting session, so not returning 2019-09-24 17:17:07:339 [W3C (067869b4)] Responding to client with driver.deleteSession() result: null 2019-09-24 17:17:07:340 [HTTP] <-- DELETE /wd/hub/session/067869b4-113f-48c6-a7f0-136b0524222d 200 87 ms - 14 2019-09-24 17:17:07:340 [HTTP] 2019-09-24 17:17:07:734 [HTTP] --> GET /wd/hub/status 2019-09-24 17:17:07:734 [HTTP] {} 2019-09-24 17:17:07:735 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:17:07:735 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:17:07:736 [HTTP] <-- GET /wd/hub/status 200 2 ms - 68 2019-09-24 17:17:07:736 [HTTP] 2019-09-24 17:17:08:143 [Instrumentation] . 2019-09-24 17:17:08:272 [Instrumentation] Time: 35.03 2019-09-24 17:17:08:272 [Instrumentation] 2019-09-24 17:17:08:273 [Instrumentation] OK (1 test) 2019-09-24 17:17:08:278 [Instrumentation] The process has exited with code 0 2019-09-24 17:17:12:304 [HTTP] --> GET /wd/hub/status 2019-09-24 17:17:12:304 [HTTP] {} 2019-09-24 17:17:12:305 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2019-09-24 17:17:12:305 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.14.0"}} 2019-09-24 17:17:12:306 [HTTP] <-- GET /wd/hub/status 200 1 ms - 68 2019-09-24 17:17:12:306 [HTTP] ```
Sign in to join this conversation.
No milestone
No project
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
starred/docker-android#140
No description provided.