t.me/atinfo_chat Telegram группа по автоматизации тестирования

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

Добрый день. Запускаю 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 http://logging.apache.org/log4j/1.2/faq.html#noconfig 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 ]

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

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

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

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

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

тест на 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 http://logging.apache.org/log4j/1.2/faq.html#noconfig 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:

  1. 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)
  2. 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

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

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