После запуска тестов на IEDrivwer получаю ошибку PHP notice: undefined index: HTTP_ACCEPT_LANGUAGE

antinua · 17.Июль.2014 12:50:50

Добрый день. Запускаю selenium тесты на IEDriver из jenkins. Тест запускается, проходит авторизацию и падает с ошибкой PHP notice: undefined index: HTTP_ACCEPT_LANGUAGE.
те же действия вручную в браузере на сервере не вызывают появление ошибки.

System: Windows 2008 Server.
IE 11.0
Selenium 2.42.0

2014-07-15 03:42:13,810 - INFO - Automation Run Started
Started InternetExplorerDriver server (64-bit)
2.42.0.0
Listening on port 26038
log4j:WARN No appenders could be found for logger (org.apache.http.client.protocol.RequestAddCookies).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See Apache log4j 1.2 - Frequently Asked Technical Questions for more info.
2014-07-15 03:42:16,749 - INFO - BROWSER INFO: name = internet explorer, version = 11
.2014-07-15 03:42:16,797 - INFO - ########################################################################
2014-07-15 03:42:16,797 - INFO - TestPlan - Starting smokeNavigation - Testing Navigation throughout the site, all the Main tabs, and sub tabs (manager focused)
2014-07-15 03:42:16,802 - INFO - ########################################################################
2014-07-15 03:42:16,803 - INFO - TestCase - Starting testNavigation - Test Navigation
2014-07-15 03:42:16,803 - INFO - ########################################################################
2014-07-15 03:42:16,828 - ERROR - java.lang.IllegalArgumentException: Malformed \uxxxx encoding.
2014-07-15 03:42:18,208 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 03:42:18,208 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 03:42:19,550 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 03:42:19,550 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 03:42:19,622 - INFO - INFO - NA - Visiting Url: http://chqtest.chequed.com/site/login
2014-07-15 03:42:20,319 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 03:42:19 to change
2014-07-15 03:42:20,343 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 03:42:19 and no longer found it
2014-07-15 03:42:20,374 - INFO - ASSERT - PASSED - Checking WebPageTitle - expected[ Chequed.com - Log In ] was [ Chequed.com - Log In ]
2014-07-15 03:42:20,543 - DEBUG - getElement [By.id: LoginForm_userName] was expected to be [Found]
2014-07-15 03:43:26,673 - INFO - ASSERT - PASSED - After Enter Text to element: By.id: LoginForm_userName Checking if values match - expected[ true ] was [ true ]
2014-07-15 03:43:26,917 - DEBUG - getElement [By.className: LoginForm_passwordHidden] was expected to be [Found]
2014-07-15 03:44:58,313 - INFO - ASSERT - PASSED - After Enter Text to element: By.className: LoginForm_passwordHidden Checking if values match - expected[ true ] was [ true ]
2014-07-15 03:44:58,471 - INFO - INFO - NA - Clicking on element: By.name: yt0
2014-07-15 03:44:58,571 - DEBUG - getElement [By.name: yt0] was expected to be [Found]
2014-07-15 03:44:59,070 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 03:42:20 to change
2014-07-15 03:45:00,133 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 03:42:20 and no longer found it
2014-07-15 03:45:00,187 - INFO - ASSERT - PASSED - Expected pagetitle to change from Automation: Waiting for Page To Load - 03:42:20 found title PHP notice - expected[ true ] was [ true ]

davert · 18.Июль.2014 00:04:04

Это явно не имеет отношения к IEDriver как к таковому, и должно быть пофикшено в коде приложения.
Насколько я могу судить, драйвер не выставляет header с HTTP_ACCEPT_LANGUAGE, а приложение использует его. Всё что вам, а точнее разработчикам, надо сделать - предусмотреть возможность, когда этого хэдера нет.

if (isset($_SERVER('HTTP_ACCEPT_LANGUAGE')) {
     // PHP код который сейчас валит тесты
     // скорее всего, тут мы включаем нужный перевод сайта
}

Почините этот момент и сам сайт будет стабильнее

joemast · 18.Июль.2014 07:27:23

Ты что-то путаешь: тест на Java не может падать с такой ошибкой Собственно место падения в логе не видно.

Напиши как ты определяешь что тест упал? Лог точно полный?

antinua · 18.Июль.2014 23:12:35

тест на Java, тестируемый сервис на yii фреймворке php.
определяю, что упал по тексту found title PHP notice по скрину с этой ошибкой.

ниже полный лог.

Building remotely on selenium01 (SELENIUM) in workspace C:\hudson\workspace\ST-test-TestPlan-_smokePositions
[ST-test-TestPlan-_smokePositions] $ cmd /c call C:\Windows\TEMP\hudson119445018300589471.bat

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>taskkill /F /IM firefox.exe
ERROR: The process “firefox.exe” not found.

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>taskkill /F /IM IEDriverServer.exe
ERROR: The process “IEDriverServer.exe” not found.

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>taskkill /F /IM iexplore.exe
ERROR: The process “iexplore.exe” not found.

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>taskkill /F /IM chromedriver.exe
ERROR: The process “chromedriver.exe” not found.

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>taskkill /F /IM chrome.exe
ERROR: The process “chrome.exe” not found.

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>set ERRORLEVEL=0

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>exit 0
[ST-test-TestPlan-_smokePositions] $ cmd /c call C:\Windows\TEMP\hudson1325079157778024582.bat

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.html

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.log
Could Not Find C:\hudson\workspace\ST-test-TestPlan-_smokePositions*.log

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.txt
Could Not Find C:\hudson\workspace\ST-test-TestPlan-_smokePositions*.txt

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.png
Could Not Find C:\hudson\workspace\ST-test-TestPlan-_smokePositions*.png

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.xml

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>del *.jpg
Could Not Find C:\hudson\workspace\ST-test-TestPlan-_smokePositions*.jpg

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>java -Dci=“http://jenkins.chequed.com:8080/job/ST-test-TestPlan-_smokePositions/771/” -Drp=“C:\hudson\workspace\ST-test-TestPlan-_smokePositions\” -Dbrowser=ie -Denvir=“test” -cp “C:\hudson\FTester_MAIN.jar” org.junit.runner.JUnitCore com.test.ftester.testplans.smokePositions
JUnit version 4.8.2
#################################################

INFO - Looking for Runtime params in SELENIUM01$.properties

INFO - Looking for Runtime params in System.Properties
Found Param: browser=ie - calling the method: setBrowser - the browser to run the test on
Found Param: envir=test - calling the method: setEnvir - the environment to run the test on
Found Param: ci=http://jenkins.chequed.com:8080/job/ST-test-TestPlan-_smokePositions/771/ - calling the method: setCiUrl - url to the ci build where test was run
Found Param: rp=C:\hudson\workspace\ST-test-TestPlan-_smokePositions\ - calling the method: setReportPath - where to write reports out to
#################################################

2014-07-15 02:58:09,253 - INFO - Automation Run Started
Started InternetExplorerDriver server (32-bit)
2.39.0.0
Listening on port 11186
log4j:WARN No appenders could be found for logger (org.apache.http.client.protocol.RequestAddCookies).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See Apache log4j 1.2 - Frequently Asked Technical Questions for more info.
2014-07-15 02:58:13,793 - INFO - BROWSER INFO: name = internet explorer, version = 11
.2014-07-15 02:58:13,841 - INFO - ########################################################################
2014-07-15 02:58:13,841 - INFO - TestPlan - Starting smokePositions - Testing the Position section
2014-07-15 02:58:13,847 - INFO - ########################################################################
2014-07-15 02:58:13,847 - INFO - TestCase - Starting testSortingOnPage - Smoke of Position Section, from adding to add candidate
2014-07-15 02:58:13,847 - INFO - ########################################################################
2014-07-15 02:58:13,877 - ERROR - java.lang.IllegalArgumentException: Malformed \uxxxx encoding.
2014-07-15 02:58:15,520 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 02:58:15,520 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 02:58:16,821 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 02:58:16,821 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 02:58:16,923 - INFO - INFO - NA - Visiting Url: http://chqtest.chequed.com/site/login
2014-07-15 02:58:18,525 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:58:16 to change
2014-07-15 02:58:18,540 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:58:16 and no longer found it
2014-07-15 02:58:18,579 - INFO - ASSERT - PASSED - Checking WebPageTitle - expected[ Chequed.com - Log In ] was [ Chequed.com - Log In ]
2014-07-15 02:58:18,671 - DEBUG - getElement [By.id: LoginForm_userName] was expected to be [Found]
2014-07-15 02:58:19,401 - INFO - ASSERT - PASSED - After Enter Text to element: By.id: LoginForm_userName Checking if values match - expected[ true ] was [ true ]
2014-07-15 02:58:19,511 - DEBUG - getElement [By.className: LoginForm_passwordHidden] was expected to be [Found]
2014-07-15 02:58:20,449 - INFO - ASSERT - PASSED - After Enter Text to element: By.className: LoginForm_passwordHidden Checking if values match - expected[ true ] was [ true ]
2014-07-15 02:58:20,508 - INFO - INFO - NA - Clicking on element: By.name: yt0
2014-07-15 02:58:20,543 - DEBUG - getElement [By.name: yt0] was expected to be [Found]
2014-07-15 02:58:20,853 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:58:18 to change
2014-07-15 02:58:21,892 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:58:18 and no longer found it
2014-07-15 02:58:21,923 - INFO - ASSERT - PASSED - Expected pagetitle to change from Automation: Waiting for Page To Load - 02:58:18 found title PHP notice - expected[ true ] was [ true ]
[0] Waiting for page title: Chequed.com - My Positions
[1] Waiting for page title: Chequed.com - My Positions
[2] Waiting for page title: Chequed.com - My Positions
[3] Waiting for page title: Chequed.com - My Positions
[4] Waiting for page title: Chequed.com - My Positions
[5] Waiting for page title: Chequed.com - My Positions
[6] Waiting for page title: Chequed.com - My Positions
[7] Waiting for page title: Chequed.com - My Positions
[8] Waiting for page title: Chequed.com - My Positions
[9] Waiting for page title: Chequed.com - My Positions
2014-07-15 02:58:33,576 - ERROR - ASSERT - FAILED - Checking WebPageTitle - expected:[ <Chequed.com - My Positions> ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_58_33_07_15_2014.112.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:258)
at com.test.ftester.framework.report.VAssert.assertPageTitle(VAssert.java:124)
at com.test.ftester.framework.report.VAssert.assertPageTitle(VAssert.java:93)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:88)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:56)
at com.test.ftester.helpers.AppHelper.login(AppHelper.java:231)
at com.test.ftester.testplans.smokePositions.setUp(smokePositions.java:31)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:58:34,883 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:36,174 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:37,460 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:38,758 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:40,055 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:41,344 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:42,637 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:43,929 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:45,248 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:46,536 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:46,802 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_58_46_07_15_2014.536.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSortingOnPage(smokePositions.java:40)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:58:46,804 - INFO - ASSERT - PASSED - Looking for Option[label=Creation Date(Newest First) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 02:58:48,096 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:49,385 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:50,677 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:51,962 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:53,249 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:54,535 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:55,826 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:57,114 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:58,406 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:59,696 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 02:58:59,940 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_58_59_07_15_2014.696.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSortingOnPage(smokePositions.java:40)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:58:59,941 - INFO - ASSERT - PASSED - Looking for Option[label=Position(A-Z) - Location(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 02:59:01,226 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:02,518 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:03,807 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:05,095 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:06,393 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:07,691 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:08,980 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:10,268 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:11,556 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:12,857 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:13,112 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_59_12_07_15_2014.857.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSortingOnPage(smokePositions.java:40)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:59:13,113 - INFO - ASSERT - PASSED - Looking for Option[label=Location(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 02:59:14,402 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:15,692 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:16,981 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:18,269 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:19,563 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:20,851 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:22,138 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:23,427 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:24,728 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:26,016 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 02:59:26,253 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_59_26_07_15_2014.016.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSortingOnPage(smokePositions.java:40)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:59:26,254 - INFO - ASSERT - PASSED - Looking for Option[label=Product(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 02:59:26,254 - WARN - No Step Defined for Test Case!
2014-07-15 02:59:26,255 - INFO - Step - desc: Holder Step for TestCase - expected:
E.2014-07-15 02:59:26,262 - INFO - ########################################################################
2014-07-15 02:59:26,262 - INFO - TestCase - Starting testSearch -
2014-07-15 02:59:26,262 - INFO - ########################################################################
2014-07-15 02:59:27,563 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 02:59:27,563 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 02:59:28,893 - INFO - [0] Waiting for element to exist: By.xpath: //a[@href=‘/site/logout’]
2014-07-15 02:59:28,893 - DEBUG - getElement [By.xpath: //a[@href=‘/site/logout’]] was expected to be [Found]
2014-07-15 02:59:28,924 - INFO - INFO - NA - Visiting Url: http://chqtest.chequed.com/site/login
2014-07-15 02:59:30,090 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:59:28 to change
2014-07-15 02:59:30,105 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:59:28 and no longer found it
[0] Waiting for page title: Chequed.com - Log In
[1] Waiting for page title: Chequed.com - Log In
[2] Waiting for page title: Chequed.com - Log In
[3] Waiting for page title: Chequed.com - Log In
[4] Waiting for page title: Chequed.com - Log In
[5] Waiting for page title: Chequed.com - Log In
[6] Waiting for page title: Chequed.com - Log In
[7] Waiting for page title: Chequed.com - Log In
[8] Waiting for page title: Chequed.com - Log In
[9] Waiting for page title: Chequed.com - Log In
2014-07-15 02:59:40,790 - ERROR - ASSERT - FAILED - Checking WebPageTitle - expected:[ <Chequed.com - Log In> ] but was:[ <Chequed.com - My Positions> ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_59_40_07_15_2014.287.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:258)
at com.test.ftester.framework.report.VAssert.assertPageTitle(VAssert.java:124)
at com.test.ftester.resource.NavTab.goDirectToPage(NavTab.java:177)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:75)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:56)
at com.test.ftester.helpers.AppHelper.login(AppHelper.java:231)
at com.test.ftester.testplans.smokePositions.setUp(smokePositions.java:31)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:59:42,622 - INFO - [0] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:44,158 - INFO - [1] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:45,667 - INFO - [2] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:47,229 - INFO - [3] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:48,742 - INFO - [4] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:50,283 - INFO - [5] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:51,823 - INFO - [6] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:53,367 - INFO - [7] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:54,922 - INFO - [8] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:56,491 - INFO - [9] Waiting for element to exist: By.id: LoginForm_userName
2014-07-15 02:59:56,974 - ERROR - ASSERT - FAILED - getElement [By.id: LoginForm_userName] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_02_59_56_07_15_2014.491.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.enterText(Driver.java:685)
at com.test.ftester.framework.web.Driver.enterText(Driver.java:668)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:77)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:56)
at com.test.ftester.helpers.AppHelper.login(AppHelper.java:231)
at com.test.ftester.testplans.smokePositions.setUp(smokePositions.java:31)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 02:59:58,518 - INFO - [0] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:00,019 - INFO - [1] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:01,548 - INFO - [2] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:03,080 - INFO - [3] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:04,613 - INFO - [4] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:06,118 - INFO - [5] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:07,655 - INFO - [6] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:09,186 - INFO - [7] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:10,721 - INFO - [8] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:12,255 - INFO - [9] Waiting for element to exist: By.className: LoginForm_passwordHidden
2014-07-15 03:00:12,725 - ERROR - ASSERT - FAILED - getElement [By.className: LoginForm_passwordHidden] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_00_12_07_15_2014.255.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.enterText(Driver.java:685)
at com.test.ftester.framework.web.Driver.enterText(Driver.java:668)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:78)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:56)
at com.test.ftester.helpers.AppHelper.login(AppHelper.java:231)
at com.test.ftester.testplans.smokePositions.setUp(smokePositions.java:31)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:00:12,726 - INFO - INFO - NA - Clicking on element: By.name: yt0
2014-07-15 03:00:13,044 - DEBUG - getElement [By.name: yt0] was expected to be [Found]
2014-07-15 03:00:15,626 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:59:40 to change
2014-07-15 03:00:15,641 - DEBUG - Waiting for pagetitle: Automation: Waiting for Page To Load - 02:59:40 and no longer found it
2014-07-15 03:00:15,669 - INFO - ASSERT - PASSED - Expected pagetitle to change from Automation: Waiting for Page To Load - 02:59:40 found title Chequed.com - Add a Position - expected[ true ] was [ true ]
[0] Waiting for page title: Chequed.com - My Positions
[1] Waiting for page title: Chequed.com - My Positions
[2] Waiting for page title: Chequed.com - My Positions
[3] Waiting for page title: Chequed.com - My Positions
[4] Waiting for page title: Chequed.com - My Positions
[5] Waiting for page title: Chequed.com - My Positions
[6] Waiting for page title: Chequed.com - My Positions
[7] Waiting for page title: Chequed.com - My Positions
[8] Waiting for page title: Chequed.com - My Positions
[9] Waiting for page title: Chequed.com - My Positions
2014-07-15 03:00:27,124 - ERROR - ASSERT - FAILED - Checking WebPageTitle - expected:[ <Chequed.com - My Positions> ] but was:[ <Chequed.com - Add a Position> ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_00_26_07_15_2014.871.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:258)
at com.test.ftester.framework.report.VAssert.assertPageTitle(VAssert.java:124)
at com.test.ftester.framework.report.VAssert.assertPageTitle(VAssert.java:93)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:88)
at com.test.ftester.pages.app.LogIn.doLogin(LogIn.java:56)
at com.test.ftester.helpers.AppHelper.login(AppHelper.java:231)
at com.test.ftester.testplans.smokePositions.setUp(smokePositions.java:31)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:00:28,436 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:29,734 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:31,031 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:32,331 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:33,628 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:34,927 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:36,244 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:37,541 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:38,842 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:40,139 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:40,404 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_00_40_07_15_2014.139.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSearch(smokePositions.java:49)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:00:40,405 - INFO - ASSERT - PASSED - Looking for Option[label=Creation Date(Newest First) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 03:00:41,698 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:42,996 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:44,308 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:45,621 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:46,915 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:48,213 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:49,510 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:50,809 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:52,106 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:53,405 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:53,708 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_00_53_07_15_2014.405.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSearch(smokePositions.java:49)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:00:53,709 - INFO - ASSERT - PASSED - Looking for Option[label=Position(A-Z) - Location(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 03:00:55,011 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:56,312 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:57,612 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 03:00:58,910 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:00,221 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:01,519 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:02,815 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:04,112 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:05,411 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:06,708 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:07,029 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_01_06_07_15_2014.708.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSearch(smokePositions.java:49)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:01:07,030 - INFO - ASSERT - PASSED - Looking for Option[label=Location(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 03:01:08,324 - INFO - [0] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:09,635 - INFO - [1] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:10,933 - INFO - [2] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:12,231 - INFO - [3] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:13,530 - INFO - [4] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:14,833 - INFO - [5] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:16,134 - INFO - [6] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:17,435 - INFO - [7] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:18,752 - INFO - [8] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:20,049 - INFO - [9] Waiting for element to exist: By.id: position_sort
2014-07-15 03:01:20,302 - ERROR - ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found] - expected:[ ] but was:[ ]
Screenshot: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\screenshot_03_01_20_07_15_2014.049.jpg
java.lang.Exception: Step Failed
at com.test.ftester.framework.util.Log.getStackTrace(Log.java:86)
at com.test.ftester.framework.report.VAssert.addToResultsCollection(VAssert.java:1266)
at com.test.ftester.framework.report.VAssert.addToAssertResultsCollection(VAssert.java:1208)
at com.test.ftester.framework.report.VAssert.assertEquals(VAssert.java:262)
at com.test.ftester.framework.web.Driver.getElements(Driver.java:919)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:847)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:827)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:809)
at com.test.ftester.framework.web.Driver.getElement(Driver.java:796)
at com.test.ftester.framework.web.Driver.getSelectListOptions(Driver.java:528)
at com.test.ftester.framework.web.Driver.getOptionByLabel(Driver.java:468)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:482)
at com.test.ftester.framework.web.Driver.selectOption(Driver.java:464)
at com.test.ftester.pages.manager.Positions.sort(Positions.java:109)
at com.test.ftester.testplans.smokePositions.testSearch(smokePositions.java:49)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

2014-07-15 03:01:20,303 - INFO - ASSERT - PASSED - Looking for Option[label=Product(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null - expected[ true ] was [ true ]
2014-07-15 03:01:20,303 - WARN - No Step Defined for Test Case!
2014-07-15 03:01:20,304 - INFO - Step - desc: Holder Step for TestCase - expected:
E2014-07-15 03:01:21,359 - INFO - Automation Run Complete
2014-07-15 03:01:21,364 - INFO - Writing out Report: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\smokePositions.html
2014-07-15 03:01:21,372 - INFO - Writing out Report: C:\hudson\workspace\ST-test-TestPlan-_smokePositions\smokePositions.xml

Time: 192.241
There were 2 failures:

testSortingOnPage(com.test.ftester.testplans.smokePositions)
junit.framework.AssertionFailedError:
Holder Step for TestCase
INFO - NA - Visiting Url: http://chqtest.chequed.com/site/login
ASSERT - PASSED - Checking WebPageTitle
ASSERT - PASSED - After Enter Text to element: By.id: LoginForm_userName Checking if values match
ASSERT - PASSED - After Enter Text to element: By.className: LoginForm_passwordHidden Checking if values match
INFO - NA - Clicking on element: By.name: yt0
ASSERT - PASSED - Expected pagetitle to change from Automation: Waiting for Page To Load - 02:58:18 found title PHP notice
ASSERT - FAILED - Checking WebPageTitle
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Creation Date(Newest First) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Position(A-Z) - Location(A-Z) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Location(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Product(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null
at junit.framework.Assert.fail(Assert.java:47)
at com.test.ftester.framework.v1.TestPlan.closeTestCase(TestPlan.java:217)
at com.test.ftester.framework.v1.TestPlan.access$400(TestPlan.java:33)
at com.test.ftester.framework.v1.TestPlan$1.finished(TestPlan.java:111)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:54)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)
testSearch(com.test.ftester.testplans.smokePositions)
junit.framework.AssertionFailedError:
Holder Step for TestCase
INFO - NA - Visiting Url: http://chqtest.chequed.com/site/login
ASSERT - FAILED - Checking WebPageTitle
ASSERT - FAILED - getElement [By.id: LoginForm_userName] was expected to be [Found]
ASSERT - FAILED - getElement [By.className: LoginForm_passwordHidden] was expected to be [Found]
INFO - NA - Clicking on element: By.name: yt0
ASSERT - PASSED - Expected pagetitle to change from Automation: Waiting for Page To Load - 02:59:40 found title Chequed.com - Add a Position
ASSERT - FAILED - Checking WebPageTitle
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Creation Date(Newest First) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Position(A-Z) - Location(A-Z) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Location(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null
ASSERT - FAILED - getElement [By.id: position_sort] was expected to be [Found]
ASSERT - PASSED - Looking for Option[label=Product(A-Z) - Position(A-Z) in list: By.id: position_sort Should not be null
at junit.framework.Assert.fail(Assert.java:47)
at com.test.ftester.framework.v1.TestPlan.closeTestCase(TestPlan.java:217)
at com.test.ftester.framework.v1.TestPlan.access$400(TestPlan.java:33)
at com.test.ftester.framework.v1.TestPlan$1.finished(TestPlan.java:111)
at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:54)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runners.Suite.runChild(Suite.java:128)
at org.junit.runners.Suite.runChild(Suite.java:24)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.junit.runner.JUnitCore.run(JUnitCore.java:157)
at org.junit.runner.JUnitCore.run(JUnitCore.java:136)
at org.junit.runner.JUnitCore.run(JUnitCore.java:117)
at org.junit.runner.JUnitCore.runMain(JUnitCore.java:98)
at org.junit.runner.JUnitCore.runMainAndExit(JUnitCore.java:53)
at org.junit.runner.JUnitCore.main(JUnitCore.java:45)

FAILURES!!!
Tests run: 2, Failures: 2

C:\hudson\workspace\ST-test-TestPlan-_smokePositions>exit 1
Build step ‘Execute Windows batch command’ marked build as failure
Archiving artifacts
Recording test results
Finished: FAILURE

antinua · 18.Июль.2014 23:19:25

спасибо, я предложу тимлиду, а там пусть решают.

antinua · 21.Июль.2014 13:36:06

спасибо, все именно так. девелоперы исправили, теперь тест запускается.