Skip to content

Instantly share code, notes, and snippets.

@mayureshshirodkar
Created September 25, 2019 05:36
Show Gist options
  • Save mayureshshirodkar/cf37d78bbdb847457914d667a3ada519 to your computer and use it in GitHub Desktop.
Save mayureshshirodkar/cf37d78bbdb847457914d667a3ada519 to your computer and use it in GitHub Desktop.
2019-09-25 05:34:01:035 - [Appium] Welcome to Appium v1.15.0-beta.1
2019-09-25 05:34:01:038 - [Appium] Non-default server args:
2019-09-25 05:34:01:039 - [Appium] address: 127.0.0.1
2019-09-25 05:34:01:039 - [Appium] logTimestamp: true
2019-09-25 05:34:01:066 - [Appium] Appium REST http interface listener started on 127.0.0.1:4723
2019-09-25 05:34:09:057 - [HTTP] --> POST /wd/hub/session
2019-09-25 05:34:09:057 - [HTTP] {"desiredCapabilities":{"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"Moto G4"}}
2019-09-25 05:34:09:059 - [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"Moto G4"},null,null]
2019-09-25 05:34:09:059 - [debug] [BaseDriver] Event 'newSessionRequested' logged at 1569389649059 (11:04:09 GMT+0530 (India Standard Time))
2019-09-25 05:34:09:305 - [Appium] Appium v1.15.0-beta.1 creating new AndroidUiautomator2Driver (v1.34.0) session
2019-09-25 05:34:09:308 - [debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "app": "/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk",
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "newCommandTimeout": 500,
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "platformVersion": "7.0",
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "automationName": "uiautomator2",
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "autoGrantPermissions": true,
2019-09-25 05:34:09:308 - [debug] [BaseDriver] "autoWebview": true,
2019-09-25 05:34:09:309 - [debug] [BaseDriver] "handlesAlerts": true,
2019-09-25 05:34:09:309 - [debug] [BaseDriver] "platformName": "Android",
2019-09-25 05:34:09:309 - [debug] [BaseDriver] "deviceName": "Moto G4"
2019-09-25 05:34:09:309 - [debug] [BaseDriver] }
2019-09-25 05:34:09:313 - [BaseDriver] The following capabilities were provided, but are not recognized by Appium:
2019-09-25 05:34:09:313 - [BaseDriver] handlesAlerts
2019-09-25 05:34:09:315 - [BaseDriver] Session created with session id: 484dd327-552a-4474-a0dd-c97b83caefc4
2019-09-25 05:34:09:345 - [BaseDriver] Using local app '/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk'
2019-09-25 05:34:09:346 - [debug] [UiAutomator2] Checking whether app is actually present
2019-09-25 05:34:09:381 - [ADB] Found 1 'build-tools' folders under '/Users/mshirodkar/Library/Android/sdk' (newest first):
2019-09-25 05:34:09:381 - [ADB] /Users/mshirodkar/Library/Android/sdk/build-tools/28.0.3
2019-09-25 05:34:09:381 - [ADB] Using 'adb' from '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb'
2019-09-25 05:34:09:381 - [AndroidDriver] Retrieving device list
2019-09-25 05:34:09:381 - [debug] [ADB] Trying to find a connected android device
2019-09-25 05:34:09:382 - [debug] [ADB] Getting connected devices...
2019-09-25 05:34:09:392 - [debug] [ADB] 1 device(s) connected
2019-09-25 05:34:09:392 - [AndroidDriver] Looking for a device with Android '7.0'
2019-09-25 05:34:09:392 - [debug] [ADB] Setting device id to ZY223DDFLZ
2019-09-25 05:34:09:392 - [ADB] Getting device platform version
2019-09-25 05:34:09:393 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell getprop ro.build.version.release'
2019-09-25 05:34:09:456 - [debug] [ADB] Current device property 'ro.build.version.release': 7.0
2019-09-25 05:34:09:456 - [AndroidDriver] Using device: ZY223DDFLZ
2019-09-25 05:34:09:457 - [ADB] Using 'adb' from '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb'
2019-09-25 05:34:09:457 - [debug] [ADB] Setting device id to ZY223DDFLZ
2019-09-25 05:34:09:458 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell getprop ro.build.version.sdk'
2019-09-25 05:34:09:511 - [debug] [ADB] Current device property 'ro.build.version.sdk': 24
2019-09-25 05:34:09:511 - [debug] [ADB] Device API level: 24
2019-09-25 05:34:09:512 - [debug] [AndroidDriver] Parsing package and activity from app manifest
2019-09-25 05:34:09:513 - [ADB] Using 'apkanalyzer' from '/Users/mshirodkar/Library/Android/sdk/tools/bin/apkanalyzer'
2019-09-25 05:34:09:514 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/tools/bin/apkanalyzer' with args ["-h","manifest","print","/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk"]
2019-09-25 05:34:10:313 - [ADB] Package name: 'com.eab.se'
2019-09-25 05:34:10:313 - [ADB] Main activity name: 'com.eab.se.MainActivity'
2019-09-25 05:34:10:313 - [debug] [AndroidDriver] Parsed package and activity are: com.eab.se/com.eab.se.MainActivity
2019-09-25 05:34:10:314 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ wait-for-device'
2019-09-25 05:34:10:326 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell echo ping'
2019-09-25 05:34:10:362 - [debug] [AndroidDriver] Pushing settings apk to device...
2019-09-25 05:34:10:363 - [debug] [ADB] Getting install status for io.appium.settings
2019-09-25 05:34:10:363 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package io.appium.settings'
2019-09-25 05:34:10:433 - [debug] [ADB] 'io.appium.settings' is installed
2019-09-25 05:34:10:434 - [debug] [ADB] Getting package info for 'io.appium.settings'
2019-09-25 05:34:10:434 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package io.appium.settings'
2019-09-25 05:34:10:513 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/tools/bin/apkanalyzer' with args ["manifest","print","/usr/local/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk"]
2019-09-25 05:34:11:272 - [debug] [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-25 05:34:11:273 - [debug] [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-25 05:34:11:273 - [debug] [ADB] Getting IDs of all 'io.appium.settings' processes
2019-09-25 05:34:11:274 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell 'pgrep --help; echo $?''
2019-09-25 05:34:11:337 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell pgrep \^appium\\.settings\$'
2019-09-25 05:34:11:461 - [debug] [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions.
2019-09-25 05:34:11:461 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell appops set io.appium.settings android\:mock_location allow'
2019-09-25 05:34:11:566 - [debug] [Logcat] Starting logcat capture
2019-09-25 05:34:11:621 - [debug] [ADB] Getting install status for io.appium.uiautomator2.server
2019-09-25 05:34:11:622 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package io.appium.uiautomator2.server'
2019-09-25 05:34:11:691 - [debug] [ADB] 'io.appium.uiautomator2.server' is installed
2019-09-25 05:34:11:691 - [debug] [ADB] Getting package info for 'io.appium.uiautomator2.server'
2019-09-25 05:34:11:691 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package io.appium.uiautomator2.server'
2019-09-25 05:34:11:764 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/tools/bin/apkanalyzer' with args ["manifest","print","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.8.1.apk"]
2019-09-25 05:34:12:521 - [debug] [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('3.8.1' >= '3.8.1')
2019-09-25 05:34:12:521 - [debug] [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled
2019-09-25 05:34:12:522 - [debug] [ADB] Checking app cert for /usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.8.1.apk
2019-09-25 05:34:12:523 - [ADB] Using 'apksigner' from '/Users/mshirodkar/Library/Android/sdk/build-tools/28.0.3/apksigner'
2019-09-25 05:34:12:523 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/build-tools/28.0.3/apksigner' with args '["verify","--print-certs","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.8.1.apk"]'
2019-09-25 05:34:12:933 - [debug] [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-25 05:34:12:933 - [debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2019-09-25 05:34:12:933 - [debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2019-09-25 05:34:12:933 - [debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2019-09-25 05:34:12:933 - [debug] [ADB]
2019-09-25 05:34:12:933 - [debug] [ADB] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.8.1.apk' is already signed.
2019-09-25 05:34:12:934 - [debug] [ADB] Getting install status for io.appium.uiautomator2.server.test
2019-09-25 05:34:12:934 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package io.appium.uiautomator2.server.test'
2019-09-25 05:34:13:014 - [debug] [ADB] 'io.appium.uiautomator2.server.test' is installed
2019-09-25 05:34:13:014 - [debug] [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-25 05:34:13:015 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/build-tools/28.0.3/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-25 05:34:13:359 - [debug] [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-25 05:34:13:359 - [debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2019-09-25 05:34:13:359 - [debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2019-09-25 05:34:13:359 - [debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2019-09-25 05:34:13:359 - [debug] [ADB]
2019-09-25 05:34:13:360 - [debug] [ADB] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already signed.
2019-09-25 05:34:13:360 - [UiAutomator2] Server packages are not going to be (re)installed
2019-09-25 05:34:13:360 - [debug] [UiAutomator2] Waiting up to 30000ms for services to be available
2019-09-25 05:34:13:361 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell pm list instrumentation'
2019-09-25 05:34:14:264 - [debug] [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available
2019-09-25 05:34:14:264 - [debug] [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8209
2019-09-25 05:34:14:264 - [debug] [ADB] Forwarding system: 8209 to device: 6790
2019-09-25 05:34:14:265 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ forward tcp\:8209 tcp\:6790'
2019-09-25 05:34:14:275 - [debug] [ADB] Checking app cert for /Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk
2019-09-25 05:34:14:275 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/build-tools/28.0.3/apksigner' with args '["verify","--print-certs","/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk"]'
2019-09-25 05:34:15:521 - [debug] [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-25 05:34:15:521 - [debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2019-09-25 05:34:15:521 - [debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2019-09-25 05:34:15:522 - [debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2019-09-25 05:34:15:522 - [debug] [ADB]
2019-09-25 05:34:15:522 - [debug] [ADB] '/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk' is already signed.
2019-09-25 05:34:15:522 - [debug] [ADB] Getting install status for com.eab.se
2019-09-25 05:34:15:522 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.eab.se'
2019-09-25 05:34:15:605 - [debug] [ADB] 'com.eab.se' is installed
2019-09-25 05:34:15:606 - [debug] [ADB] Getting install status for com.eab.se
2019-09-25 05:34:15:606 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.eab.se'
2019-09-25 05:34:15:673 - [debug] [ADB] 'com.eab.se' is installed
2019-09-25 05:34:15:674 - [debug] [ADB] Getting package info for 'com.eab.se'
2019-09-25 05:34:15:674 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.eab.se'
2019-09-25 05:34:15:741 - [debug] [ADB] Starting '/Users/mshirodkar/Library/Android/sdk/tools/bin/apkanalyzer' with args ["manifest","print","/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk"]
2019-09-25 05:34:16:564 - [debug] [ADB] The version name of the installed 'com.eab.se' is greater or equal to the application version name ('19.8.2' >= '19.8.2')
2019-09-25 05:34:16:564 - [debug] [ADB] There is no need to install/upgrade '/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk'
2019-09-25 05:34:16:565 - [AndroidDriver] Performing fast reset on 'com.eab.se'
2019-09-25 05:34:16:565 - [debug] [ADB] Getting install status for com.eab.se
2019-09-25 05:34:16:565 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.eab.se'
2019-09-25 05:34:16:648 - [debug] [ADB] 'com.eab.se' is installed
2019-09-25 05:34:16:649 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell am force-stop com.eab.se'
2019-09-25 05:34:17:593 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell pm clear com.eab.se'
2019-09-25 05:34:18:568 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.eab.se'
2019-09-25 05:34:18:649 - [debug] [ADB] Retrieving requested permissions
2019-09-25 05:34:18:650 - [debug] [ADB] Retrieved 9 permission(s) from ["requested"] group(s)
2019-09-25 05:34:18:651 - [debug] [ADB] Retrieving granted permissions
2019-09-25 05:34:18:651 - [debug] [ADB] Retrieved 4 permission(s) from ["install","runtime"] group(s)
2019-09-25 05:34:18:652 - [debug] [ADB] Granting permissions ["android.permission.READ_CALENDAR","android.permission.WRITE_CALENDAR","android.permission.WRITE_EXTERNAL_STORAGE","android.permission.GET_ACCOUNTS","android.permission.READ_EXTERNAL_STORAGE"] to 'com.eab.se'
2019-09-25 05:34:18:652 - [debug] [ADB] Got the following command chunks to execute: [["pm","grant","com.eab.se","android.permission.READ_CALENDAR",";","pm","grant","com.eab.se","android.permission.WRITE_CALENDAR",";","pm","grant","com.eab.se","android.permission.WRITE_EXTERNAL_STORAGE",";","pm","grant","com.eab.se","android.permission.GET_ACCOUNTS",";","pm","grant","com.eab.se","android.permission.READ_EXTERNAL_STORAGE",";"]]
2019-09-25 05:34:18:652 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell pm grant com.eab.se android.permission.READ_CALENDAR \; pm grant com.eab.se android.permission.WRITE_CALENDAR \; pm grant com.eab.se android.permission.WRITE_EXTERNAL_STORAGE \; pm grant com.eab.se android.permission.GET_ACCOUNTS \; pm grant com.eab.se android.permission.READ_EXTERNAL_STORAGE \;'
2019-09-25 05:34:23:078 - [debug] [AndroidDriver] Performed fast reset on the installed 'com.eab.se' application (stop and clear)
2019-09-25 05:34:23:079 - [debug] [UiAutomator2] Performing shallow cleanup of automation leftovers
2019-09-25 05:34:23:095 - [debug] [UiAutomator2] No obsolete sessions have been detected (Error: read ECONNRESET)
2019-09-25 05:34:23:095 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell am force-stop io.appium.uiautomator2.server.test'
2019-09-25 05:34:24:006 - [UiAutomator2] Starting UIAutomator2 server 3.8.1
2019-09-25 05:34:24:006 - [UiAutomator2] Using UIAutomator2 server from '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.8.1.apk' and test from '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk'
2019-09-25 05:34:24:007 - [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
2019-09-25 05:34:24:007 - [debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","ZY223DDFLZ","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
2019-09-25 05:34:25:013 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-25 05:34:25:015 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8209/wd/hub/status] with no body
2019-09-25 05:34:25:021 - [WD Proxy] Got an unexpected response with status undefined: {"errno":"ECONNRESET","code":"ECONNRESET","syscall":"read"}
2019-09-25 05:34:25:375 - [debug] [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server:
2019-09-25 05:34:26:023 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-25 05:34:26:023 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8209/wd/hub/status] with no body
2019-09-25 05:34:26:029 - [WD Proxy] Got an unexpected response with status undefined: {"errno":"ECONNRESET","code":"ECONNRESET","syscall":"read"}
2019-09-25 05:34:27:033 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-25 05:34:27:033 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8209/wd/hub/status] with no body
2019-09-25 05:34:27:103 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"None","status":0,"value":"Status Invoked"}
2019-09-25 05:34:27:103 - [debug] [WD Proxy] Determined the downstream protocol as 'MJSONWP'
2019-09-25 05:34:27:104 - [debug] [UiAutomator2] The initialization of the instrumentation process took 3098ms
2019-09-25 05:34:27:104 - [debug] [WD Proxy] Matched '/session' to command name 'createSession'
2019-09-25 05:34:27:104 - [debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8209/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"Moto G4"},"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"ZY223DDFLZ","deviceUDID":"ZY223DDFLZ","appPackage":"com.eab.se"}}
2019-09-25 05:34:27:136 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"cd7308cf-db8c-482f-8858-e5b915a8411c","status":0,"value":"Created Session"}
2019-09-25 05:34:27:136 - [debug] [WD Proxy] Determined the downstream protocol as 'MJSONWP' per session creation request
2019-09-25 05:34:27:145 - [debug] [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8209/wd/hub/session/cd7308cf-db8c-482f-8858-e5b915a8411c/appium/device/info] with no body
2019-09-25 05:34:27:185 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"cd7308cf-db8c-482f-8858-e5b915a8411c","status":0,"value":{"androidId":"7139029c7cbc6c8","manufacturer":"motorola","model":"Moto G (4)","brand":"motorola","apiVersion":"24","platformVersion":"7.0","carrierName":"Jio 4G","realDisplaySize":"1080x1920","displayDensity":480,"networks":[{"type":0,"typeName":"MOBILE","subtype":13,"subtypeName":"LTE","isConnected":true,"detailedState":"CONNECTED","state":"CONNECTED","extraInfo":"jionet","isAvailable":true,"isFailover":false,"isRoaming":false,"capabilities":{"transportTypes":"NET_CAPABILITY_MMS","networkCapabilities":"","linkUpstreamBandwidthKbps":51200,"linkDownBandwidthKbps":102400,"signalStrength":-2147483648,"networkSpecifier":"5","SSID":null}}],"locale":"en_IN","timeZone":"Asia\/Calcutta"}}
2019-09-25 05:34:27:187 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys window'
2019-09-25 05:34:27:249 - [AndroidDriver] Screen already unlocked, doing nothing
2019-09-25 05:34:27:249 - [UiAutomator2] UiAutomator2 did not start the activity we were waiting for, 'com.eab.se/com.eab.se.MainActivity'. Starting it ourselves
2019-09-25 05:34:27:250 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell am start -W -n com.eab.se/com.eab.se.MainActivity -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000'
2019-09-25 05:34:29:763 - [debug] [ADB] Waiting up to 20000ms for activity matching pkg: 'com.eab.se' and activity: 'com.eab.se.MainActivity' to be focused
2019-09-25 05:34:29:764 - [debug] [ADB] Possible activities, to be checked: 'com.eab.se.MainActivity', 'com.eab.se.com.eab.se.MainActivity'
2019-09-25 05:34:29:764 - [debug] [ADB] Getting focused package and activity
2019-09-25 05:34:29:765 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys window windows'
2019-09-25 05:34:29:871 - [debug] [ADB] Found package: 'com.eab.se' and fully qualified activity name : 'com.eab.se.MainActivity'
2019-09-25 05:34:29:871 - [UiAutomator2] Setting auto webview to context 'WEBVIEW_com.eab.se' with timeout 2000ms
2019-09-25 05:34:29:872 - [debug] [AndroidDriver] Getting a list of available webviews
2019-09-25 05:34:29:873 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell cat /proc/net/unix'
2019-09-25 05:34:29:922 - [AndroidDriver] Not checking whether webviews have active pages; use the 'ensureWebviewsHavePages' cap to turn this check on
2019-09-25 05:34:29:923 - [debug] [AndroidDriver] WEBVIEW_27550 mapped to pid 27550
2019-09-25 05:34:29:923 - [debug] [AndroidDriver] Getting process name for webview
2019-09-25 05:34:29:923 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell ps'
2019-09-25 05:34:30:052 - [debug] [AndroidDriver] Parsed pid: '27550' pkg: 'com.eab.se' from
2019-09-25 05:34:30:053 - [debug] [AndroidDriver] USER PID PPID VSIZE RSS WCHAN PC NAME
2019-09-25 05:34:30:053 - [debug] [AndroidDriver] u0_a1089 27550 585 1220436 120068 futex_wait 00000000 S com.eab.se
2019-09-25 05:34:30:053 - [debug] [AndroidDriver] Returning process name: 'com.eab.se'
2019-09-25 05:34:30:053 - [debug] [AndroidDriver] Found webviews: ["WEBVIEW_com.eab.se"]
2019-09-25 05:34:30:054 - [debug] [AndroidDriver] Available contexts: ["NATIVE_APP","WEBVIEW_com.eab.se"]
2019-09-25 05:34:30:054 - [debug] [AndroidDriver] Connecting to chrome-backed webview context 'WEBVIEW_com.eab.se'
2019-09-25 05:34:30:061 - [debug] [AndroidDriver] A port was not given, using random free port: 8000
2019-09-25 05:34:30:061 - [debug] [AndroidDriver] Automated Chromedriver download is disabled. Use 'chromedriver_autodownload' server feature to enable it
2019-09-25 05:34:30:062 - [debug] [AndroidDriver] Before starting chromedriver, androidPackage is 'com.eab.se'
2019-09-25 05:34:30:063 - [debug] [Chromedriver] Changed state to 'starting'
2019-09-25 05:34:30:067 - [debug] [Chromedriver] Found 1 executable in '/usr/local/lib/node_modules/appium/node_modules/appium-chromedriver/chromedriver/mac'
2019-09-25 05:34:30:092 - [debug] [Chromedriver] The following Chromedriver executables were found:
2019-09-25 05:34:30:092 - [debug] [Chromedriver] '/usr/local/lib/node_modules/appium/node_modules/appium-chromedriver/chromedriver/mac/chromedriver' (version '77.0.3865.40', minimum Chrome version 'Unknown')
2019-09-25 05:34:30:093 - [debug] [ADB] Getting package info for 'com.android.chrome'
2019-09-25 05:34:30:093 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ shell dumpsys package com.android.chrome'
2019-09-25 05:34:30:175 - [debug] [Chromedriver] Found Chrome bundle 'com.android.chrome' version '77.0.3865'
2019-09-25 05:34:30:176 - [Chromedriver] No known Chromedriver available to automate Chrome version '77.0.3865'.
2019-09-25 05:34:30:176 - [Chromedriver] Using Chromedriver version '77.0.3865.40', which has not been tested with Appium. You could also try to enable automated chromedrivers download server feature
2019-09-25 05:34:30:176 - [Chromedriver] Set chromedriver binary as: /usr/local/lib/node_modules/appium/node_modules/appium-chromedriver/chromedriver/mac/chromedriver
2019-09-25 05:34:30:176 - [debug] [Chromedriver] Killing any old chromedrivers, running: pkill -15 -f "/usr/local/lib/node_modules/appium/node_modules/appium-chromedriver/chromedriver/mac/chromedriver.*--port=8000"
2019-09-25 05:34:30:204 - [Chromedriver] No old chromedrivers seem to exist
2019-09-25 05:34:30:204 - [debug] [Chromedriver] Cleaning any old adb forwarded port socket connections
2019-09-25 05:34:30:204 - [debug] [ADB] List forwarding ports
2019-09-25 05:34:30:204 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ forward --list'
2019-09-25 05:34:30:212 - [debug] [ADB] Removing forwarded port socket connection: 59071
2019-09-25 05:34:30:213 - [debug] [ADB] Running '/Users/mshirodkar/Library/Android/sdk/platform-tools/adb -P 5037 -s ZY223DDFLZ forward --remove tcp\:59071'
2019-09-25 05:34:30:220 - [Chromedriver] Spawning chromedriver with: /usr/local/lib/node_modules/appium/node_modules/appium-chromedriver/chromedriver/mac/chromedriver --url-base=wd/hub --port=8000 --adb-port=5037 --verbose
2019-09-25 05:34:30:238 - [debug] [Chromedriver] Chromedriver version: '77.0.3865.40'
2019-09-25 05:34:30:240 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-09-25 05:34:30:241 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8000/wd/hub/status] with no body
2019-09-25 05:34:30:243 - [debug] [WD Proxy] Got response with status 200: {"value":{"build":{"version":"77.0.3865.40 (f484704e052e0b556f8030b65b953dce96503217-refs/branch-heads/3865@{#442})"},"message":"ChromeDriver ready for new sessions.","os":{"arch":"x86_64","name":"Mac OS X","version":"10.14.5"},"ready":true}}
2019-09-25 05:34:30:244 - [Chromedriver] Starting W3C Chromedriver session with capabilities: {
2019-09-25 05:34:30:244 - [Chromedriver] "capabilities": {
2019-09-25 05:34:30:244 - [Chromedriver] "alwaysMatch": {
2019-09-25 05:34:30:245 - [Chromedriver] "goog:chromeOptions": {
2019-09-25 05:34:30:245 - [Chromedriver] "androidPackage": "com.eab.se",
2019-09-25 05:34:30:245 - [Chromedriver] "androidUseRunningApp": true,
2019-09-25 05:34:30:245 - [Chromedriver] "androidDeviceSerial": "ZY223DDFLZ"
2019-09-25 05:34:30:245 - [Chromedriver] },
2019-09-25 05:34:30:245 - [Chromedriver] "goog:loggingPrefs": {
2019-09-25 05:34:30:245 - [Chromedriver] "browser": "ALL"
2019-09-25 05:34:30:245 - [Chromedriver] }
2019-09-25 05:34:30:245 - [Chromedriver] }
2019-09-25 05:34:30:245 - [Chromedriver] }
2019-09-25 05:34:30:245 - [Chromedriver] }
2019-09-25 05:34:30:245 - [debug] [WD Proxy] Matched '/session' to command name 'createSession'
2019-09-25 05:34:30:246 - [debug] [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8000/wd/hub/session] with body: {"capabilities":{"alwaysMatch":{"goog:chromeOptions":{"androidPackage":"com.eab.se","androidUseRunningApp":true,"androidDeviceSerial":"ZY223DDFLZ"},"goog:loggingPrefs":{"browser":"ALL"}}}}
2019-09-25 05:34:31:519 - [debug] [Chromedriver] Webview version: 'Chrome/77.0.3865.73'
2019-09-25 05:34:33:122 - [debug] [WD Proxy] Got response with status 200: {"value":{"capabilities":{"acceptInsecureCerts":false,"browserName":"chrome","browserVersion":"77.0.3865.73","chrome":{"chromedriverVersion":"77.0.3865.40 (f484704e052e0b556f8030b65b953dce96503217-refs/branch-heads/3865@{#442})"},"goog:chromeOptions":{"debuggerAddress":"localhost:59403"},"pageLoadStrategy":"normal","platformName":"android","proxy":{},"setWindowRect":false,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"},"sessionId":"3837aec517ac7cf0fd344f963a7536be"}}
2019-09-25 05:34:33:122 - [WD Proxy] Determined the downstream protocol as 'W3C'
2019-09-25 05:34:33:122 - [debug] [Chromedriver] Changed state to 'online'
2019-09-25 05:34:33:124 - [debug] [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8209/wd/hub/session/cd7308cf-db8c-482f-8858-e5b915a8411c/appium/device/pixel_ratio] with body: {}
2019-09-25 05:34:33:149 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"cd7308cf-db8c-482f-8858-e5b915a8411c","status":0,"value":3}
2019-09-25 05:34:33:150 - [debug] [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
2019-09-25 05:34:33:151 - [debug] [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8209/wd/hub/session/cd7308cf-db8c-482f-8858-e5b915a8411c/appium/device/system_bars] with body: {}
2019-09-25 05:34:33:173 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"cd7308cf-db8c-482f-8858-e5b915a8411c","status":0,"value":{"statusBar":72}}
2019-09-25 05:34:33:174 - [debug] [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize'
2019-09-25 05:34:33:174 - [debug] [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8209/wd/hub/session/cd7308cf-db8c-482f-8858-e5b915a8411c/window/current/size] with body: {}
2019-09-25 05:34:33:199 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"cd7308cf-db8c-482f-8858-e5b915a8411c","status":0,"value":{"height":1776,"width":1080}}
2019-09-25 05:34:33:200 - [Appium] New AndroidUiautomator2Driver session created successfully, session 484dd327-552a-4474-a0dd-c97b83caefc4 added to master session list
2019-09-25 05:34:33:201 - [debug] [BaseDriver] Event 'newSessionStarted' logged at 1569389673201 (11:04:33 GMT+0530 (India Standard Time))
2019-09-25 05:34:33:202 - [debug] [MJSONWP (484dd327)] Cached the protocol value 'MJSONWP' for the new session 484dd327-552a-4474-a0dd-c97b83caefc4
2019-09-25 05:34:33:203 - [debug] [MJSONWP (484dd327)] Responding to client with driver.createSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"Moto G4"},"app":"/Users/mshirodkar/Desktop/Git/DemoMobile/WorkSpace_Test/app/trilamb.apk","newCommandTimeout":500,"platformVersion":"7.0","automationName":"uiautomator2","autoGrantPermissions":true,"autoWebview":true,"handlesAlerts":true,"platformName":"Android","deviceName":"ZY223DDFLZ","deviceUDID":"ZY223DDFLZ","appPackage":"com.eab.se","deviceApiLevel":24,"deviceScreenSize":"1080x1920","deviceScreenDensity":480,"deviceModel":"Moto G (4)","deviceManufacturer":"motorola","pixelRatio":3,"statBarHeight":72,"viewportRect":{"left":0,"top":72,"wi...
2019-09-25 05:34:33:207 - [HTTP] <-- POST /wd/hub/session 200 24149 ms - 1118
2019-09-25 05:34:33:207 - [HTTP]
2019-09-25 05:34:33:247 - [HTTP] --> GET /wd/hub/session/484dd327-552a-4474-a0dd-c97b83caefc4
2019-09-25 05:34:33:247 - [HTTP] {}
2019-09-25 05:34:33:249 - [MJSONWP (484dd327)] Driver proxy active, passing request on via HTTP proxy
2019-09-25 05:34:33:275 - [debug] [WD Proxy] Matched '/wd/hub/session/484dd327-552a-4474-a0dd-c97b83caefc4' to command name 'getSession'
2019-09-25 05:34:33:275 - [debug] [WD Proxy] Proxying [GET /wd/hub/session/484dd327-552a-4474-a0dd-c97b83caefc4] to [GET http://127.0.0.1:8000/wd/hub/session/3837aec517ac7cf0fd344f963a7536be] with body: {}
2019-09-25 05:34:33:278 - [debug] [WD Proxy] Got response with status 404: {"value":{"error":"unknown command","message":"unknown command: Cannot call non W3C standard command while in W3C mode","stacktrace":"0 chromedriver 0x000000010d42bf09 chromedriver + 3694345\n1 chromedriver 0x000000010d3b8fd3 chromedriver + 3223507\n2 chromedriver 0x000000010d15858f chromedriver + 730511\n3 chromedriver 0x000000010d0c00a1 chromedriver + 106657\n4 chromedriver 0x000000010d0c11d5 chromedriver + 111061\n5 chromedriver 0x000000010d3e707f chromedriver + 3412095\n6 chromedriver 0x000000010d3f3ced chromedriver + 3464429\n7 chromedriver 0x000000010d3f3a78 chromedriver + 3463800\n8 chromedriver 0x000000010d3c6959 chromedriver + 3279193\n9 chromedriver 0x000000010d3f4548 chromedriver + 3466568\n10 chromedriver 0x000000010d3db1b7 c...
2019-09-25 05:34:33:283 - [debug] [MJSONWP (484dd327)] Encountered internal error running command: ProxyRequestError: Could not proxy command to remote server. Original error: 404 - "{\"value\":{\"error\":\"unknown command\",\"message\":\"unknown command: Cannot call non W3C standard command while in W3C mode\",\"stacktrace\":\"0 chromedriver 0x000000010d42bf09 chromedriver + 3694345\\n1 chromedriver 0x000000010d3b8fd3 chromedriver + 3223507\\n2 chromedriver 0x000000010d15858f chromedriver + 730511\\n3 chromedriver 0x000000010d0c00a1 chromedriver + 106657\\n4 chromedriver 0x000000010d0c11d5 chromedriver + 111061\\n5 chromedriver 0x000000010d3e707f chromedriver + 3412095\\n6 chromedriver 0x000000010d3f3ced chromedriver + 3464429\\n7 chromedriver 0x000000010d3f3a78 chromedriver + 3463800\\n8 chromedriver 0x000000010d3c6959 chromedriver + 3279193\\n9 chromedriver 0x000000010d3f4548 chromedriver + 3466568\\n10 chromedriver 0x000000010d3db1b7 chromedriver + 3363255\\n11 chromedriver 0x000000010d412474 chromedriver + 3589236\\n12 chromedriver 0x000000010d431ce7 chromedriver + 3718375\\n13 libsystem_pthread.dylib 0x00007fff7024d2eb _pthread_body + 126\\n14 libsystem_pthread.dylib 0x00007fff70250249 _pthread_start + 66\\n15 libsystem_pthread.dylib 0x00007fff7024c40d thread_start + 13\\n\"}}"
2019-09-25 05:34:33:283 - [debug] [MJSONWP (484dd327)] at JWProxy.proxy (/usr/local/lib/node_modules/appium/node_modules/appium-base-driver/lib/jsonwp-proxy/proxy.js:213:13)
2019-09-25 05:34:33:283 - [debug] [W3C] Matched W3C error code 'unknown command' to UnknownCommandError
2019-09-25 05:34:33:285 - [HTTP] <-- GET /wd/hub/session/484dd327-552a-4474-a0dd-c97b83caefc4 500 37 ms - 157
2019-09-25 05:34:33:285 - [HTTP]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment