AppiumServer.log

 node .
info: Welcome to Appium v1.2.4 (REV 71584425ecf777349e3215f0f18e71e7782acfb6)
info: Appium REST http interface listener started on 0.0.0.0:4723
info: Console LogLevel: debug
info: --> POST /wd/hub/session {"desiredCapabilities":{"newCommandTimeout":"300","automationName":"Appium","app":"/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk","platformVersion":"4.4.2","deviceName":"Android","platformName":"Android","appActivity":".HomeScreenActivity","browserName":"","deviceReadyTimeout":"300","appPackage":"io.selendroid.testapp"}}
info: [debug] Using local app from desired caps: /home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk
info: [debug] Creating new appium session 109a2476-0907-4d28-8c26-185dc2312026
info: Starting android appium
info: [debug] Using fast reset? true
info: [debug] Preparing device for session
info: [debug] Checking whether app is actually present
info: [debug] Checking whether adb is present
info: [debug] Using adb from /opt/android-sdk-linux/platform-tools/adb
info: Retrieving device
info: [debug] Trying to find a connected android device
info: [debug] Getting connected devices...
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" devices
info: [debug] 1 device(s) connected
info: Found device 08fdc373006a020b
info: [debug] Setting device id to 08fdc373006a020b
info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 300)
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b wait-for-device
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "echo 'ready'"
info: [debug] Starting logcat capture
info: [debug] Getting device API level
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "getprop ro.build.version.sdk"
info: [debug] Device is at API Level 19
info: Device API level is: 19
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "getprop persist.sys.language"
info: [debug] Current device language: en
info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk" "/tmp/io.selendroid.testapp" en
info: [debug] No strings.xml for language 'en', getting default strings.xml
info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk" "/tmp/io.selendroid.testapp"
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b push "/tmp/io.selendroid.testapp/strings.json" /data/local/tmp
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from /opt/android-sdk-linux/build-tools/19.1.0/aapt
info: [debug] processFromManifest: "/opt/android-sdk-linux/build-tools/19.1.0/aapt" dump xmltree "/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk" AndroidManifest.xml
info: [debug] Set app process to: io.selendroid.testapp
info: [debug] Not uninstalling app since server not started with --full-reset
info: [debug] Checking app cert for /home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk: java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/verify.jar" "/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk"
info: [debug] App already signed.
info: [debug] Zip-aligning /home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from /opt/android-sdk-linux/build-tools/19.1.0/zipalign
info: [debug] zipAlignApk: "/opt/android-sdk-linux/build-tools/19.1.0/zipalign" -f 4 "/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk" "/tmp/appium11498-11802-1coky9v.tmp"
info: [debug] MD5 for app is 2fcae5dd65dc8344c1689db2cae09dea
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "ls /data/local/tmp/2fcae5dd65dc8344c1689db2cae09dea.apk"
info: [debug] Getting install status for io.selendroid.testapp
info: [debug] Getting device API level
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "getprop ro.build.version.sdk"
info: [debug] Device is at API Level 19
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "pm list packages -3 io.selendroid.testapp"
info: [debug] App is installed
info: App is already installed, resetting app
info: [debug] Running fast reset (stop and clear)
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "am force-stop io.selendroid.testapp"
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "pm clear io.selendroid.testapp"
info: [debug] Forwarding system:4724 to device:4724
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b forward tcp:4724 tcp:4724
info: [debug] Pushing appium bootstrap to device...
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b push "/home/adminuser/Java_Projects/Appium/appium/build/android_bootstrap/AppiumBootstrap.jar" /data/local/tmp/
info: [debug] Pushing settings apk to device...
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b install "/home/adminuser/Java_Projects/Appium/appium/build/settings_apk/settings_apk-debug.apk"
info: [debug] Pushing unlock helper app to device...
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b install "/home/adminuser/Java_Projects/Appium/appium/build/unlock_apk/unlock_apk-debug.apk"
info: Starting App
info: [debug] Attempting to kill all 'uiautomator' processes
info: [debug] Getting all processes with 'uiautomator'
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "ps 'uiautomator'"
info: [debug] No matching processes found
info: [debug] Running bootstrap
info: [debug] spawning: /opt/android-sdk-linux/platform-tools/adb -s 08fdc373006a020b shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
info: [debug] [UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 1
info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724
info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready
info: [debug] [BOOTSTRAP] [debug] Loading json...
info: [debug] [BOOTSTRAP] [debug] json loading complete.
info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
info: [debug] [BOOTSTRAP] [debug] Client connected
info: [debug] Waking up device if it's not alive
info: [debug] Pushing command to appium work queue: ["wake",{}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"wake","params":{}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: wake
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "dumpsys window"
info: [debug] Writing dumpsys output to /home/adminuser/Java_Projects/Appium/appium/.dumpsys.log
info: [debug] Screen already unlocked, continuing.
info: [debug] Pushing command to appium work queue: ["getDataDir",{}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"getDataDir","params":{}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"\/data\/local\/tmp","status":0}
info: [debug] dataDir set to: /data/local/tmp
info: [debug] Pushing command to appium work queue: ["compressedLayoutHierarchy",{"compressLayout":false}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"compressedLayoutHierarchy","params":{"compressLayout":false}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":false,"status":0}
info: [debug] Getting device API level
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "getprop ro.build.version.sdk"
info: [debug] Device is at API Level 19
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n io.selendroid.testapp/.HomeScreenActivity"
info: [debug] Waiting for pkg "io.selendroid.testapp" and activity ".HomeScreenActivity" to be focused
info: [debug] Getting focused package and activity
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "dumpsys window windows"
info: [debug] Device launched! Ready for commands
info: [debug] Setting command timeout to 300 secs
info: [debug] Appium session started with sessionId 109a2476-0907-4d28-8c26-185dc2312026
info: <-- POST /wd/hub/session 303 8444.904 ms - 9 
info: --> GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026 {}
info: [debug] Responding to client with success: {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"newCommandTimeout":"300","automationName":"Appium","app":"/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk","platformVersion":"4.4.2","deviceName":"Android","platformName":"Android","appActivity":".HomeScreenActivity","browserName":"","deviceReadyTimeout":"300","appPackage":"io.selendroid.testapp"},"newCommandTimeout":"300","automationName":"Appium","app":"/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk","deviceName":"Android","platformName":"Android","appActivity":".HomeScreenActivity","deviceReadyTimeout":"300","appPackage":"io.selendroid.testapp"},"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026 200 6.226 ms - 977 {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"newCommandTimeout":"300","automationName":"Appium","app":"/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk","platformVersion":"4.4.2","deviceName":"Android","platformName":"Android","appActivity":".HomeScreenActivity","browserName":"","deviceReadyTimeout":"300","appPackage":"io.selendroid.testapp"},"newCommandTimeout":"300","automationName":"Appium","app":"/home/adminuser/Java_Projects/AppiumTestAutomation/target/test-classes/selendroid-test-app.apk","deviceName":"Android","platformName":"Android","appActivity":".HomeScreenActivity","deviceReadyTimeout":"300","appPackage":"io.selendroid.testapp"},"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":3000}
info: [debug] Set Android implicit wait to 3000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 3.126 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 11.454 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"id","value":"io.selendroid.testapp:id/buttonStartWebview"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"io.selendroid.testapp:id/buttonStartWebview","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"io.selendroid.testapp:id/buttonStartWebview","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding io.selendroid.testapp:id/buttonStartWebview using ID with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE_ID=io.selendroid.testapp:id/buttonStartWebview]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE_ID=io.selendroid.testapp:id/buttonStartWebview]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE_ID=io.selendroid.testapp:id/buttonStartWebview]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=1, RESOURCE_ID=io.selendroid.testapp:id/buttonStartWebview]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"1"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"1"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 350.115 ms - 89 {"status":0,"value":[{"ELEMENT":"1"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.119 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/1/click {"id":"1"}
info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"1"}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"1"}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: click
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/1/click 200 572.746 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: --> GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/contexts {}
info: [debug] Getting a list of available webviews
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "cat /proc/net/unix"
info: [debug] Available contexts: 
info: [debug] []
info: [debug] Available contexts: NATIVE_APP
info: [debug] Responding to client with success: {"status":0,"value":["NATIVE_APP"],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/contexts 200 70.049 ms - 86 {"status":0,"value":["NATIVE_APP"],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 0.792 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"id","value":"android:id/text1"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"android:id/text1","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"android:id/text1","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding android:id/text1 using ID with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=1, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"2"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"2"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 1649.686 ms - 89 {"status":0,"value":[{"ELEMENT":"2"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.639 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/2/click {"id":"2"}
info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"2"}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"2"}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: click
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/2/click 200 326.397 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 3.346 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"id","value":"android:id/text1"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"android:id/text1","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"android:id/text1","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding android:id/text1 using ID with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=1, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (2)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=2, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (3)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=3, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (4)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=4, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (5)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=5, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (6)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=6, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (7)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=7, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (8)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=8, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (9)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=9, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (10)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=10, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (11)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=11, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (12)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=12, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (13)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=13, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (14)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=14, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (15)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=15, RESOURCE_ID=android:id/text1]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"},{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"},{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"},{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"},{"ELEMENT":"15"},{"ELEMENT":"16"},{"ELEMENT":"17"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"},{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"},{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"},{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"},{"ELEMENT":"15"},{"ELEMENT":"16"},{"ELEMENT":"17"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 1551.003 ms - 321 {"status":0,"value":[{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"},{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"},{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"},{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"},{"ELEMENT":"15"},{"ELEMENT":"16"},{"ELEMENT":"17"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 16.356 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/3/click {"id":"3"}
info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"3"}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"3"}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: click
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/3/click 200 312.123 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.315 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"class name","value":"android.widget.EditText"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"class name","selector":"android.widget.EditText","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"class name","selector":"android.widget.EditText","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding android.widget.EditText using CLASS_NAME with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.EditText]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.EditText]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.EditText, INSTANCE=0]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.EditText, INSTANCE=1]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"18"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"18"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 510.721 ms - 90 {"status":0,"value":[{"ELEMENT":"18"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 1.717 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/18/clear {"id":"18"}
info: [debug] Pushing command to appium work queue: ["element:clear",{"elementId":"18"}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:clear","params":{"elementId":"18"}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: clear
info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/18/clear 200 2934.394 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.776 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"class name","value":"android.widget.EditText"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"class name","selector":"android.widget.EditText","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"class name","selector":"android.widget.EditText","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding android.widget.EditText using CLASS_NAME with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.EditText]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.EditText]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.EditText, INSTANCE=0]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.EditText, INSTANCE=1]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"19"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"19"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 472.458 ms - 90 {"status":0,"value":[{"ELEMENT":"19"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.988 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/19/value {"id":"19","value":["abc@abc.com"]}
info: [debug] Pushing command to appium work queue: ["element:setText",{"elementId":"19","text":"abc@abc.com","replace":false}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:setText","params":{"elementId":"19","text":"abc@abc.com","replace":false}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: setText
info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().
info: [debug] [BOOTSTRAP] [debug] Sending plain text to element: abc@abc.com
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/19/value 200 4107.592 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/screenshot {}
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "/system/bin/rm /data/local/tmp/screenshot.png; /system/bin/screencap -p /data/local/tmp/screenshot.png "
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b pull /data/local/tmp/screenshot.png "/tmp/appium11498-11802-rjr1ln.png"
info: [debug] Responding to client with success: {"status":0,"value":"iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAAAABHNCSVQICAgIfAhkiAAAIABJREFUeJzs3XdwG+eZP/DvLjoBEAB7LyIpkqJIVVOFsiXbiu3YkaO43uTGKWf7cslkJv18Y/9iR4nLTZK7uYszmbtJcpfkbjKJY8stPlsusmRbXRZVSLGIvXeQIFEXu/v7Q9oVFoUESIIEqeczQ5tabHn3xQLc99n3fV4GgAhCCCGEEEIIIYSQFYxd7gIQQgghhBBCCCGELBQFOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiqZe7AGTxMAwDrVaLpKQkGI1GpKWlIT8/H4WFhUhPT4fNZoPJZIJOpwMAeL1euFwuTE5OYmhoCP39/eju7sbIyAicTidcLhd8Ph9EUVzmMyPLRafTwWg0wmQywWazIT09HSzLQhRFTE1NYWxsDNPT05ienobX66VrhRBCCCGEELJsGADUIlnhGIaBRqOByWRCXl4eampqUFFRgaysLCQlJUGn00GtVkOtVkOlUoFlr3bcEUURgiCA4zhwHAefzweXy4WhoSE0NTXh4sWL6O/vx/T0NDiOo8brDYRhGOh0OqxZswa1tbWorKxERkaGHNwArl4/DocD7e3tOH78OJqamuB2uyEIwjKXnhBCCCFkdbj//vvx6quvxvX+Kjc3FykpKfPa1m63o6+vb5FLRMj8UYBjFbBaraiqqkJdXR3Ky8thsVhgMpmg0WjkYEa0BEGQAx1TU1NoaWnBxx9/jMuXL2NycjJOZ0ASCcMwMJlM+MxnPoO6ujoUFRXBZDKBYRjY7XY4HA7odDqkp6dDrVbD5XKhr68PJ0+exLvvvouxsTHwPL/cp0EI...","sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- GET /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/screenshot 200 1078.784 ms - 233266 {"status":0,"value":"iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAAAABHNCSVQICAgIfAhkiAAAIABJREFUeJzs3XdwG+eZP/DvLjoBEAB7LyIpkqJIVVOFsiXbiu3YkaO43uTGKWf7cslkJv18Y/9iR4nLTZK7uYszmbtJcpfkbjKJY8stPlsusmRbXRZVSLGIvXeQIFEXu/v7Q9oVFoUESIIEqeczQ5tabHn3xQLc99n3fV4GgAhCCCGEEEIIIYSQFYxd7gIQQgghhBBCCCGELBQFOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiUYCDEEIIIYQQQgghKx4FOAghhBBCCCGEELLiqZe7AGTxMAwDrVaLpKQkGI1GpKWlIT8/H4WFhUhPT4fNZoPJZIJOpwMAeL1euFwuTE5OYmhoCP39/eju7sbIyAicTidcLhd8Ph9EUVzmMyPLRafTwWg0wmQywWazIT09HSzLQhRFTE1NYWxsDNPT05ienobX66VrhRBCCCGEELJsGADUIlnhGIaBRqOByWRCXl4eampqUFFRgaysLCQlJUGn00GtVkOtVkOlUoFlr3bcEUURgiCA4zhwHAefzweXy4WhoSE0NTXh4sWL6O/vx/T0NDiOo8brDYRhGOh0OqxZswa1tbWorKxERkaGHNwArl4/DocD7e3tOH78OJqamuB2uyEIwjKXnhBCCCFkdbj//vvx6quvxvX+Kjc3FykpKfPa1m63o6+vb5FLRMj8UYBjFbBaraiqqkJdXR3Ky8thsVhgMpmg0WjkYEa0BEGQAx1TU1NoaWnBxx9/jMuXL2NycjJOZ0ASCcMwMJlM+MxnPoO6ujoUFRXBZDKBYRjY7XY4HA7odDqkp6dDrVbD5XKhr68PJ0+exLvvvouxsTHwPL/cp0EI...","sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":0}
info: [debug] Set Android implicit wait to 0ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.239 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements {"using":"class name","value":"android.widget.Button"}
info: [debug] Pushing command to appium work queue: ["find",{"strategy":"class name","selector":"android.widget.Button","context":"","multiple":true}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"class name","selector":"android.widget.Button","context":"","multiple":true}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: find
info: [debug] [BOOTSTRAP] [debug] Finding android.widget.Button using CLASS_NAME with the contextId:  multiple: true
info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.Button]
info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.Button]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=0]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=1]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (2)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=2]
info: [debug] [BOOTSTRAP] [debug] Element[] is null: (3)
info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=3]
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":[{"ELEMENT":"20"},{"ELEMENT":"21"},{"ELEMENT":"22"}],"status":0}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"20"},{"ELEMENT":"21"},{"ELEMENT":"22"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/elements 200 807.492 ms - 124 {"status":0,"value":[{"ELEMENT":"20"},{"ELEMENT":"21"},{"ELEMENT":"22"}],"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait {"ms":1000}
info: [debug] Set Android implicit wait to 1000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/timeouts/implicit_wait 200 2.164 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/21/click {"id":"21"}
info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"21"}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"21"}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: click
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- POST /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026/element/21/click 200 322.683 ms - 76 {"status":0,"value":true,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: --> DELETE /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026 {}
info: Shutting down appium session
info: [debug] Pressing the HOME button
info: [debug] executing: "/opt/android-sdk-linux/platform-tools/adb" -s 08fdc373006a020b shell "input keyevent 3"
info: [debug] Stopping logcat capture
info: [debug] Logcat terminated with code null, signal SIGTERM
info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"shutdown"}
info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN
info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"OK, shutting down","status":0}
info: [debug] [BOOTSTRAP] [debug] Closed client connection
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=.
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 0
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
info: [debug] [UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.
info: [debug] [UIAUTOMATOR STDOUT] Time: 17.906
info: [debug] [UIAUTOMATOR STDOUT] OK (1 test)
info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: -1
info: [debug] Sent shutdown command, waiting for UiAutomator to stop...
info: [debug] UiAutomator shut down normally
info: [debug] Cleaning up android objects
info: [debug] Cleaning up appium session
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}
info: <-- DELETE /wd/hub/session/109a2476-0907-4d28-8c26-185dc2312026 200 698.148 ms - 76 {"status":0,"value":null,"sessionId":"109a2476-0907-4d28-8c26-185dc2312026"}