Page factory not working in android

      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)

Hi,

Kindly refer code on following reporsitory.

Thanks,
Priyank Shah

1 Like

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.

Hi, sendlink!

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
);

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. :smile:

Thanks,
Priyank Shah

@SergeyTichomirov

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

I get same error

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

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”), 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. :slight_smile:

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

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

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. :slight_smile: The explanation will be over 5 minutes

You can read this about Appium page factory facilities

The root cause of these problems is when you invoke
PageFactory.initElements(driver, this) you actually use DefaultFieldDecorator.

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.

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

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

благодаря again :slight_smile:

@SergeyTichomirov Now its working

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

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("[email protected]");
		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[@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[@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[@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[@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[@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[@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[@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[@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”:[“[email protected]”]}
2016-02-15 10:54:14:466 - info: [debug] Pushing command to appium work queue: [“element:setText”,{“elementId”:“2”,“text”:“[email protected]”,“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”:“[email protected]”,“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: [email protected]
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[@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[@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[@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[@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[@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[@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[@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[@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”}