Appium pattern unlock failing log

appium-unlock.log

./node_modules/.bin/gulp transpile && ./node_modules/.bin/mocha --compilers js:babel-core/register -t 0 test/functional/driver-e2e-specs.js
[08:43:15] Using gulpfile ~/Sauce/appium-android-driver/gulpfile.js
[08:43:16] Starting 'transpile'...
[08:43:30] Finished 'transpile' after 15 s


  createSession
dbug AndroidDriver AndroidDriver version: 1.10.39
WARN BaseDriver The following capabilities were provided, but are not recognized by appium: unlockType, unlockKey.
info BaseDriver Session created with session id: 9d026d3f-7402-4335-a28e-6f030a80770f
dbug AndroidDriver Getting Java version
info AndroidDriver Java version is: 1.8.0_102
info ADB Checking whether adb is present
info ADB Using adb from /Users/vruno/Library/Android/sdk/platform-tools/adb
dbug ADB Trying to find Nexus_S_API_24 emulator
dbug ADB Getting connected emulators
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB 1 emulator(s) connected
dbug ADB Sending telnet command to device: avd name
dbug ADB Getting running emulator port
dbug ADB Socket connection to device created
dbug ADB Socket connection to device ready
dbug ADB Telnet command got response: Nexus_S_API_24
dbug ADB Found emulator Nexus_S_API_24 in port 5554
dbug ADB Setting device id to emulator-5554
dbug AndroidDriver Not launching AVD because it is already running.
info AndroidDriver Using device: emulator-5554
info ADB Checking whether adb is present
info ADB Using adb from /Users/vruno/Library/Android/sdk/platform-tools/adb
dbug ADB Setting device id to emulator-5554
info BaseDriver Using local app '/Users/vruno/Sauce/appium-android-driver/node_modules/android-apidemos/bin/ApiDemos-debug.apk'
dbug AndroidDriver Checking whether app is actually present
info AndroidDriver Starting Android session
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","wait-for-device"]
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","echo","ping"]
dbug Logcat Starting logcat capture
dbug AndroidDriver Pushing settings apk to device...
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","install","/Users/vruno/Sauce/appium-android-driver/node_modules/io.appium.settings/bin/settings_apk-debug.apk"]
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","install","/Users/vruno/Sauce/appium-android-driver/node_modules/io.appium.settings/bin/settings_apk-debug.apk"]
dbug ADB Application '/Users/vruno/Sauce/appium-android-driver/node_modules/io.appium.settings/bin/settings_apk-debug.apk' already installed. Continuing.
dbug AndroidDriver Pushing unlock helper app to device...
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","install","/Users/vruno/Sauce/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk"]
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","install","/Users/vruno/Sauce/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk"]
dbug ADB Application '/Users/vruno/Sauce/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk' already installed. Continuing.
info ADB Getting device platform version
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.release"]
dbug AndroidDriver Parsing package and activity from app manifest
info ADB Checking whether aapt is present
info ADB Using aapt from /Users/vruno/Library/Android/sdk/build-tools/25.0.2/aapt
info ADB Extracting package and launch activity from manifest
dbug ADB badging package: io.appium.android.apis
dbug ADB badging act: io.appium.android.apis.ApiDemos
dbug AndroidDriver Parsed package and activity are: io.appium.android.apis/io.appium.android.apis.ApiDemos
info AndroidDriver Remote apk path is /data/local/tmp/72bd97de5a345d61bde09aed56ec1b2d.apk
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","ls","/data/local/tmp/72bd97de5a345d61bde09aed56ec1b2d.apk"]
dbug AndroidDriver Checking if app is installed
dbug ADB Getting install status for io.appium.android.apis
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","pm","list","packages","io.appium.android.apis"]
dbug ADB App is installed
info AndroidDriver Apk is already on remote and installed, resetting
dbug AndroidDriver Running fast reset (stop and clear)
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","io.appium.android.apis"]
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","pm","clear","io.appium.android.apis"]
dbug AndroidDriver Extracting strings from apk /Users/vruno/Sauce/appium-android-driver/node_modules/android-apidemos/bin/ApiDemos-debug.apk undefined /var/folders/bh/9q5834c17_b8t5_z1hhrv6k00000gn/T/io.appium.android.apis
dbug ADB Extracting strings for language: default
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.sdk"]
dbug ADB Device API level: 24
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","persist.sys.locale"]
dbug ADB Current device property 'persist.sys.locale':
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.product.locale"]
dbug ADB Current device property 'ro.product.locale': en-US
dbug ADB No strings.xml for language 'en', getting default strings.xml
dbug ADB Reading strings from converted strings.json
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","push","/var/folders/bh/9q5834c17_b8t5_z1hhrv6k00000gn/T/io.appium.android.apis/strings.json","/data/local/tmp"]
dbug AndroidBootstrap Watching for bootstrap disconnect
dbug ADB Forwarding system: 4724 to device: 4724
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","forward","tcp:4724","tcp:4724"]
dbug UiAutomator Starting UiAutomator
dbug UiAutomator Moving to state 'starting'
dbug UiAutomator Parsing uiautomator jar
dbug UiAutomator Found jar name: 'AppiumBootstrap.jar'
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","push","/Users/vruno/Sauce/appium-android-driver/node_modules/appium-android-bootstrap/bootstrap/bin/AppiumBootstrap.jar","/data/local/tmp/"]
dbug ADB Attempting to kill all uiautomator processes
dbug ADB Getting all processes with uiautomator
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","ps"]
info ADB No uiautomator process found to kill, continuing...
dbug UiAutomator Starting UIAutomator
dbug ADB Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","uiautomator","runtest","AppiumBootstrap.jar","-c","io.appium.android.bootstrap.Bootstrap","-e","pkg","io.appium.android.apis","-e","disableAndroidWatchers",false,"-e","acceptSslCerts",false]
dbug UiAutomator Moving to state 'online'
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Loading json...
info AndroidBootstrap [BOOTSTRAP LOG] [debug] json loading complete.
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Registered crash watchers.
info AndroidBootstrap Android bootstrap socket is now connected
dbug ADB Getting connected devices...
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Client connected
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window"]
dbug ADB Device API level: 24
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","input_method"]
dbug ADB Pressing the BACK button
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","input","keyevent",4]
dbug AndroidBootstrap Emitting alert message...
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Emitting system alert message
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","wm","dismiss-keyguard"]
dbug AndroidDriver Screen is locked, trying to unlock
info AndroidDriver Trying to unlock device using pattern 159874236
dbug BaseDriver Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
dbug BaseDriver Waiting up to 0 ms for condition
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.android.systemui:id/lockPatternView","context":"","multiple":false}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"id","selector":"com.android.systemui:id/lockPatternView","context":"","multiple":false}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: find
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Finding 'com.android.systemui:id/lockPatternView' using 'ID' with the contextId: '' multiple: false
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=com.android.systemui:id/lockPatternView]
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"ELEMENT":"1"}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
info AndroidDriver Key: 1 - Pos: {"x":102,"y":392}
info AndroidDriver Key: 5 - Pos: {"x":240,"y":530}
info AndroidDriver Key: 9 - Pos: {"x":377,"y":667}
info AndroidDriver Key: 8 - Pos: {"x":240,"y":667}
info AndroidDriver Key: 7 - Pos: {"x":102,"y":667}
info AndroidDriver Key: 4 - Pos: {"x":102,"y":530}
info AndroidDriver Key: 2 - Pos: {"x":240,"y":392}
info AndroidDriver Key: 3 - Pos: {"x":377,"y":392}
info AndroidDriver Key: 6 - Pos: {"x":377,"y":530}
info AndroidDriver Actions ---> [{"action":"longPress","options":{"element":null,"x":102,"y":392}},{"action":"moveTo","options":{"element":"1","x":102,"y":392}},{"action":"moveTo","options":{"element":"1","x":240,"y":530}},{"action":"moveTo","options":{"element":"1","x":377,"y":667}},{"action":"moveTo","options":{"element":"1","x":240,"y":667}},{"action":"moveTo","options":{"element":"1","x":102,"y":667}},{"action":"moveTo","options":{"element":"1","x":102,"y":530}},{"action":"moveTo","options":{"element":"1","x":240,"y":392}},{"action":"moveTo","options":{"element":"1","x":377,"y":392}},{"action":"moveTo","options":{"element":"1","x":377,"y":530}}]
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getLocation","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getLocation
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"x":33,"y":323}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:getSize","params":{"elementId":"1"}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: getSize
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"width":413,"height":413}}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchDown","params":{"x":102,"y":392}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchDown","params":{"x":102,"y":392}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchDown
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Display bounds: [0,0][480,800]
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchDown using element? false x: 102, y: 392
dbug AndroidBootstrap Received command result from bootstrap
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":990}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":135,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":273,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":715}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
dbug AndroidBootstrap Received command result from bootstrap
dbug AndroidBootstrap Sending command to android: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:touchMove","params":{"elementId":"1","x":410,"y":853}}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type ACTION
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command action: touchMove
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Performing TouchMove using element? true x: 239, y: 529
dbug AndroidBootstrap Received command result from bootstrap
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window"]
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window"]
dbug AndroidDriver Shutting down Android driver
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","io.appium.android.apis"]
dbug ADB Pressing the HOME button
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","input","keyevent",3]
dbug AndroidBootstrap Sending command to android: {"cmd":"shutdown"}
dbug AndroidBootstrap Received command result from bootstrap
dbug UiAutomator Shutting down UiAutomator
dbug UiAutomator Moving to state 'stopping'
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"shutdown"}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Got command of type SHUTDOWN
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":"OK, shutting down"}
info AndroidBootstrap [BOOTSTRAP LOG] [debug] Closed client connection
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: numtests=1
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=.
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: current=1
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: 0
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=
dbug AndroidBootstrap [UIAUTO STDOUT] Test results for WatcherResultPrinter=.
dbug AndroidBootstrap [UIAUTO STDOUT] Time: 12.906
dbug AndroidBootstrap [UIAUTO STDOUT] OK (1 test)
dbug AndroidBootstrap [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: -1
dbug UiAutomator UiAutomator shut down normally
dbug UiAutomator Moving to state 'stopped'
dbug ADB Attempting to kill all uiautomator processes
dbug ADB Getting all processes with uiautomator
dbug ADB Getting connected devices...
dbug ADB 1 device(s) connected
dbug ADB Running '/Users/vruno/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","ps"]
info ADB No uiautomator process found to kill, continuing...
dbug UiAutomator Moving to state 'stopped'
dbug Logcat Stopping logcat capture
dbug AndroidDriver Not cleaning generated files. Add `clearSystemFiles` capability if wanted.
    1) should unlock the device using a PATTERN
dbug AndroidDriver Shutting down Android driver
dbug AndroidDriver Called deleteSession but bootstrap wasn't active
dbug AndroidDriver Not cleaning generated files. Add `clearSystemFiles` capability if wanted.


  0 passing (20s)
  1 failing

  1) createSession should unlock the device using a PATTERN:
     Error: Screen did not unlock successfully, retrying
      at Object.callee$2$0$ (lib/android-helpers.js:433:15)
      at tryCatch (node_modules/babel-runtime/regenerator/runtime.js:67:40)
      at GeneratorFunctionPrototype.invoke [as _invoke] (node_modules/babel-runtime/regenerator/runtime.js:315:22)
      at GeneratorFunctionPrototype.prototype.(anonymous function) [as next] (node_modules/babel-runtime/regenerator/runtime.js:100:21)
      at GeneratorFunctionPrototype.invoke (node_modules/babel-runtime/regenerator/runtime.js:136:37)
      at process._tickCallback (internal/process/next_tick.js:103:7)