# Page factory not working in android

**URL:** <https://discuss.appium.io/t/page-factory-not-working-in-android/327>\
**Category:** Issues/Bugs\
**Created:** [September 12, 2014, 6:06am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327 "2014-09-12T06:06:14Z")\
**Posts on this page:** 16\
**Page:** 1

<div class="post-metadata">

**Author:** ![sendlink](https://discuss.appium.io/letter_avatar_proxy/v4/letter/s/bc8723/32.png) [@sendlink](https://discuss.appium.io/u/sendlink)\
**Post date:** [September 12, 2014, 6:06am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/1 "2014-09-12T06:06:14Z")

</div>

```
      appium - 1.2.2

```

java-client - 1.7.0

android - 4.4.2

OS - Windows 7

Following is code

public class Napp

{

@AndroidFindBy(name = “Data usage”)

```
private WebElement setdata;

```

DesiredCapabilities capabilities = new DesiredCapabilities();

capabilities.setCapability(“appPackage”, “com.android.settings”);  
capabilities.setCapability(“appActivity”, “.Settings”);

```
 capabilities.setCapability(MobileCapabilityType.PLATFORM_NAME, "Android");
 capabilities.setCapability(MobileCapabilityType.PLATFORM_VERSION, "4.4.2");
 capabilities.setCapability(MobileCapabilityType.DEVICE_NAME, "Nexus 5");

 driver = new AppiumDriver(new URL("http://0.0.0.0:4723/wd/hub"), capabilities);

 PageFactory.initElements(new AppiumFieldDecorator(driver),Napp.class);

```

setdata.click();

Following is error

java.lang.NullPointerException

```
at org.openqa.selenium.support.ui.ExpectedConditions.elementIfVisible(ExpectedConditions.java:227)

at org.openqa.selenium.support.ui.ExpectedConditions.access$1(ExpectedConditions.java:226)

at org.openqa.selenium.support.ui.ExpectedConditions$7.apply(ExpectedConditions.java:213)

at org.openqa.selenium.support.ui.ExpectedConditions$7.apply(ExpectedConditions.java:1)

at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)

at Napp.testCal(Napp.java:111)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

at java.lang.reflect.Method.invoke(Unknown Source)

at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)

at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)

at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)

at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)

at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)

at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)

at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)

at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)

at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)

at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)

at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)

at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)

at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)

at org.junit.runners.ParentRunner.run(ParentRunner.java:309)

at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:50)

at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38)

at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:467)

at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:683)

at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:390)

at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:197)
```

---

<div class="post-metadata">

**Author:** ![Priyank\_Shah](https://discuss.appium.io/user_avatar/discuss.appium.io/priyank_shah/32/5628_2.png) [@Priyank\_Shah](https://discuss.appium.io/u/Priyank_Shah)\
**Post date:** [September 12, 2014, 8:40am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/2 "2014-09-12T08:40:34Z")

</div>

Hi,

Kindly refer code on following reporsitory.

> <https://github.com/priyankshah217/AppiumTestAutomation/tree/master/src/test/java/com/test>
>
> //github.com/priyankshah217/AppiumTestAutomation/tree/master/src/test/java/com/test

Thanks,  
Priyank Shah

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 12, 2014, 5:43pm UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/4 "2014-09-12T17:43:28Z")

</div>

Hi, Priyank\_Shah!

Your exaples are correct!  
But:

- this way you can’t instantiate MobileElement or RemoteWebElement for example.

- you can’t use TouchActions and MultiTouchActions because you will catch ClassCastExeption

- you can’t use some new annotations implemented for Screen Objects. Read [this tread](https://discuss.appium.io/t/java-client-1-6-0-findby-annotations-vs-driver-find/88).

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 12, 2014, 5:46pm UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/5 "2014-09-12T17:46:28Z")

</div>

Hi, sendlink!

> [@sendlink](#):
>
> PageFactory.initElements(new AppiumFieldDecorator(driver),Napp.class);

It is not a correct way to use AppiumFieldDecorator.

The correct way is

> PageFactory.initElements(new AppiumFieldDecorator(driver),  
> new Napp(params) //there should be an instance, not class  
> );

---

<div class="post-metadata">

**Author:** ![Priyank\_Shah](https://discuss.appium.io/user_avatar/discuss.appium.io/priyank_shah/32/5628_2.png) [@Priyank\_Shah](https://discuss.appium.io/u/Priyank_Shah)\
**Post date:** [September 13, 2014, 10:21am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/6 "2014-09-13T10:21:15Z")

</div>

Thanks sergey for your suggestion. My current scripts are working fine, but since you have highlighted area of improvement so i can work on that. but can you please eloborate more on point no 1 & 3.

About point 2: Since i am using TouchActions which is part of appiumDriver, so i dont think so about catching classcastExceptions. But i faced class cast exception only when i ran automation in selendroid mode and u tried to use TouchActions functions. In that case i got this exception.

Kindly provide your guidance so i can i m prove. 😄

Thanks,  
Priyank Shah

---

<div class="post-metadata">

**Author:** ![sendlink](https://discuss.appium.io/letter_avatar_proxy/v4/letter/s/bc8723/32.png) [@sendlink](https://discuss.appium.io/u/sendlink)\
**Post date:** [September 15, 2014, 4:42am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/7 "2014-09-15T04:42:07Z")

</div>

@SergeyTichomirov

with PageFactory.initElements(new AppiumFieldDecorator(driver),new Napp());

I get same error

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 15, 2014, 7:16am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/8 "2014-09-15T07:16:15Z")

</div>

Oh, my god!..  
I am sorry for confusion that I made ☹

I your code really looks like

> public class Napp

> {

> @AndroidFindBy(name = “Data usage”)  
> private WebElement setdata;  
> …  
> DesiredCapabilities capabilities = new DesiredCapabilities();

> capabilities.setCapability(“appPackage”, “com.android.settings”);  
> capabilities.setCapability(“appActivity”, “.Settings”);  
> capabilities.setCapability(MobileCapabilityType.PLATFORM\_NAME, “Android”);  
> capabilities.setCapability(MobileCapabilityType.PLATFORM\_VERSION, “4.4.2”);  
> capabilities.setCapability(MobileCapabilityType.DEVICE\_NAME, “Nexus 5”);  
> driver = new AppiumDriver(new URL(“[http://0.0.0.0:4723/wd/hub](http://0.0.0.0:4723/wd/hub)”), capabilities);  
> …  
> //here is WebElement instantiation  
> PageFactory.initElements(new AppiumFieldDecorator(driver),…);  
> …  
> …
> 
> {  
> setdata.click();  
> }…  
> }

you need this

> PageFactory.initElements(new AppiumFieldDecorator(driver),  
> this// Napp current instance  
> );

Please try this. I am waiting for feedback. 🙂

If the problem is not worked out, please share you full sample code (github, for example).

---

<div class="post-metadata">

**Author:** ![Priyank\_Shah](https://discuss.appium.io/user_avatar/discuss.appium.io/priyank_shah/32/5628_2.png) [@Priyank\_Shah](https://discuss.appium.io/u/Priyank_Shah)\
**Post date:** [September 15, 2014, 7:24am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/9 "2014-09-15T07:24:16Z")

</div>

hello @SergeyTichomirov ,  
Welcome, i really thank for your suggestions, and i am working on improvement.

- this way you can’t instantiate MobileElement or RemoteWebElement for example. (Can you please expain or suggest sample code? )
- you can’t use TouchActions and MultiTouchActions because you will catch ClassCastExeption (Class cast exceptions only raised when u run your code in selendroid mode, so when u run your code in selendroid mode you can not cast such driver object to appium driver. so that might be the root cause)
- you can’t use some new annotations implemented for Screen Objects. Read this tread. (I am only using @FindBy annotatons, I am not using new annotation which is part of appiumDriver)

Thanks,  
Priyank Shah

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 15, 2014, 7:29am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/10 "2014-09-15T07:29:06Z")

</div>

Hello!

You don’t need to do something special.

I am looking at your samples and I think it is enough to replace

> PageFactory.initElements(driver, this);

by

> PageFactory.initElements(new AppiumFieldDecorator(driver), this);

Issues will be fixed. 🙂 The explanation will be over 5 minutes

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 15, 2014, 7:32am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/11 "2014-09-15T07:32:39Z")

</div>

You can read [this](https://github.com/appium/java-client/pull/68) about Appium page factory facilities

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 15, 2014, 7:44am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/12 "2014-09-15T07:44:32Z")

</div>

The root cause of these problems is when you invoke  
PageFactory.initElements(driver, this) you actually use [DefaultFieldDecorator](https://github.com/SeleniumHQ/selenium/blob/master/java/client/src/org/openqa/selenium/support/pagefactory/DefaultFieldDecorator.java).

It instantiates WebElement field as a java.lang.reflect.Proxy object which implements only WebElement and WrapsElement.

AppiumFieldDecorator uses CGLIB and makes proxies of classes.

So WebElement and RemoteWebElement fields are instantiated as RemoteWebElement. If you declare your field as MobileElement, MobileElement will be here.

---

<div class="post-metadata">

**Author:** ![SergeyTichomirov](https://discuss.appium.io/user_avatar/discuss.appium.io/sergeytichomirov/32/5175_2.png) [@SergeyTichomirov](https://discuss.appium.io/u/SergeyTichomirov)\
**Post date:** [September 15, 2014, 7:48am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/13 "2014-09-15T07:48:18Z")

</div>

@Priyank_Shah  
Has my explanation helped you?  
It would be nice if it is so:)

---

<div class="post-metadata">

**Author:** ![Priyank\_Shah](https://discuss.appium.io/user_avatar/discuss.appium.io/priyank_shah/32/5628_2.png) [@Priyank\_Shah](https://discuss.appium.io/u/Priyank_Shah)\
**Post date:** [September 15, 2014, 7:51am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/14 "2014-09-15T07:51:26Z")

</div>

@SergeyTichomirov Your explaination is nice… i will enhance my script, and got your point…

благодаря again 🙂

---

<div class="post-metadata">

**Author:** ![sendlink](https://discuss.appium.io/letter_avatar_proxy/v4/letter/s/bc8723/32.png) [@sendlink](https://discuss.appium.io/u/sendlink)\
**Post date:** [September 15, 2014, 8:05am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/15 "2014-09-15T08:05:54Z")

</div>

@SergeyTichomirov Now its working

---

<div class="post-metadata">

**Author:** ![Priyank\_Shah](https://discuss.appium.io/user_avatar/discuss.appium.io/priyank_shah/32/5628_2.png) [@Priyank\_Shah](https://discuss.appium.io/u/Priyank_Shah)\
**Post date:** [September 16, 2014, 10:19am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/16 "2014-09-16T10:19:48Z")

</div>

Updated project.  
Please find sample project with PageFactory framework on below link.

> <https://github.com/priyankshah217/AppiumTestAutomation/tree/master/src/test/java/com/test>
>
> //github.com/priyankshah217/AppiumTestAutomation/tree/master/src/test/java/com/test

---

<div class="post-metadata">

**Author:** ![Ravi\_Kiran](https://discuss.appium.io/letter_avatar_proxy/v4/letter/r/4491bb/32.png) [@Ravi\_Kiran](https://discuss.appium.io/u/Ravi_Kiran)\
**Post date:** [February 15, 2016, 11:41am UTC](https://discuss.appium.io/t/page-factory-not-working-in-android/327/17 "2016-02-15T11:41:42Z")

</div>

Hello

@SergeyTichomirov  
@Priyank_Shah

i am facing the same issue with page factory model, when i execute the code locally its working perfectly but when i tried to execute in cloud. it says null point exception error

my code is

AbstractPage

public abstract class BasePage {

```
  public final AndroidDriver<MobileElement> driver;
  
  public BasePage(AndroidDriver<MobileElement> driver){
        this.driver = driver;
        PageFactory.initElements(new AppiumFieldDecorator(driver, 10, TimeUnit.SECONDS), this);
    }
  
  
@AndroidFindBy(className = "android.widget.ImageButton")
public static MobileElement menu;
  
@AndroidFindBy(id = "android:id/search_button")
public static MobileElement searchbutton;

@AndroidFindBy(id = "android:id/search_src_text")
public static MobileElement searchtext;

@AndroidFindBy(xpath = "//android.widget.Button[@text = 'Download' and @clickable='true']")
public static MobileElement download;

@AndroidFindBy(xpath = "//android.widget.Button[@text = 'Play' and @clickable='true']")
public static MobileElement play;

@AndroidFindBy(id = "com.android.packageinstaller:id/permission_allow_button")
public static MobileElement Allow;

@AndroidFindBy(id = "com.android.packageinstaller:id/permission_deny_button")
public static MobileElement Deny;

```

and Page Elements

public class RegistrationPage extends BasePage {

```
	public RegistrationPage(AndroidDriver<MobileElement> driver) {
	super(driver);
	// TODO Auto-generated constructor stub
}

	@AndroidFindBy(xpath = "//android.widget.LinearLayout[@text = 'Mobile Number']")
	private MobileElement mobile;
	
	@AndroidFindBy(xpath = "//android.widget.LinearLayout[@text = 'Email Address']")
	private MobileElement email;
	
	@AndroidFindBy(xpath = "//android.widget.Button[@text = 'Get Started']")
	private MobileElement submit;
	
	@AndroidFindBy(className = "android.widget.Button")
	private static MobileElement status;
	
	public String getButtonStatus(){
        return status.getText();
    }
	
	public void TestButton() throws InterruptedException,NoSuchElementException
	{
		Thread.sleep(10000);
		driver.hideKeyboard();
		Thread.sleep(6000);
		mobile.sendKeys("9550205474");
		Thread.sleep(8000);
		email.sendKeys("ravi@ravi.com");
		Thread.sleep(8000);
		try
		{
		if
		(submit.isDisplayed())
		{
		submit.click();
		}
		}
		catch (Exception e)
		{
		driver.hideKeyboard();
		Thread.sleep(3000);
		submit.click();
		}
		Thread.sleep(5000);
		}
	
	
	public void go() throws InterruptedException,NoSuchElementException
	{
		Thread.sleep(5000);
		try
		{
		if (BasePage.Allow.isDisplayed())
		{
			BasePage.Allow.click();
			Thread.sleep(2500);
			status.click();
		}
		}
		catch (Exception e)
		{
		status.click();
		Thread.sleep(5000);
		}
	}

```

and One more page

public class MoviesPage extends BasePage {

```
public MoviesPage(AndroidDriver<MobileElement> driver) {
	super(driver);
	// TODO Auto-generated constructor stub
}

@AndroidFindBy(xpath = "//android.widget.TextView[@text='Popular']")
private static MobileElement popular;

@AndroidFindBy(xpath = "//android.widget.TextView[@text='New']")
private static MobileElement New;

@AndroidFindBy(xpath = "//android.widget.LinearLayout[@index='0' and @clickable= 'true']")
private static MobileElement firstmovie;

@AndroidFindBy(className = "android.widget.LinearLayout")
private static List<MobileElement> catalog;

@AndroidFindBy(id = "com.fropcorn.www:id/catalogItemLinearLayout")
private static MobileElement list;

public static List<MobileElement> cataloglist()
{
	List<MobileElement> cataloglist = catalog;
	return cataloglist;
	
}

public static void popularbutton()
{
	popular.click();
}

public static void newbutton()
{
	New.click();
}

public static void firstmoviebutton()
{
	firstmovie.click();
}

```

}  
and my Test Case is

BasePage.menu.click();  
Thread.sleep(5000);  
MenuPage.moviesbutton();  
Thread.sleep(5000);  
MoviesPage.firstmoviebutton();  
Thread.sleep(2500);  
BasePage.download.click();  
WebDriverWait wait = new WebDriverWait(driver, 50000);  
wait.until(ExpectedConditions.visibilityOf(BasePage.play));  
BasePage.play.click();  
Thread.sleep(30000);  
Screenshot shot = new Screenshot();  
shot.takeScreenshot(“player”);

The problem here is i could execute the elements in one page but not other.That element is showing as null point exception error

When i execute MenuPage.moviesbutton() the testcase fail with the exception

The java log is

[TestRunner] Running the tests in ‘Command line test’ with parallel mode:false  
[RunInfo] Adding method selector: org.testng.internal.XmlMethodSelector@d70c109 priority: 10  
[TestClass] Creating TestClass for [ClassImpl class=TestSuite.RegistrationTest]  
[TestClass] Adding method RegistrationTest.Registration()[pri:0, instance:null] on TestClass class TestSuite.RegistrationTest  
[XmlMethodSelector] Including method Configuration.setUpAppium()  
[XmlMethodSelector] Including method Configuration.tearDownAppium()  
[XmlMethodSelector] Including method TestSuite.setUpPage()  
[XmlMethodSelector] Including method TestSuite.Registration()  
[TestNG] Running:  
Command line suite

# [Invoker 985922955] Keeping method TestBase.setUpAppium()[pri:0, instance:TestSuite.RegistrationTest@6adca536] for class null [Invoker 985922955] Invoking @BeforeSuite TestBase.setUpAppium()[pri:0, instance:TestSuite.RegistrationTest@6adca536] [TestNG] INVOKING CONFIGURATION: “UNKNOWN” - @BeforeSuite Configuration.TestBase.setUpAppium() [TestNG] PASSED CONFIGURATION: “UNKNOWN” - @BeforeSuite Configuration.TestBase.setUpAppium() finished in 2633 ms [SuiteRunner] Created 1 TestRunners [TestRunner] Running test Command line test on 1 classes, included groups:[] excluded groups:[] ===== Test class TestSuite.RegistrationTest @Test RegistrationTest.Registration()[pri:0, instance:TestSuite.RegistrationTest@6adca536]

# [TestNG] RUNNING: Suite: “Command line test” containing “1” Tests (config: null) [Invoker 985922955] Keeping method RegistrationTest.setUpPage()[pri:0, instance:TestSuite.RegistrationTest@6adca536] for class null [Invoker 985922955] Invoking @BeforeTest RegistrationTest.setUpPage()[pri:0, instance:TestSuite.RegistrationTest@6adca536] [TestNG] INVOKING CONFIGURATION: “Command line test” - @BeforeTest TestSuite.RegistrationTest.setUpPage() [TestNG] PASSED CONFIGURATION: “Command line test” - @BeforeTest TestSuite.RegistrationTest.setUpPage() finished in 526 ms [TestNG] INVOKING: “Command line test” - TestSuite.RegistrationTest.Registration() [Invoker 985922955] Invoking TestSuite.RegistrationTest.Registration TestCase: Registration [TestNG] FAILED: “Command line test” - TestSuite.RegistrationTest.Registration() finished in 90006 ms [TestNG] java.lang.NullPointerException [TestNG] at Configuration.Pages.MenuPage.moviesbutton(MenuPage.java:34) [TestNG] at TestSuite.RegistrationTest.Registration(RegistrationTest.java:36) [TestNG] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [TestNG] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [TestNG] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [TestNG] at java.lang.reflect.Method.invoke(Method.java:497) [TestNG] at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84) [TestNG] at org.testng.internal.Invoker.invokeMethod(Invoker.java:714) [TestNG] at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901) [TestNG] at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231) [TestNG] at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127) [TestNG] at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111) [TestNG] at org.testng.TestRunner.privateRun(TestRunner.java:767) [TestNG] at org.testng.TestRunner.run(TestRunner.java:617) [TestNG] at org.testng.SuiteRunner.runTest(SuiteRunner.java:348) [TestNG] at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:343) [TestNG] at org.testng.SuiteRunner.privateRun(SuiteRunner.java:305) [TestNG] at org.testng.SuiteRunner.run(SuiteRunner.java:254) [TestNG] at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52) [TestNG] at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86) [TestNG] at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224) [TestNG] at org.testng.TestNG.runSuitesLocally(TestNG.java:1149) [TestNG] at org.testng.TestNG.run(TestNG.java:1057) [TestNG] at org.testng.TestNG.privateMain(TestNG.java:1364) [TestNG] at org.testng.TestNG.main(TestNG.java:1333) ===== Invoked methods RegistrationTest.Registration()[pri:0, instance:TestSuite.RegistrationTest@6adca536] 1792845110

Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/Command line suite/Command line test.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/Command line suite/Command line test.xml  
FAILED: Registration  
java.lang.NullPointerException  
at Configuration.Pages.MenuPage.moviesbutton(MenuPage.java:34)  
at TestSuite.RegistrationTest.Registration(RegistrationTest.java:36)  
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)  
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)  
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)  
at java.lang.reflect.Method.invoke(Method.java:497)  
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84)  
at org.testng.internal.Invoker.invokeMethod(Invoker.java:714)  
at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)  
at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)  
at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)  
at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)  
at org.testng.TestRunner.privateRun(TestRunner.java:767)  
at org.testng.TestRunner.run(TestRunner.java:617)  
at org.testng.SuiteRunner.runTest(SuiteRunner.java:348)  
at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:343)  
at org.testng.SuiteRunner.privateRun(SuiteRunner.java:305)  
at org.testng.SuiteRunner.run(SuiteRunner.java:254)  
at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)  
at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)  
at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224)  
at org.testng.TestNG.runSuitesLocally(TestNG.java:1149)  
at org.testng.TestNG.run(TestNG.java:1057)  
at org.testng.TestNG.privateMain(TestNG.java:1364)  
at org.testng.TestNG.main(TestNG.java:1333)

# =============================================== Command line test Tests run: 1, Failures: 1, Skips: 0

[TestNG]  
[TestNG] ===============================================  
[TestNG] Command line test  
[TestNG] Tests run: 1, Failures: 1, Skips: 0  
[TestNG] ===============================================  
[Invoker 985922955] Keeping method TestBase.tearDownAppium()[pri:0, instance:TestSuite.RegistrationTest@6adca536] for class null  
[Invoker 985922955] Invoking @AfterSuite TestBase.tearDownAppium()[pri:0, instance:TestSuite.RegistrationTest@6adca536]  
[TestNG] INVOKING CONFIGURATION: “UNKNOWN” - @AfterSuite Configuration.TestBase.tearDownAppium()  
[TestNG] PASSED CONFIGURATION: “UNKNOWN” - @AfterSuite Configuration.TestBase.tearDownAppium() finished in 1064 ms

# =============================================== Command line suite Total tests run: 1, Failures: 1, Skips: 0

Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/toc.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/Command line test.properties  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/index.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/main.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/groups.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-alphabetical.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/classes.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/reporter-output.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/methods-not-run.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/Command line suite/testng.xml.html  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/old/index.html  
[TestNG] Time taken by org.testng.reporters.SuiteHTMLReporter@7dc5e7b4: 92 ms  
[TestNG] Time taken by org.testng.reporters.EmailableReporter2@7637f22: 18 ms  
[TestNG] Time taken by org.testng.reporters.jq.Main@6f2b958e: 112 ms  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/testng-failed.xml  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/Command line suite/testng-failed.xml  
[TestNG] Time taken by [FailedReporter passed=0 failed=0 skipped=0]: 2 ms  
[TestNG] Time taken by org.testng.reporters.XMLReporter@36aa7bc2: 57 ms  
Creating /tmp/scratchB8dOd9.scratch/resultsR0tdp2/junitreports/TEST-TestSuite.RegistrationTest.xml  
[TestNG] Time taken by org.testng.reporters.JUnitReportReporter@41a4555e: 5 ms

and appium server log

2016-02-15 10:53:17:102 - info: [debug] Starting Appium in pre-launch mode  
2016-02-15 10:53:17:117 - info: Pre-launching app  
2016-02-15 10:53:17:122 - info: [debug] Using local app from command line: /tmp/scratchB8dOd9.scratch/share-whZoGG.scratch/app-SIAUak.apk  
2016-02-15 10:53:17:124 - info: [debug] Creating new appium session d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54  
2016-02-15 10:53:17:125 - info: Starting android appium  
2016-02-15 10:53:17:127 - info: [debug] Getting Java version  
2016-02-15 10:53:17:285 - info: Java version is: 1.8.0\_65  
2016-02-15 10:53:17:286 - info: [debug] Checking whether adb is present  
2016-02-15 10:53:17:287 - info: [debug] Using adb from /opt/dev/android-sdk-linux/platform-tools/adb  
2016-02-15 10:53:17:288 - info: [debug] Using fast reset? true  
2016-02-15 10:53:17:289 - info: [debug] Preparing device for session  
2016-02-15 10:53:17:289 - info: [debug] Checking whether app is actually present  
2016-02-15 10:53:17:290 - info: Retrieving device  
2016-02-15 10:53:17:291 - info: [debug] Trying to find a connected android device  
2016-02-15 10:53:17:300 - info: [debug] Getting connected devices…  
2016-02-15 10:53:17:302 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb devices  
2016-02-15 10:53:17:448 - info: [debug] 1 device(s) connected  
2016-02-15 10:53:17:448 - info: Found device ZX1D528ZJM  
2016-02-15 10:53:17:449 - info: [debug] Setting device id to ZX1D528ZJM  
2016-02-15 10:53:17:450 - info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 5)  
2016-02-15 10:53:17:452 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM wait-for-device  
2016-02-15 10:53:17:599 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “echo ‘ready’”  
2016-02-15 10:53:17:802 - info: [debug] Starting logcat capture  
2016-02-15 10:53:17:975 - info: [debug] Getting device API level  
2016-02-15 10:53:17:976 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “getprop ro.build.version.sdk”  
2016-02-15 10:53:18:138 - info: [debug] Device is at API Level 21  
2016-02-15 10:53:18:138 - info: Device API level is: 21  
2016-02-15 10:53:18:139 - info: [debug] Extracting strings for language: default  
2016-02-15 10:53:18:139 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “getprop persist.sys.language”  
2016-02-15 10:53:18:372 - info: [debug] Current device persist.sys.language: en  
2016-02-15 10:53:18:373 - info: [debug] java -jar “/usr/local/avm/versions/1.4.10/node\_modules/appium/node\_modules/appium-adb/jars/appium\_apk\_tools.jar” “stringsFromApk” “/tmp/scratchB8dOd9.scratch/share-whZoGG.scratch/app-SIAUak.apk” “/tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www” en  
2016-02-15 10:53:20:279 - info: [debug] No strings.xml for language ‘en’, getting default strings.xml  
2016-02-15 10:53:20:279 - info: [debug] java -jar “/usr/local/avm/versions/1.4.10/node\_modules/appium/node\_modules/appium-adb/jars/appium\_apk\_tools.jar” “stringsFromApk” “/tmp/scratchB8dOd9.scratch/share-whZoGG.scratch/app-SIAUak.apk” “/tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www”  
2016-02-15 10:53:22:013 - info: [debug] Reading strings from converted strings.json  
2016-02-15 10:53:22:015 - info: [debug] Setting language to default  
2016-02-15 10:53:22:016 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM push “/tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www/strings.json” /data/local/tmp  
2016-02-15 10:53:22:314 - info: [debug] Checking whether aapt is present  
2016-02-15 10:53:22:314 - info: [debug] Using aapt from /opt/dev/android-sdk-linux/build-tools/21.1.1/aapt  
2016-02-15 10:53:22:316 - info: [debug] Retrieving process from manifest.  
2016-02-15 10:53:22:316 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/build-tools/21.1.1/aapt dump xmltree /tmp/scratchB8dOd9.scratch/share-whZoGG.scratch/app-SIAUak.apk AndroidManifest.xml  
2016-02-15 10:53:22:336 - info: [debug] Set app process to: com.fropcorn.www  
2016-02-15 10:53:22:336 - info: [debug] Not uninstalling app since server not started with --full-reset  
2016-02-15 10:53:22:362 - info: [debug] Printing keystore md5.  
2016-02-15 10:53:22:362 - info: [debug] executing cmd: “/usr/lib/jvm/jdk1.8.0\_65/bin/keytool” -v -list -alias androiddebugkey -keystore /home/rwx/.android/debug.keystore -storepass android  
2016-02-15 10:53:22:970 - info: [debug] Keystore MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B  
2016-02-15 10:53:23:329 - info: [debug] Entry: META-INF/ANDROIDD.RSA  
2016-02-15 10:53:23:330 - info: [debug] entryPath: /tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www/cert  
2016-02-15 10:53:23:331 - info: [debug] entryFile: /tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www/cert/META-INF/ANDROIDD.RSA  
2016-02-15 10:53:23:350 - info: [debug] extracted!  
2016-02-15 10:53:23:350 - info: [debug] Printing apk md5.  
2016-02-15 10:53:23:351 - info: [debug] executing cmd: “/usr/lib/jvm/jdk1.8.0\_65/bin/keytool” -v -printcert -file /tmp/scratchB8dOd9.scratch/appium-WNLgmm/com.fropcorn.www/cert/META-INF/ANDROIDD.RSA  
2016-02-15 10:53:23:987 - info: [debug] entryHash MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B  
2016-02-15 10:53:23:987 - info: [debug] keystore MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B  
2016-02-15 10:53:23:987 - info: [debug] Matches keystore? true  
2016-02-15 10:53:24:235 - info: [debug] MD5 for app is 4ffe1c84e8e88a29d14cde2aebbb9ba5  
2016-02-15 10:53:24:235 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “ls /data/local/tmp/4ffe1c84e8e88a29d14cde2aebbb9ba5.apk”  
2016-02-15 10:53:24:469 - info: [debug] Getting install status for com.fropcorn.www  
2016-02-15 10:53:24:470 - info: [debug] Getting device API level  
2016-02-15 10:53:24:470 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “getprop ro.build.version.sdk”  
2016-02-15 10:53:24:662 - info: [debug] Device is at API Level 21  
2016-02-15 10:53:24:662 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “pm list packages -3 com.fropcorn.www”  
2016-02-15 10:53:25:462 - info: [debug] App is installed  
2016-02-15 10:53:25:463 - info: App is already installed, resetting app  
2016-02-15 10:53:25:464 - info: [debug] Running fast reset (stop and clear)  
2016-02-15 10:53:25:465 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “am force-stop com.fropcorn.www”  
2016-02-15 10:53:26:282 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “pm clear com.fropcorn.www”  
2016-02-15 10:53:27:180 - info: [debug] Forwarding system:4724 to device:4724  
2016-02-15 10:53:27:181 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM forward tcp:4724 tcp:4724  
2016-02-15 10:53:27:347 - info: [debug] Pushing appium bootstrap to device…  
2016-02-15 10:53:27:348 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM push “/usr/local/avm/versions/1.4.10/node\_modules/appium/build/android\_bootstrap/AppiumBootstrap.jar” /data/local/tmp/  
2016-02-15 10:53:27:642 - info: [debug] Pushing settings apk to device…  
2016-02-15 10:53:27:643 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM install “/usr/local/avm/versions/1.4.10/node\_modules/appium/build/settings\_apk/settings\_apk-debug.apk”  
2016-02-15 10:53:29:109 - info: [debug] Pushing unlock helper app to device…  
2016-02-15 10:53:29:111 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM install “/usr/local/avm/versions/1.4.10/node\_modules/appium/build/unlock\_apk/unlock\_apk-debug.apk”  
2016-02-15 10:53:30:583 - info: Starting App  
2016-02-15 10:53:30:585 - info: [debug] Attempting to kill all ‘uiautomator’ processes  
2016-02-15 10:53:30:585 - info: [debug] Getting all processes with ‘uiautomator’  
2016-02-15 10:53:30:586 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “ps ‘uiautomator’”  
2016-02-15 10:53:30:842 - info: [debug] No matching processes found  
2016-02-15 10:53:30:843 - info: [debug] Running bootstrap  
2016-02-15 10:53:30:843 - info: [debug] spawning: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap -e pkg com.fropcorn.www -e disableAndroidWatchers false  
2016-02-15 10:53:31:518 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: numtests=1  
2016-02-15 10:53:31:519 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: stream=  
2016-02-15 10:53:31:520 - info: [debug] [UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:  
2016-02-15 10:53:31:526 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: id=UiAutomatorTestRunner  
2016-02-15 10:53:31:527 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: test=testRunServer  
2016-02-15 10:53:31:527 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: class=io.appium.android.bootstrap.Bootstrap  
2016-02-15 10:53:31:528 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: current=1  
2016-02-15 10:53:31:528 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS\_CODE: 1  
2016-02-15 10:53:31:563 - info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724  
2016-02-15 10:53:31:564 - info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready  
2016-02-15 10:53:31:565 - info: [debug] [BOOTSTRAP] [debug] Loading json…  
2016-02-15 10:53:31:566 - info: [debug] Waking up device if it’s not alive  
2016-02-15 10:53:31:567 - info: [debug] Pushing command to appium work queue: [“wake”,{}]  
2016-02-15 10:53:31:570 - info: [debug] [BOOTSTRAP] [debug] json loading complete.  
2016-02-15 10:53:31:571 - info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.  
2016-02-15 10:53:31:579 - info: [debug] [BOOTSTRAP] [debug] Client connected  
2016-02-15 10:53:31:587 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“wake”,“params”:{}}  
2016-02-15 10:53:31:594 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “dumpsys window”  
2016-02-15 10:53:31:597 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:31:598 - info: [debug] [BOOTSTRAP] [debug] Got command action: wake  
2016-02-15 10:53:31:599 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:53:31:777 - info: [debug] Screen already unlocked, continuing.  
2016-02-15 10:53:31:778 - info: [debug] Pushing command to appium work queue: [“getDataDir”,{}]  
2016-02-15 10:53:31:791 - info: [debug] dataDir set to: /data/local/tmp  
2016-02-15 10:53:31:792 - info: [debug] Pushing command to appium work queue: [“compressedLayoutHierarchy”,{“compressLayout”:false}]  
2016-02-15 10:53:31:793 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“getDataDir”,“params”:{}}  
2016-02-15 10:53:31:794 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:31:794 - info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir  
2016-02-15 10:53:31:795 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:"/data/local/tmp"}  
2016-02-15 10:53:31:805 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“compressedLayoutHierarchy”,“params”:{“compressLayout”:false}}  
2016-02-15 10:53:31:805 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:31:807 - info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy  
2016-02-15 10:53:31:809 - info: [debug] Getting device API level  
2016-02-15 10:53:31:810 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “getprop ro.build.version.sdk”  
2016-02-15 10:53:31:825 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:false}  
2016-02-15 10:53:31:982 - info: [debug] Device is at API Level 21  
2016-02-15 10:53:31:983 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.fropcorn.www/md569a6b40b6c9dd89241ed1fe199a0a771.SplashScreen”  
2016-02-15 10:53:33:115 - info: [debug] Waiting for pkg “com.fropcorn.www” and activity “md569a6b40b6c9dd89241ed1fe199a0a771.SplashScreen” to be focused  
2016-02-15 10:53:33:116 - info: [debug] Getting focused package and activity  
2016-02-15 10:53:33:116 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “dumpsys window windows”  
2016-02-15 10:53:33:391 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “getprop ro.build.version.release”  
2016-02-15 10:53:33:612 - info: [debug] Device is at release version 5.0.2  
2016-02-15 10:53:33:612 - info: [debug] Device launched! Ready for commands  
2016-02-15 10:53:33:612 - info: [debug] Setting command timeout to the default of 60 secs  
2016-02-15 10:53:33:628 - info: Welcome to Appium v1.4.10 (REV c97e824665ae82df682a0b540b38dfe8c13c8ac7)  
2016-02-15 10:53:33:628 - info: Appium REST http interface listener started on 127.0.0.1:4723  
2016-02-15 10:53:33:632 - info: [debug] Non-default server args: {“app”:"/tmp/scratchB8dOd9.scratch/share-whZoGG.scratch/app-SIAUak.apk",“udid”:“ZX1D528ZJM”,“address”:“127.0.0.1”,“launch”:true,“logTimestamp”:true,“logNoColors”:true,“androidPackage”:“com.fropcorn.www”,“androidActivity”:“md569a6b40b6c9dd89241ed1fe199a0a771.SplashScreen”,“platformName”:“Android”,“useKeystore”:true,“tmpDir”:"/tmp/scratchB8dOd9.scratch/appium-WNLgmm"}  
2016-02-15 10:53:33:632 - info: Console LogLevel: debug  
2016-02-15 10:53:33:772 - info: --\> GET /wd/hub/status {}  
2016-02-15 10:53:33:775 - info: [debug] Responding to client with success: {“status”:0,“value”:{“build”:{“version”:“1.4.10”,“revision”:“c97e824665ae82df682a0b540b38dfe8c13c8ac7”}},“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:33:797 - info: \<-- GET /wd/hub/status 200 24.045 ms - 156 {“status”:0,“value”:{“build”:{“version”:“1.4.10”,“revision”:“c97e824665ae82df682a0b540b38dfe8c13c8ac7”}},“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:37:131 - info: --\> POST /wd/hub/session {“desiredCapabilities”:{“platformName”:“Android”}}  
2016-02-15 10:53:37:133 - info: Client User-Agent string: Apache-HttpClient/4.3.3 (java 1.5)  
2016-02-15 10:53:37:153 - info: \<-- POST /wd/hub/session 303 21.441 ms - 74  
2016-02-15 10:53:37:211 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54 {}  
2016-02-15 10:53:37:232 - info: [debug] Responding to client with success: {“status”:0,“value”:{“platform”:“LINUX”,“browserName”:“Android”,“platformVersion”:“5.0.2”,“webStorageEnabled”:false,“takesScreenshot”:true,“javascriptEnabled”:true,“databaseEnabled”:false,“networkConnectionEnabled”:true,“locationContextEnabled”:false,“warnings”:{},“desired”:{},“deviceName”:“ZX1D528ZJM”},“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:37:263 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54 200 52.260 ms - 356 {“status”:0,“value”:{“platform”:“LINUX”,“browserName”:“Android”,“platformVersion”:“5.0.2”,“webStorageEnabled”:false,“takesScreenshot”:true,“javascriptEnabled”:true,“databaseEnabled”:false,“networkConnectionEnabled”:true,“locationContextEnabled”:false,“warnings”:{},“desired”:{},“deviceName”:“ZX1D528ZJM”},“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:37:399 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:100000}  
2016-02-15 10:53:37:445 - info: [debug] Set Android implicit wait to 100000ms  
2016-02-15 10:53:37:446 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:37:460 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 60.654 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:48:008 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/appium/device/hide\_keyboard {}  
2016-02-15 10:53:48:019 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “dumpsys input\_method”  
2016-02-15 10:53:48:248 - info: [debug] Pushing command to appium work queue: [“pressBack”]  
2016-02-15 10:53:48:261 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“pressBack”,“params”:{}}  
2016-02-15 10:53:48:262 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:48:262 - info: [debug] [BOOTSTRAP] [debug] Got command action: pressBack  
2016-02-15 10:53:48:315 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:48:318 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/appium/device/hide\_keyboard 200 310.432 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:48:323 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:53:54:325 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:53:54:326 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:53:54:326 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:339 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 14.318 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:355 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:53:54:356 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:356 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 1.891 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:390 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“xpath”,“value”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Mobile Number’]"}  
2016-02-15 10:53:54:391 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:53:54:392 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“xpath”,“selector”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Mobile Number’]",“context”:"",“multiple”:true}]  
2016-02-15 10:53:54:410 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“xpath”,“selector”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Mobile Number’]",“context”:"",“multiple”:true}}  
2016-02-15 10:53:54:411 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:54:411 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:53:54:411 - info: [debug] [BOOTSTRAP] [debug] Finding [//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Mobile Number’] using XPATH with the contextId: multiple: true  
2016-02-15 10:53:54:567 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.LinearLayout, INSTANCE=3]  
2016-02-15 10:53:54:576 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.LinearLayout, INSTANCE=3]  
2016-02-15 10:53:54:576 - info: [debug] [BOOTSTRAP] [debug] Selector ends with instance.  
2016-02-15 10:53:54:614 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“1”}]}  
2016-02-15 10:53:54:616 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“1”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:619 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 229.417 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“1”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:628 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:53:54:638 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:53:54:638 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:644 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 15.450 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:53:54:699 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/1/value {“id”:“1”,“value”:[“9550205474”]}  
2016-02-15 10:53:54:701 - info: [debug] Pushing command to appium work queue: [“element:setText”,{“elementId”:“1”,“text”:“9550205474”,“replace”:false}]  
2016-02-15 10:53:54:714 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:setText”,“params”:{“elementId”:“1”,“text”:“9550205474”,“replace”:false}}  
2016-02-15 10:53:54:714 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:53:54:714 - info: [debug] [BOOTSTRAP] [debug] Got command action: setText  
2016-02-15 10:53:54:714 - info: [debug] [BOOTSTRAP] [debug] Using element passed in.  
2016-02-15 10:53:54:725 - info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().  
2016-02-15 10:53:57:069 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:53:57:070 - info: [debug] [BOOTSTRAP] [debug] Clearing text not successful. Attempting to clear by selecting all and deleting.  
2016-02-15 10:54:02:732 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:54:02:732 - info: [debug] [BOOTSTRAP] [debug] Clearing text not successful. Attempting to clear by sending delete keys.  
2016-02-15 10:54:03:810 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:54:03:833 - info: [debug] [BOOTSTRAP] [debug] Exhausted all means to clear text but ‘Mobile Number’ remains.  
2016-02-15 10:54:03:843 - info: [debug] [BOOTSTRAP] [debug] Text not cleared. Assuming remainder is hint text.  
2016-02-15 10:54:03:843 - info: [debug] [BOOTSTRAP] [debug] Sending plain text to element: 9550205474  
2016-02-15 10:54:06:320 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:54:06:320 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:06:326 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/1/value 200 11627.022 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:329 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:14:330 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:14:330 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:342 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 13.169 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:347 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:14:347 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:349 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 2.666 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:359 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“xpath”,“value”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Email Address’]"}  
2016-02-15 10:54:14:359 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:14:360 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“xpath”,“selector”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Email Address’]",“context”:"",“multiple”:true}]  
2016-02-15 10:54:14:376 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“xpath”,“selector”:"[//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Email Address’]",“context”:"",“multiple”:true}}  
2016-02-15 10:54:14:376 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:14:377 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:14:377 - info: [debug] [BOOTSTRAP] [debug] Finding [//android.widget.LinearLayout](https://android.widget.LinearLayout)[@text = ‘Email Address’] using XPATH with the contextId: multiple: true  
2016-02-15 10:54:14:423 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.LinearLayout, INSTANCE=4]  
2016-02-15 10:54:14:429 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.LinearLayout, INSTANCE=4]  
2016-02-15 10:54:14:429 - info: [debug] [BOOTSTRAP] [debug] Selector ends with instance.  
2016-02-15 10:54:14:429 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“2”}]}  
2016-02-15 10:54:14:430 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“2”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:442 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 83.687 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“2”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:444 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:14:445 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:14:446 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:457 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 12.287 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:14:459 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/2/value {“id”:“2”,“value”:[“ravi@ravi.com”]}  
2016-02-15 10:54:14:466 - info: [debug] Pushing command to appium work queue: [“element:setText”,{“elementId”:“2”,“text”:“ravi@ravi.com”,“replace”:false}]  
2016-02-15 10:54:14:478 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:setText”,“params”:{“elementId”:“2”,“text”:“ravi@ravi.com”,“replace”:false}}  
2016-02-15 10:54:14:478 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:14:479 - info: [debug] [BOOTSTRAP] [debug] Got command action: setText  
2016-02-15 10:54:14:479 - info: [debug] [BOOTSTRAP] [debug] Using element passed in.  
2016-02-15 10:54:14:487 - info: [debug] [BOOTSTRAP] [debug] Attempting to clear using UiObject.clearText().  
2016-02-15 10:54:16:957 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:54:16:958 - info: [debug] [BOOTSTRAP] [debug] Clearing text not successful. Attempting to clear by selecting all and deleting.  
2016-02-15 10:54:22:660 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:54:22:660 - info: [debug] [BOOTSTRAP] [debug] Clearing text not successful. Attempting to clear by sending delete keys.  
2016-02-15 10:54:25:870 - info: [debug] [BOOTSTRAP] [debug] Could not check for hint text because the element is not focused!  
2016-02-15 10:54:25:912 - info: [debug] [BOOTSTRAP] [debug] Exhausted all means to clear text but ‘Email Address’ remains.  
2016-02-15 10:54:25:913 - info: [debug] [BOOTSTRAP] [debug] Text not cleared. Assuming remainder is hint text.  
2016-02-15 10:54:25:913 - info: [debug] [BOOTSTRAP] [debug] Sending plain text to element: [ravi@ravi.com](mailto:ravi@ravi.com)  
2016-02-15 10:54:28:472 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:28:477 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/2/value 200 14018.111 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:28:479 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:54:36:479 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:36:480 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:36:480 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:483 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 4.619 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:501 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:36:502 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:503 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 2.289 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:509 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“xpath”,“value”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]"}  
2016-02-15 10:54:36:519 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:36:519 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“xpath”,“selector”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]",“context”:"",“multiple”:true}]  
2016-02-15 10:54:36:535 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“xpath”,“selector”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]",“context”:"",“multiple”:true}}  
2016-02-15 10:54:36:535 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:36:535 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:36:535 - info: [debug] [BOOTSTRAP] [debug] Finding [//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’] using XPATH with the contextId: multiple: true  
2016-02-15 10:54:36:587 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:36:595 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:36:596 - info: [debug] [BOOTSTRAP] [debug] Selector ends with instance.  
2016-02-15 10:54:36:643 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“3”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:648 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 139.205 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“3”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:651 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“3”}]}  
2016-02-15 10:54:36:652 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:36:653 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:36:654 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:669 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 16.861 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:673 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/3/displayed {}  
2016-02-15 10:54:36:674 - info: [debug] Pushing command to appium work queue: [“element:getAttribute”,{“elementId”:“3”,“attribute”:“displayed”}]  
2016-02-15 10:54:36:688 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:getAttribute”,“params”:{“elementId”:“3”,“attribute”:“displayed”}}  
2016-02-15 10:54:36:688 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:36:689 - info: [debug] [BOOTSTRAP] [debug] Got command action: getAttribute  
2016-02-15 10:54:36:736 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:738 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/3/displayed 200 64.154 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:740 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:“true”}  
2016-02-15 10:54:36:747 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:36:749 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:36:757 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:762 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 14.363 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:763 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:36:763 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:775 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 11.860 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:796 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“xpath”,“value”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]"}  
2016-02-15 10:54:36:797 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:36:797 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“xpath”,“selector”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]",“context”:"",“multiple”:true}]  
2016-02-15 10:54:36:810 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“xpath”,“selector”:"[//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’]",“context”:"",“multiple”:true}}  
2016-02-15 10:54:36:810 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:36:811 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:36:811 - info: [debug] [BOOTSTRAP] [debug] Finding [//android.widget.Button](https://android.widget.Button)[@text = ‘Get Started’] using XPATH with the contextId: multiple: true  
2016-02-15 10:54:36:854 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:36:861 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“4”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:867 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 70.752 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“4”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:868 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:36:869 - info: [debug] [BOOTSTRAP] [debug] Selector ends with instance.  
2016-02-15 10:54:36:870 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“4”}]}  
2016-02-15 10:54:36:871 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:36:872 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:36:873 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:887 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 16.122 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:36:893 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/4/click {“id”:“4”}  
2016-02-15 10:54:36:894 - info: [debug] Pushing command to appium work queue: [“element:click”,{“elementId”:“4”}]  
2016-02-15 10:54:36:907 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:click”,“params”:{“elementId”:“4”}}  
2016-02-15 10:54:36:907 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:36:908 - info: [debug] [BOOTSTRAP] [debug] Got command action: click  
2016-02-15 10:54:38:712 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:54:38:713 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:38:718 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/4/click 200 1824.682 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:719 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:48:721 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:48:722 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:727 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 8.705 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:729 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:48:729 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:748 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 19.512 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:750 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“class name”,“value”:“android.widget.Button”}  
2016-02-15 10:54:48:751 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:48:752 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“class name”,“selector”:“android.widget.Button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:48:769 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“class name”,“selector”:“android.widget.Button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:48:769 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:48:770 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:48:771 - info: [debug] [BOOTSTRAP] [debug] Finding android.widget.Button using CLASS\_NAME with the contextId: multiple: true  
2016-02-15 10:54:48:771 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.Button]  
2016-02-15 10:54:48:772 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.Button]  
2016-02-15 10:54:48:772 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:48:773 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:48:775 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)  
2016-02-15 10:54:48:775 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=1]  
2016-02-15 10:54:48:786 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“5”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:791 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 41.019 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“5”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:792 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“5”}]}  
2016-02-15 10:54:48:793 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:48:794 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:48:795 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:798 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 5.046 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:800 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/5/text {}  
2016-02-15 10:54:48:810 - info: [debug] Pushing command to appium work queue: [“element:getText”,{“elementId”:“5”}]  
2016-02-15 10:54:48:822 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:getText”,“params”:{“elementId”:“5”}}  
2016-02-15 10:54:48:822 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:48:822 - info: [debug] [BOOTSTRAP] [debug] Got command action: getText  
2016-02-15 10:54:48:837 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:“GO”}  
2016-02-15 10:54:48:845 - info: [debug] Responding to client with success: {“status”:0,“value”:“GO”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:48:846 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/5/text 200 46.420 ms - 76 {“status”:0,“value”:“GO”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:881 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:53:882 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:53:882 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:883 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 1.454 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:905 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:53:905 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:911 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 6.572 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:914 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:53:915 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:53:915 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:53:928 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:53:928 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:53:928 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:53:928 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:53:928 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:929 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:929 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:53:929 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:951 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:53:970 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:53:970 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:970 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:970 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:53:970 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:53:986 - info: [debug] Condition unmet after 71ms. Timing out.  
2016-02-15 10:54:53:986 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:989 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 74.851 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:53:989 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:54:492 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:54:493 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:54:494 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 2.455 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:54:499 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:54:509 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:54:509 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:54:522 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:54:523 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:54:524 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:54:524 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:54:525 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:525 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:526 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:54:526 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:546 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:54:547 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:54:548 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:549 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:549 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:54:550 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:54:588 - info: [debug] Condition unmet after 79ms. Timing out.  
2016-02-15 10:54:54:589 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:54:595 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 95.472 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:54:596 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:55:103 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:55:104 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:107 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 3.434 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:117 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:55:119 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:55:119 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:55:133 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:55:133 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:55:136 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:55:136 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:55:137 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:138 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:138 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:55:139 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:160 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:55:161 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:55:162 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:162 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:163 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:55:163 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:187 - info: [debug] Condition unmet after 68ms. Timing out.  
2016-02-15 10:54:55:188 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:193 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 76.553 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:194 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:55:693 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:55:694 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:695 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 1.461 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:720 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:55:721 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:55:721 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:55:736 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:55:736 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:55:736 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:55:736 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:55:736 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:737 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:737 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:55:737 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:55:762 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:55:796 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:55:797 - info: [debug] Condition unmet after 76ms. Timing out.  
2016-02-15 10:54:55:797 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:55:812 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 91.729 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:304 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:56:306 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:310 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 6.092 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:320 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:56:324 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:56:325 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:56:338 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:56:339 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:56:340 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:56:340 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:56:341 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:341 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:342 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:56:342 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:415 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:56:416 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:56:417 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:417 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:418 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:56:418 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:444 - info: [debug] Condition unmet after 119ms. Timing out.  
2016-02-15 10:54:56:444 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:447 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 127.170 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:448 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:56:950 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:56:951 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:955 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 5.092 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:56:957 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:56:958 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:56:959 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:56:985 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:56:986 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:56:987 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:56:987 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:56:988 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:988 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:990 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:56:990 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:56:994 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:57:003 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:57:003 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:003 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:003 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:57:004 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:031 - info: [debug] Condition unmet after 72ms. Timing out.  
2016-02-15 10:54:57:031 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:57:034 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 77.344 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:57:039 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:57:536 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:57:537 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:57:538 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 2.109 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:57:544 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:57:544 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:57:545 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:57:559 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:57:560 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:57:560 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:57:561 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:57:561 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:562 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:563 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:57:563 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:595 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:57:597 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:57:597 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:598 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:598 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:57:599 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:57:620 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:57:622 - info: [debug] Condition unmet after 77ms. Timing out.  
2016-02-15 10:54:57:622 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:57:638 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 94.797 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:151 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:58:152 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:172 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 21.194 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:174 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:58:175 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:58:175 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:58:203 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:58:203 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:58:203 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:58:204 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:58:204 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:204 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:204 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:58:204 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:259 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:58:259 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:58:259 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:259 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:266 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:58:266 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:294 - info: [debug] Condition unmet after 119ms. Timing out.  
2016-02-15 10:54:58:295 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:297 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 123.345 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:301 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:58:801 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:58:802 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:806 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 5.462 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:808 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“id”,“value”:“com.android.packageinstaller:id/permission\_allow\_button”}  
2016-02-15 10:54:58:809 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:58:810 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:58:835 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“com.android.packageinstaller:id/permission\_allow\_button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:58:835 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:58:835 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:58:835 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:58:835 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:836 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:836 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:58:836 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:854 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.  
2016-02-15 10:54:58:854 - info: [debug] [BOOTSTRAP] [debug] Finding com.android.packageinstaller:id/permission\_allow\_button using ID with the contextId: multiple: true  
2016-02-15 10:54:58:854 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:854 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:854 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:58:855 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[INSTANCE=0, RESOURCE\_ID=com.android.packageinstaller:id/permission\_allow\_button]  
2016-02-15 10:54:58:883 - info: [debug] Condition unmet after 73ms. Timing out.  
2016-02-15 10:54:58:884 - info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:884 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 76.275 ms - 74 {“status”:0,“value”:[],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:889 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:58:890 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:58:890 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:903 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 13.248 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:904 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[]}  
2016-02-15 10:54:58:919 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:54:58:920 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:54:58:920 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:923 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 4.044 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:948 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:54:58:949 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:952 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 3.749 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:58:953 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“class name”,“value”:“android.widget.Button”}  
2016-02-15 10:54:58:953 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:54:58:953 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“class name”,“selector”:“android.widget.Button”,“context”:"",“multiple”:true}]  
2016-02-15 10:54:58:967 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“class name”,“selector”:“android.widget.Button”,“context”:"",“multiple”:true}}  
2016-02-15 10:54:58:967 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:58:967 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:54:58:968 - info: [debug] [BOOTSTRAP] [debug] Finding android.widget.Button using CLASS\_NAME with the contextId: multiple: true  
2016-02-15 10:54:58:968 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.Button]  
2016-02-15 10:54:58:968 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.Button]  
2016-02-15 10:54:58:968 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:54:58:968 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=0]  
2016-02-15 10:54:58:981 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)  
2016-02-15 10:54:58:997 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“6”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:59:017 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 64.540 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“6”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:59:018 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.Button, INSTANCE=1]  
2016-02-15 10:54:59:018 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“6”}]}  
2016-02-15 10:54:59:019 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:54:59:019 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:54:59:019 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:59:039 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 19.736 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:54:59:060 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/6/click {“id”:“6”}  
2016-02-15 10:54:59:061 - info: [debug] Pushing command to appium work queue: [“element:click”,{“elementId”:“6”}]  
2016-02-15 10:54:59:082 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:click”,“params”:{“elementId”:“6”}}  
2016-02-15 10:54:59:082 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:54:59:082 - info: [debug] [BOOTSTRAP] [debug] Got command action: click  
2016-02-15 10:55:00:622 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:00:623 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/6/click 200 1562.626 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:00:642 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:55:05:632 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:0}  
2016-02-15 10:55:05:641 - info: [debug] Set Android implicit wait to 0ms  
2016-02-15 10:55:05:641 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:644 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 12.166 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:647 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context {}  
2016-02-15 10:55:05:647 - info: [debug] Responding to client with success: {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:658 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/context 200 11.103 ms - 84 {“status”:0,“value”:“NATIVE\_APP”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:658 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements {“using”:“class name”,“value”:“android.widget.ImageButton”}  
2016-02-15 10:55:05:659 - info: [debug] Waiting up to 0ms for condition  
2016-02-15 10:55:05:659 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“class name”,“selector”:“android.widget.ImageButton”,“context”:"",“multiple”:true}]  
2016-02-15 10:55:05:673 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“class name”,“selector”:“android.widget.ImageButton”,“context”:"",“multiple”:true}}  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] Got command action: find  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] Finding android.widget.ImageButton using CLASS\_NAME with the contextId: multiple: true  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[CLASS=android.widget.ImageButton]  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] getElements selector:UiSelector[CLASS=android.widget.ImageButton]  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (0)  
2016-02-15 10:55:05:674 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.ImageButton, INSTANCE=0]  
2016-02-15 10:55:05:702 - info: [debug] [BOOTSTRAP] [debug] Element[] is null: (1)  
2016-02-15 10:55:05:702 - info: [debug] [BOOTSTRAP] [debug] getElements tmp selector:UiSelector[CLASS=android.widget.ImageButton, INSTANCE=1]  
2016-02-15 10:55:05:717 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:[{“ELEMENT”:“7”}]}  
2016-02-15 10:55:05:718 - info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“7”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:719 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/elements 200 60.224 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“7”}],“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:759 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts {“type”:“implicit”,“ms”:5000}  
2016-02-15 10:55:05:760 - info: [debug] Set Android implicit wait to 5000ms  
2016-02-15 10:55:05:760 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:763 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/timeouts 200 3.562 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:05:776 - info: --\> POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/7/click {“id”:“7”}  
2016-02-15 10:55:05:777 - info: [debug] Pushing command to appium work queue: [“element:click”,{“elementId”:“7”}]  
2016-02-15 10:55:05:791 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:click”,“params”:{“elementId”:“7”}}  
2016-02-15 10:55:05:791 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION  
2016-02-15 10:55:05:791 - info: [debug] [BOOTSTRAP] [debug] Got command action: click  
2016-02-15 10:55:06:007 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}  
2016-02-15 10:55:06:007 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:06:008 - info: \<-- POST /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/element/7/click 200 231.563 ms - 76 {“status”:0,“value”:true,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:08:014 - info: --\> GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/screenshot {}  
2016-02-15 10:55:08:016 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell "/system/bin/rm /data/local/tmp/screenshot.png; /system/bin/screencap -p /data/local/tmp/screenshot.png "  
2016-02-15 10:55:09:078 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM pull /data/local/tmp/screenshot.png “/tmp/appium116115-2156-1b46vta.png”  
2016-02-15 10:55:09:444 - info: [debug] Responding to client with success: {“status”:0,“value”:“iVBORw0KGgoAAAANSUhEUgAAAtAAAAUACAYAAABuzmU9AAAABHNCSVQICAgIfAhkiAAAIABJREFUeJzsvXucZEV5//+pOn2bnevuXPaOwCILBBBYQBTEBVGJ7gIaNWD0q2AiCxoFBf2awLIgahQVk5hkhSS/fEWWePnGhAX9euFm1ERlAQMirHJb9r47O7MzOzvTl1PP74+qOn1O9+nuc7pP3+vNa3Vmuk919blUPfXU53ke9uSrXkUwGAwGg8FgMBgMgeDN7oDBYDAYDAaDwdBOGAPaYDAYDAaDwWAIgTGgDQaDwWAwGAyGEBgD2mAwGAwGg8FgCIExoA0Gg8FgMBgMhhAYA9pgMBgMBoPBYAiBMaANBoPBYDAYDIYQxJrdgVbixCeeANk2mGWV/H8Avq9l0ln8xZuuxBtXnYHzPn05kgO9ntc1fr/rNvXvT51ySlX9/4MtW8p+RjnSh+fwqTddiTetOgPnf/ZPkehNVdUHAFX332AwGAwGg6EdaC0DmnOASP5rEtrYLPX/hX+b2j+JL15+I/a8uBMAkJ1N44cf34h5I4M462PvQO/oUJEB62fQBjFyg/Y9THu6//u374Gds5GdTeMH1/wd5o0M4tXX/BH6Fs6vqh9uA75e8N5exEdHYQ0OIjZ/PgrvmuzOnbCnp5HdtavufTEYDAaDwdA9tI4BzVjecHb/3KJM7B7H33/kc9i+9SUIIYpeP7z/IB664Z/ROzYfZ3zoIvQtGm5CL0szsWccf//nrv4XnO7D+w/i4fX/X1X9r6vxzBjmX3wxRq+4AvGlS+W9UoHZp57CzltvxdzWrfXrVwBSK1di8Sc+gZc+8hGImZlQx/LeXrzib/4Gu7/0Jcw+/XSdemgwGPxILFmC+OLFmHnssZacm+KLF4MxhszOnc3uSscwtGYNFl93HQBg9+23Y+I//qPJPZIs+/SnkTr22JKvZ3ftwp6NGzH37LMtea92Eq1hQDOG1CtfifmXXILMzp2Y…”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:09:463 - info: \<-- GET /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54/screenshot 200 1448.613 ms - 236710 {“status”:0,“value”:“iVBORw0KGgoAAAANSUhEUgAAAtAAAAUACAYAAABuzmU9AAAABHNCSVQICAgIfAhkiAAAIABJREFUeJzsvXucZEV5//+pOn2bnevuXPaOwCILBBBYQBTEBVGJ7gIaNWD0q2AiCxoFBf2awLIgahQVk5hkhSS/fEWWePnGhAX9euFm1ERlAQMirHJb9r47O7MzOzvTl1PP74+qOn1O9+nuc7pP3+vNa3Vmuk919blUPfXU53ke9uSrXkUwGAwGg8FgMBgMgeDN7oDBYDAYDAaDwdBOGAPaYDAYDAaDwWAIgTGgDQaDwWAwGAyGEBgD2mAwGAwGg8FgCIExoA0Gg8FgMBgMhhAYA9pgMBgMBoPBYAiBMaANBoPBYDAYDIYQxJrdgVbixCeeANk2mGWV/H8Avq9l0ln8xZuuxBtXnYHzPn05kgO9ntc1fr/rNvXvT51ySlX9/4MtW8p+RjnSh+fwqTddiTetOgPnf/ZPkehNVdUHAFX332AwGAwGg6EdaC0DmnOASP5rEtrYLPX/hX+b2j+JL15+I/a8uBMAkJ1N44cf34h5I4M462PvQO/oUJEB62fQBjFyg/Y9THu6//u374Gds5GdTeMH1/wd5o0M4tXX/BH6Fs6vqh9uA75e8N5exEdHYQ0OIjZ/PgrvmuzOnbCnp5HdtavufTEYDAaDwdA9tI4BzVjecHb/3KJM7B7H33/kc9i+9SUIIYpeP7z/IB664Z/ROzYfZ3zoIvQtGm5CL0szsWccf//nrv4XnO7D+w/i4fX/X1X9r6vxzBjmX3wxRq+4AvGlS+W9UoHZp57CzltvxdzWrfXrVwBSK1di8Sc+gZc+8hGImZlQx/LeXrzib/4Gu7/0Jcw+/XSdemgwGPxILFmC+OLFmHnssZacm+KLF4MxhszOnc3uSscwtGYNFl93HQBg9+23Y+I//qPJPZIs+/SnkTr22JKvZ3ftwp6NGzH37LMtea92Eq1hQDOG1CtfifmXXILMzp2Y…”,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:09:754 - info: --\> DELETE /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54 {}  
2016-02-15 10:55:09:755 - info: Shutting down appium session  
2016-02-15 10:55:09:756 - info: [debug] Pressing the HOME button  
2016-02-15 10:55:09:757 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ZX1D528ZJM shell “input keyevent 3”  
2016-02-15 10:55:10:763 - info: [debug] Stopping logcat capture  
2016-02-15 10:55:10:764 - info: [debug] Logcat terminated with code 143, signal null  
2016-02-15 10:55:10:779 - info: [debug] Sent shutdown command, waiting for UiAutomator to stop…  
2016-02-15 10:55:10:780 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“shutdown”}  
2016-02-15 10:55:10:780 - info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN  
2016-02-15 10:55:10:780 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:“OK, shutting down”}  
2016-02-15 10:55:10:780 - info: [debug] [BOOTSTRAP] [debug] Closed client connection  
2016-02-15 10:55:10:780 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: numtests=1  
2016-02-15 10:55:10:781 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: stream=.  
2016-02-15 10:55:10:781 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: id=UiAutomatorTestRunner  
2016-02-15 10:55:10:781 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: test=testRunServer  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: class=io.appium.android.bootstrap.Bootstrap  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: current=1  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS\_CODE: 0  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS: stream=  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.  
2016-02-15 10:55:10:782 - info: [debug] [UIAUTOMATOR STDOUT] Time: 99.315  
2016-02-15 10:55:10:794 - info: [debug] [UIAUTOMATOR STDOUT] OK (1 test)  
2016-02-15 10:55:10:794 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION\_STATUS\_CODE: -1  
2016-02-15 10:55:10:811 - info: [debug] UiAutomator shut down normally  
2016-02-15 10:55:10:811 - info: [debug] Cleaning up android objects  
2016-02-15 10:55:10:812 - info: [debug] Cleaning up appium session  
2016-02-15 10:55:10:812 - info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}  
2016-02-15 10:55:10:816 - info: \<-- DELETE /wd/hub/session/d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54 200 1062.071 ms - 76 {“status”:0,“value”:null,“sessionId”:“d1e7a131-cf0e-490d-b97d-1cf4c4cd5e54”}