appium服务日志解析

> Launching Appium server with command: C:\Program Files (x86)\Appium\node.exe lib\server\main.js --address 127.0.0.1 --port 4723 --platform-name Android --platform-version 23 --automation-name Appium --log-no-color
//启动appium服务器
> info: Welcome to Appium v1.4.16 (REV ae6877eff263066b26328d457bd285c0cc62430d)
> info: Appium REST http interface listener started on 127.0.0.1:4723
//设置监听端口
> info: [debug] Non-default server args: {"address":"127.0.0.1","logNoColors":true,"platformName":"Android","platformVersion":"23","automationName":"Appium"}
> info: Console LogLevel: debug
> info: --> POST /wd/hub/session {"desiredCapabilities":{"deviceName":"2a2d811","app":"F:\\appium\\apps\\jHD_help-debug-v1.7.0.9.apk","platformVersion":"4.4","appPackage":"com.jhd.help","platformName":"Android","appActivity":".module.welcome.SplashActivity"}}
> info: Client User-Agent string: Python-urllib/2.7
> info: [debug] Using local app from desired caps: F:\appium\apps\jHD_help-debug-v1.7.0.9.apk
> info: [debug] Creating new appium session 8393a3ff-e259-4976-9d4d-db473047f2bf//根据客户端在desiredCapabilities里指定的内容，建立一个客户端与服务器通信的session；
> info: Starting android appium
> info: [debug] Getting Java version
> info: Java version is: 1.8.0_20
> info: [debug] Checking whether adb is present
> info: [debug] Using adb from F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe
> info: [debug] Using fast reset? true
> info: [debug] Preparing device for session
> info: [debug] Checking whether app is actually present
> info: Retrieving device
> info: [debug] Trying to find a connected android device
> info: [debug] Getting connected devices...
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe devices
> info: [debug] 1 device(s) connected
> info: Found device 2a2d811
> info: [debug] Setting device id to 2a2d811
> info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 5)
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 wait-for-device
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "echo 'ready'"
> //找到设备并指定该设备
> info: [debug] Starting logcat capture
> info: [debug] Getting device API level
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "getprop ro.build.version.sdk"
> info: [debug] Device is at API Level 19
> info: Device API level is: 19
> info: [debug] Extracting strings for language: default
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "getprop persist.sys.language"
> info: [debug] Current device persist.sys.language: zh
> info: [debug] java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar" "stringsFromApk" "F:\appium\apps\jHD_help-debug-v1.7.0.9.apk" "C:\Users\ritawu\AppData\Local\Temp\com.jhd.help" zh
> info: [debug] Reading strings from converted strings.json
> info: [debug] Setting language to default
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 push "C:\\Users\\ritawu\\AppData\\Local\\Temp\\com.jhd.help\\strings.json" /data/local/tmp
> info: [debug] Checking whether aapt is present
> info: [debug] Using aapt from F:\adt-bundle-windows-x86_64-20140702\sdk\build-tools\android-4.4W\aapt.exe
> info: [debug] Retrieving process from manifest.
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\build-tools\android-4.4W\aapt.exe dump xmltree F:\appium\apps\jHD_help-debug-v1.7.0.9.apk AndroidManifest.xml
> info: [debug] Set app process to: com.jhd.help
> info: [debug] Not uninstalling app since server not started with --full-reset
> info: [debug] Checking app cert for F:\appium\apps\jHD_help-debug-v1.7.0.9.apk.
> info: [debug] executing cmd: java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar" F:\appium\apps\jHD_help-debug-v1.7.0.9.apk
> info: [debug] App already signed.
> info: [debug] Zip-aligning F:\appium\apps\jHD_help-debug-v1.7.0.9.apk
> info: [debug] Checking whether zipalign is present
> info: [debug] Using zipalign from F:\adt-bundle-windows-x86_64-20140702\sdk\build-tools\android-4.4W\zipalign.exe
> info: [debug] Zip-aligning apk.
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\build-tools\android-4.4W\zipalign.exe -f 4 F:\appium\apps\jHD_help-debug-v1.7.0.9.apk C:\Users\ritawu\AppData\Local\Temp\11626-11760-1y4n699\appium.tmp
> info: [debug] MD5 for app is efb07fbd0cee8519f9e4dba25a9a71b2
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "ls /data/local/tmp/efb07fbd0cee8519f9e4dba25a9a71b2.apk"
> info: [debug] Getting install status for com.jhd.help
> info: [debug] Getting device API level
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "getprop ro.build.version.sdk"
> info: [debug] Device is at API Level 19
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "pm list packages -3 com.jhd.help"
> info: [debug] App is installed
> info: App is already installed, resetting app
> info: [debug] Running fast reset (stop and clear)
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "am force-stop com.jhd.help"
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "pm clear com.jhd.help"
> info: [debug] Forwarding system:4724 to device:4724
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 forward tcp:4724 tcp:4724
> info: [debug] Pushing appium bootstrap to device...
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 push "C:\\Program Files (x86)\\Appium\\node_modules\\appium\\build\\android_bootstrap\\AppiumBootstrap.jar" /data/local/tmp/
> info: [debug] Pushing settings apk to device...
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 install "C:\Program Files (x86)\Appium\node_modules\appium\build\settings_apk\settings_apk-debug.apk"
> info: [debug] Pushing unlock helper app to device...
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 install "C:\Program Files (x86)\Appium\node_modules\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 cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "ps 'uiautomator'"
> info: [debug] No matching processes found
> info: [debug] Running bootstrap
> info: [debug] spawning: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap -e pkg com.jhd.help -e disableAndroidWatchers false
> 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] Waking up device if it's not alive
> info: [debug] Pushing command to appium work queue: ["wake",{}]
> info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
> info: [debug] [BOOTSTRAP] [debug] Client connected
> 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 cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "dumpsys window"
> 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 cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "getprop ro.build.version.sdk"
> info: [debug] Device is at API Level 19
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.jhd.help/.module.welcome.SplashActivity"
> info: [debug] Waiting for pkg "com.jhd.help" and activity ".module.welcome.SplashActivity" to be focused
> info: [debug] Getting focused package and activity
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "dumpsys window windows"
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 shell "getprop ro.build.version.release"
> info: [debug] Device is at release version 4.4.4
> info: [debug] Device launched! Ready for commands
> info: [debug] Setting command timeout to the default of 60 secs
> info: [debug] Appium session started with sessionId 8393a3ff-e259-4976-9d4d-db473047f2bf
> info: <-- POST /wd/hub/session 303 15644.871 ms - 74
> info: --> GET /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf {}
> info: [debug] Responding to client with success: {"status":0,"value":{"platform":"LINUX","browserName":"Android","platformVersion":"4.4.4","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"deviceName":"2a2d811","app":"F:\\appium\\apps\\jHD_help-debug-v1.7.0.9.apk","platformVersion":"4.4","appPackage":"com.jhd.help","platformName":"Android","appActivity":".module.welcome.SplashActivity"},"deviceName":"2a2d811","app":"F:\\appium\\apps\\jHD_help-debug-v1.7.0.9.apk","appPackage":"com.jhd.help","platformName":"Android","appActivity":".module.welcome.SplashActivity"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- GET /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf 200 22.143 ms - 707 {"status":0,"value":{"platform":"LINUX","browserName":"Android","platformVersion":"4.4.4","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"deviceName":"2a2d811","app":"F:\\appium\\apps\\jHD_help-debug-v1.7.0.9.apk","platformVersion":"4.4","appPackage":"com.jhd.help","platformName":"Android","appActivity":".module.welcome.SplashActivity"},"deviceName":"2a2d811","app":"F:\\appium\\apps\\jHD_help-debug-v1.7.0.9.apk","appPackage":"com.jhd.help","platformName":"Android","appActivity":".module.welcome.SplashActivity"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","actions":[{"action":"press","options":{"y":200,"x":680}},{"action":"wait","options":{"ms":1500}},{"action":"moveTo","options":{"y":680,"x":10}},{"action":"release","options":{}}]}
> info: [debug] Pushing command to appium work queue: ["swipe",{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"swipe","params":{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: swipe
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Swiping from [x=680.0, y=200.0] to [x=10.0, y=680.0] with steps: 42
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform 200 779.323 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","actions":[{"action":"press","options":{"y":200,"x":680}},{"action":"wait","options":{"ms":1500}},{"action":"moveTo","options":{"y":680,"x":10}},{"action":"release","options":{}}]}
> info: [debug] Pushing command to appium work queue: ["swipe",{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"swipe","params":{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: swipe
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Swiping from [x=680.0, y=200.0] to [x=10.0, y=680.0] with steps: 42
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform 200 744.592 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","actions":[{"action":"press","options":{"y":200,"x":680}},{"action":"wait","options":{"ms":1500}},{"action":"moveTo","options":{"y":680,"x":10}},{"action":"release","options":{}}]}
> info: [debug] Pushing command to appium work queue: ["swipe",{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"swipe","params":{"startX":680,"startY":200,"endX":10,"endY":680,"steps":42}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: swipe
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Display bounds: [0,0][720,1280]
> info: [debug] [BOOTSTRAP] [debug] Swiping from [x=680.0, y=200.0] to [x=10.0, y=680.0] with steps: 42
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/touch/perform 200 728.884 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/btn_instant_start"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/btn_instant_start","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/btn_instant_start","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/btn_instant_start using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/btn_instant_start]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"1"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"1"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 953.033 ms - 87 {"status":0,"value":{"ELEMENT":"1"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/1/click {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","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] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/1/click 200 1092.649 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/sEtUserName"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/sEtUserName","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/sEtUserName","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/sEtUserName using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/sEtUserName]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"2"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"2"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 641.508 ms - 87 {"status":0,"value":{"ELEMENT":"2"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/sEtPassword"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/sEtPassword","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/sEtPassword","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/sEtPassword using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/sEtPassword]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"3"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"3"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 517.630 ms - 87 {"status":0,"value":{"ELEMENT":"3"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/login_btn"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/login_btn","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/login_btn","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/login_btn using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/login_btn]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"4"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"4"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 46.730 ms - 87 {"status":0,"value":{"ELEMENT":"4"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/regist_text"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/regist_text","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/regist_text","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/regist_text using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/regist_text]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"5"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 27.807 ms - 87 {"status":0,"value":{"ELEMENT":"5"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/2/value {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"2","value":["1","3","4","2","5","1","5","2","5","1","5"]}
> info: [debug] Pushing command to appium work queue: ["element:setText",{"elementId":"2","text":"13425152515","replace":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:setText","params":{"elementId":"2","text":"13425152515","replace":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: setText
> info: [debug] [BOOTSTRAP] [debug] Using element passed in.
> info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().
> info: [debug] [BOOTSTRAP] [debug] Text remains after clearing, but it appears to be hint text.
> info: [debug] [BOOTSTRAP] [debug] Text not cleared. Assuming remainder is hint text.
> info: [debug] [BOOTSTRAP] [debug] Sending plain text to element: 13425152515
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/2/value 200 5774.192 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/3/value {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"3","value":["1","2","3","4","5","6"]}
> info: [debug] Pushing command to appium work queue: ["element:setText",{"elementId":"3","text":"123456","replace":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:setText","params":{"elementId":"3","text":"123456","replace":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: setText
> info: [debug] [BOOTSTRAP] [debug] Using element passed in.
> info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().
> info: [debug] [BOOTSTRAP] [debug] Sending plain text to element: 123456
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/3/value 200 7012.309 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/4/click {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"4"}
> info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"4"}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"4"}}
> 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":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/4/click 200 655.841 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/setting_image"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/setting_image","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/setting_image","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/setting_image using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/setting_image]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"6"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"6"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 43.434 ms - 87 {"status":0,"value":{"ELEMENT":"6"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/6/click {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"6"}
> info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"6"}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"6"}}
> 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":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/6/click 200 383.845 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/setting_image"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/setting_image","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/setting_image","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/setting_image using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/setting_image]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"7"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"7"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 64.200 ms - 87 {"status":0,"value":{"ELEMENT":"7"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/my_message_text"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/my_message_text","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/my_message_text","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/my_message_text using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/my_message_text]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"8"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"8"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 68.391 ms - 87 {"status":0,"value":{"ELEMENT":"8"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/set_layout"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/set_layout","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/set_layout","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/set_layout using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/set_layout]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"9"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"9"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 112.252 ms - 87 {"status":0,"value":{"ELEMENT":"9"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/9/click {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"9"}
> info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"9"}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"9"}}
> 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":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/9/click 200 462.951 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element {"using":"id","sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","value":"com.jhd.help:id/quit_account_btn"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: ["find",{"strategy":"id","selector":"com.jhd.help:id/quit_account_btn","context":"","multiple":false}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.jhd.help:id/quit_account_btn","context":"","multiple":false}}
> info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
> info: [debug] [BOOTSTRAP] [debug] Got command action: find
> info: [debug] [BOOTSTRAP] [debug] Finding com.jhd.help:id/quit_account_btn using ID with the contextId:  multiple: false
> info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.jhd.help:id/quit_account_btn]
> info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":{"ELEMENT":"10"},"status":0}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"10"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element 200 83.964 ms - 88 {"status":0,"value":{"ELEMENT":"10"},"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/10/click {"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf","id":"10"}
> info: [debug] Pushing command to appium work queue: ["element:click",{"elementId":"10"}]
> info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"10"}}
> 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":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- POST /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf/element/10/click 200 1718.197 ms - 76 {"status":0,"value":true,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: --> DELETE /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf {}
> info: Shutting down appium session
> info: [debug] Pressing the HOME button
> info: [debug] executing cmd: F:\adt-bundle-windows-x86_64-20140702\sdk\platform-tools\adb.exe -s 2a2d811 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: 67.557
> 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":"8393a3ff-e259-4976-9d4d-db473047f2bf"}
> info: <-- DELETE /wd/hub/session/8393a3ff-e259-4976-9d4d-db473047f2bf 200 1530.680 ms - 76 {"status":0,"value":null,"sessionId":"8393a3ff-e259-4976-9d4d-db473047f2bf"}