Skip to content

Instantly share code, notes, and snippets.

@davehunt
Created November 17, 2011 14:12
Show Gist options
  • Save davehunt/1373221 to your computer and use it in GitHub Desktop.
Save davehunt/1373221 to your computer and use it in GitHub Desktop.
Logs from Selenium session issue in 2.12.0
Started by timer
Building on master
Checkout:workspace / /Users/Shared/Jenkins/Home/jobs/mdn.staging/workspace - hudson.remoting.LocalChannel@5ea47962
Using strategy: Default
Last Built Revision: Revision 14384a8d45f03d4f76934cec602b3514cfcd9502 (origin/HEAD, origin/master)
Checkout:workspace / /Users/Shared/Jenkins/Home/jobs/mdn.staging/workspace - hudson.remoting.LocalChannel@5ea47962
Wiping out workspace first.
Cloning the remote Git repository
Cloning repository origin
Fetching upstream changes from git://github.com/mozilla/mdn-tests.git
Seen branch in repository origin/HEAD
Seen branch in repository origin/master
Seen branch in repository origin/staging
Commencing build of Revision 14384a8d45f03d4f76934cec602b3514cfcd9502 (origin/HEAD, origin/master)
Checking out Revision 14384a8d45f03d4f76934cec602b3514cfcd9502 (origin/HEAD, origin/master)
Warning : There are multiple branch changesets here
[EnvInject] - Injecting as environment variables the properties content
'PATH=.env/bin:/usr/bin:/bin:/usr/sbin:/sbin'
[workspace] $ /bin/sh -xe /var/folders/Wp/Wp49uNCTGAq9KGDuyHFZDU+++TI/-Tmp-/hudson5706040812917800361.sh
+ '[' -d .env ']'
+ echo '**> creating virtualenv'
**> creating virtualenv
+ /usr/local/bin/virtualenv --no-site-packages .env
New python executable in .env/bin/python
Installing setuptools............done.
Installing pip...............done.
[workspace] $ /bin/sh -xe /var/folders/Wp/Wp49uNCTGAq9KGDuyHFZDU+++TI/-Tmp-/hudson3777986734158232780.sh
+ pip install -r requirements/requirements.txt
Downloading/unpacking selenium (from -r requirements/requirements.txt (line 4))
Running setup.py egg_info for package selenium
/System/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/distutils/dist.py:266: UserWarning: Unknown distribution option: 'src_root'
warnings.warn(msg)
warning: no files found matching 'docs/api/py/index.rst'
Downloading/unpacking pytest (from -r requirements/requirements.txt (line 5))
Running setup.py egg_info for package pytest
Downloading/unpacking pytest-xdist (from -r requirements/requirements.txt (line 6))
Downloading pytest-xdist-1.6.zip
Running setup.py egg_info for package pytest-xdist
warning: no previously-included files found matching '*.orig'
warning: no previously-included files found matching '*.rej'
warning: no previously-included files matching '*.pyc' found under directory 'testing'
warning: no previously-included files matching '*$py.class' found under directory 'testing'
no previously-included directories found matching '.svn'
no previously-included directories found matching '.hg'
Downloading/unpacking unittestzero (from -r requirements/requirements.txt (line 7))
Downloading UnittestZero-0.1.6.tar.gz
Running setup.py egg_info for package unittestzero
Downloading/unpacking pytest-mozwebqa (from -r requirements/requirements.txt (line 8))
Downloading pytest-mozwebqa-0.5.tar.gz
Running setup.py egg_info for package pytest-mozwebqa
Downloading/unpacking rdflib==3.1.0 (from selenium->-r requirements/requirements.txt (line 4))
Running setup.py egg_info for package rdflib
Downloading/unpacking py>=1.4.5 (from pytest->-r requirements/requirements.txt (line 5))
Running setup.py egg_info for package py
Downloading/unpacking execnet>=1.0.8 (from pytest-xdist->-r requirements/requirements.txt (line 6))
Running setup.py egg_info for package execnet
no previously-included directories found matching 'doc/_build'
warning: no previously-included files found matching '*.orig'
warning: no previously-included files found matching '*.rej'
warning: no previously-included files matching '*.pyc' found under directory 'execnet'
warning: no previously-included files matching '*$py.class' found under directory 'execnet'
warning: no previously-included files matching '*.pyc' found under directory 'testing'
warning: no previously-included files matching '*$py.class' found under directory 'testing'
no previously-included directories found matching '.svn'
no previously-included directories found matching '.hg'
Downloading/unpacking pyyaml (from pytest-mozwebqa->-r requirements/requirements.txt (line 8))
Running setup.py egg_info for package pyyaml
Installing collected packages: selenium, pytest, pytest-xdist, unittestzero, pytest-mozwebqa, rdflib, py, execnet, pyyaml
Running setup.py install for selenium
/System/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/distutils/dist.py:266: UserWarning: Unknown distribution option: 'src_root'
warnings.warn(msg)
warning: no files found matching 'docs/api/py/index.rst'
Running setup.py install for pytest
Installing py.test script to /Users/Shared/Jenkins/Home/jobs/mdn.staging/workspace/.env/bin
Installing py.test-2.6 script to /Users/Shared/Jenkins/Home/jobs/mdn.staging/workspace/.env/bin
Running setup.py install for pytest-xdist
warning: no previously-included files found matching '*.orig'
warning: no previously-included files found matching '*.rej'
warning: no previously-included files matching '*.pyc' found under directory 'testing'
warning: no previously-included files matching '*$py.class' found under directory 'testing'
no previously-included directories found matching '.svn'
no previously-included directories found matching '.hg'
Running setup.py install for unittestzero
Running setup.py install for pytest-mozwebqa
Running setup.py install for rdflib
Running setup.py install for py
Running setup.py install for execnet
no previously-included directories found matching 'doc/_build'
warning: no previously-included files found matching '*.orig'
warning: no previously-included files found matching '*.rej'
warning: no previously-included files matching '*.pyc' found under directory 'execnet'
warning: no previously-included files matching '*$py.class' found under directory 'execnet'
warning: no previously-included files matching '*.pyc' found under directory 'testing'
warning: no previously-included files matching '*$py.class' found under directory 'testing'
no previously-included directories found matching '.svn'
no previously-included directories found matching '.hg'
Running setup.py install for pyyaml
checking if libyaml is compilable
gcc-4.2 -fno-strict-aliasing -fno-common -dynamic -DNDEBUG -g -fwrapv -Os -Wall -Wstrict-prototypes -DENABLE_DTRACE -arch i386 -arch ppc -arch x86_64 -pipe -I/System/Library/Frameworks/Python.framework/Versions/2.6/include/python2.6 -c build/temp.macosx-10.6-universal-2.6/check_libyaml.c -o build/temp.macosx-10.6-universal-2.6/check_libyaml.o
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:2:18: error: yaml.h: No such file or directory
build/temp.macosx-10.6-universal-2.6/check_libyaml.c: In function 'main':
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: 'yaml_parser_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: (Each undeclared identifier is reported only once
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: for each function it appears in.)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: expected ';' before 'parser'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: 'yaml_emitter_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: expected ';' before 'emitter'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: warning: implicit declaration of function 'yaml_parser_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: error: 'parser' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:9: warning: implicit declaration of function 'yaml_parser_delete'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: warning: implicit declaration of function 'yaml_emitter_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: error: 'emitter' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:12: warning: implicit declaration of function 'yaml_emitter_delete'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:2:18: error: yaml.h: No such file or directory
build/temp.macosx-10.6-universal-2.6/check_libyaml.c: In function 'main':
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: 'yaml_parser_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: (Each undeclared identifier is reported only once
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: for each function it appears in.)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: expected ';' before 'parser'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: 'yaml_emitter_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: expected ';' before 'emitter'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: warning: implicit declaration of function 'yaml_parser_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: error: 'parser' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:9: warning: implicit declaration of function 'yaml_parser_delete'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: warning: implicit declaration of function 'yaml_emitter_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: error: 'emitter' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:12: warning: implicit declaration of function 'yaml_emitter_delete'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:2:18: error: yaml.h: No such file or directory
build/temp.macosx-10.6-universal-2.6/check_libyaml.c: In function 'main':
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: 'yaml_parser_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: (Each undeclared identifier is reported only once
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: for each function it appears in.)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:5: error: expected ';' before 'parser'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: 'yaml_emitter_t' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:6: error: expected ';' before 'emitter'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: warning: implicit declaration of function 'yaml_parser_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:8: error: 'parser' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:9: warning: implicit declaration of function 'yaml_parser_delete'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: warning: implicit declaration of function 'yaml_emitter_initialize'
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:11: error: 'emitter' undeclared (first use in this function)
build/temp.macosx-10.6-universal-2.6/check_libyaml.c:12: warning: implicit declaration of function 'yaml_emitter_delete'
lipo: can't open input file: /var/folders/Wp/Wp49uNCTGAq9KGDuyHFZDU+++TI/-Tmp-//ccSMw0PS.out (No such file or directory)
libyaml is not found or a compiler error: forcing --without-libyaml
(if libyaml is installed correctly, you may need to
specify the option --include-dirs or uncomment and
modify the parameter include_dirs in setup.cfg)
Successfully installed selenium pytest pytest-xdist unittestzero pytest-mozwebqa rdflib py execnet pyyaml
Cleaning up...
[workspace] $ /bin/sh -xe /var/folders/Wp/Wp49uNCTGAq9KGDuyHFZDU+++TI/-Tmp-/hudson898862277646713640.sh
+ py.test -r fsxX -n 10 --baseurl=http://developer-stage9.mozilla.org --api=rc '--environment=Firefox 7 on Mac OS X' --timeout=180000 --junitxml=results.xml --credentials=/Users/daveandpam/workspace/webqa-credentials/mdn.yaml --junitxml=results.xml
============================= test session starts ==============================
platform darwin -- Python 2.6.1 -- pytest-2.1.3
gw0 I / gw1 I / gw2 I / gw3 I / gw4 I / gw5 I / gw6 I / gw7 I / gw8 I / gw9 I
gw0 [12] / gw1 [12] / gw2 [12] / gw3 [12] / gw4 [12] / gw5 [12] / gw6 [12] / gw7 [12] / gw8 [12] / gw9 [12]
scheduling tests via LoadScheduling
.x........E.
==================================== ERRORS ====================================
________ ERROR at setup of TestLearnPage.test_page_elements_are_visible ________
[gw0] darwin -- Python 2.6.1 /Users/Shared/Jenkins/Home/jobs/mdn.staging/workspace/.env/bin/python
item = <Function 'test_page_elements_are_visible'>
def pytest_runtest_setup(item):
item.api = item.config.option.api
item.host = item.config.option.host
item.port = item.config.option.port
item.driver = item.config.option.driver
item.chrome_path = item.config.option.chrome_path
item.firefox_path = item.config.option.firefox_path
item.browser = item.config.option.browser
item.environment = item.config.option.environment
item.browser_name = item.config.option.browser_name
item.browser_version = item.config.option.browser_version
item.platform = item.config.option.platform
TestSetup.base_url = item.config.option.base_url
TestSetup.timeout = item.config.option.timeout
TestSetup.default_implicit_wait = 10
item.sauce_labs_credentials_file = item.config.option.sauce_labs_credentials_file
if item.sauce_labs_credentials_file:
item.sauce_labs_credentials = _credentials(item.config.option.sauce_labs_credentials_file)
item.credentials_file = item.config.option.credentials_file
if item.credentials_file:
TestSetup.credentials = _credentials(item.credentials_file)
if not 'skip_selenium' in item.keywords:
_check_selenium_usage(item)
> _start_selenium(item)
.env/lib/python2.6/site-packages/mozwebqa/mozwebqa.py:100:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
item = <Function 'test_page_elements_are_visible'>
def _start_selenium(item):
if item.api == 'webdriver':
_start_webdriver_client(item)
else:
> _start_rc_client(item)
.env/lib/python2.6/site-packages/mozwebqa/mozwebqa.py:302:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
item = <Function 'test_page_elements_are_visible'>
def _start_rc_client(item):
if item.sauce_labs_credentials_file:
settings = _get_common_sauce_settings(item)
settings.update({'username': item.sauce_labs_credentials['username'],
'access-key': item.sauce_labs_credentials['api-key'],
'os': item.platform,
'browser': item.browser_name,
'browser-version': item.browser_version})
TestSetup.selenium = selenium('ondemand.saucelabs.com', '80',
json.dumps(settings),
TestSetup.base_url)
else:
browser = item.environment or item.browser
TestSetup.selenium = selenium(item.host, str(item.port), browser, TestSetup.base_url)
if item.config.option.capture_network:
TestSetup.selenium.start("captureNetworkTraffic=true")
else:
TestSetup.selenium.start()
if item.sauce_labs_credentials_file:
_capture_session_id(item, _debug_path(item))
> TestSetup.selenium.set_timeout(TestSetup.timeout)
.env/lib/python2.6/site-packages/mozwebqa/mozwebqa.py:371:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
self = <selenium.selenium.selenium object at 0x1027f9f10>, timeout = 180000
def set_timeout(self,timeout):
"""
Specifies the amount of time that Selenium will wait for actions to complete.
Actions that require waiting include "open" and the "waitFor\*" actions.
The default timeout is 30 seconds.
'timeout' is a timeout in milliseconds, after which the action will return with an error
"""
> self.do_command("setTimeout", [timeout,])
.env/lib/python2.6/site-packages/selenium/selenium.py:1729:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
self = <selenium.selenium.selenium object at 0x1027f9f10>, verb = 'setTimeout'
args = [180000]
def do_command(self, verb, args):
conn = httplib.HTTPConnection(self.host, self.port)
try:
body = u'cmd=' + urllib.quote_plus(unicode(verb).encode('utf-8'))
for i in range(len(args)):
body += '&' + unicode(i+1) + '=' + \
urllib.quote_plus(unicode(args[i]).encode('utf-8'))
if (None != self.sessionId):
body += "&sessionId=" + unicode(self.sessionId)
headers = {
"Content-Type":
"application/x-www-form-urlencoded; charset=utf-8"
}
conn.request("POST", "/selenium-server/driver/", body, headers)
response = conn.getresponse()
data = unicode(response.read(), "UTF-8")
if (not data.startswith('OK')):
> raise Exception, data
E Exception: <html>
E <head>
E <title>Error 500 org.openqa.grid.common.exception.GridException: Session [a22e9443040744fb963b7e7397c75778] not available - [ext. key 1107d4514eff4d7f9136a93def8a6844, ext. key 4bf56910a3174405a5d456dadfd6ced6, c97059f2-24dc-4d22-8f0f-f0f3dea4d589 (int. key, remote not contacted yet.)]</title>
E </head>
E <body>
E <h2>HTTP ERROR: 500</h2><pre>org.openqa.grid.common.exception.GridException: Session [a22e9443040744fb963b7e7397c75778] not available - [ext. key 1107d4514eff4d7f9136a93def8a6844, ext. key 4bf56910a3174405a5d456dadfd6ced6, c97059f2-24dc-4d22-8f0f-f0f3dea4d589 (int. key, remote not contacted yet.)]</pre>
E <p>RequestURI=/selenium-server/driver/</p>
E <p><i><small><a href="http://jetty.mortbay.org">Powered by Jetty://</a></small></i></p>
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E
E </body>
E </html>
.env/lib/python2.6/site-packages/selenium/selenium.py:214: Exception
----------------------- generated xml file: results.xml ------------------------
=========================== short test summary info ============================
XFAIL test_dev_derby.py::TestDevDerbyPage::()::test_derby_links_visible
No derby winners on production yet
================ 10 passed, 1 xfailed, 1 error in 71.20 seconds ================
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Finished: FAILURE
[java] Nov 14, 2011 3:03:14 PM org.openqa.grid.selenium.GridLauncher main
[java] INFO: Launching a selenium grid server
[java] Nov 14, 2011 3:03:17 PM org.openqa.jetty.http.HttpServer doStart
[java] INFO: Version Jetty/5.1.x
[java] Nov 14, 2011 3:03:17 PM org.openqa.jetty.util.FileResource <clinit>
[java] INFO: Checking Resource aliases
[java] Nov 14, 2011 3:03:18 PM org.openqa.jetty.util.Container start
[java] INFO: Started org.openqa.jetty.jetty.servlet.WebApplicationHandler@a578073
[java] Nov 14, 2011 3:03:18 PM org.openqa.jetty.util.Container start
[java] INFO: Started WebApplicationContext[/,/]
[java] Nov 14, 2011 3:03:18 PM org.openqa.jetty.http.SocketListener start
[java] INFO: Started SocketListener on 0.0.0.0:4444
[java] Nov 14, 2011 3:03:18 PM org.openqa.jetty.util.Container start
[java] INFO: Started org.openqa.jetty.jetty.Server@761eec35
[java] Nov 17, 2011 1:36:12 PM org.openqa.grid.web.servlet.handler.RequestHandler getSession
[java] WARNING: Cannot find session a22e9443040744fb963b7e7397c75778 in the registry.
[java] 13:35:26.045 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:26.045 INFO - creating new remote session
[java] 13:35:26.046 INFO - Allocated session 6fe0e8cfc70e4a7088abad576bd0c4c8 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:26.054 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:26.055 INFO - creating new remote session
[java] 13:35:26.055 INFO - Allocated session 1541ba8daf564366b8cf9b05d1c31500 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:26.083 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:26.083 INFO - creating new remote session
[java] 13:35:26.083 INFO - Allocated session f2156a162e2244be8ae2c36c9ab750af for http://developer-stage9.mozilla.org, launching...
[java] 13:35:26.092 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:26.092 INFO - creating new remote session
[java] 13:35:26.092 INFO - Allocated session 828823e773c04d6694e35037486ff4f1 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:26.095 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:26.095 INFO - creating new remote session
[java] 13:35:26.095 INFO - Allocated session 9599512a76704003aaecc86a27916972 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:26.121 INFO - Preparing Firefox profile...
[java] 13:35:26.203 INFO - Preparing Firefox profile...
[java] 13:35:26.393 INFO - Preparing Firefox profile...
[java] 13:35:26.559 INFO - Preparing Firefox profile...
[java] 13:35:26.839 INFO - Preparing Firefox profile...
[java] 13:35:29.879 INFO - Launching Firefox...
[java] 13:35:29.888 INFO - Launching Firefox...
[java] 13:35:30.632 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@3726d8af at URL: /status)
[java] 13:35:30.632 INFO - Done: /status
[java] 13:35:31.179 INFO - Launching Firefox...
[java] 13:35:31.217 INFO - Launching Firefox...
[java] 13:35:31.238 INFO - Launching Firefox...
[java] 13:35:33.956 INFO - Got result: OK,828823e773c04d6694e35037486ff4f1 on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:33.963 INFO - Command request: setTimeout[180000, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:33.967 INFO - Got result: OK on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:33.974 INFO - Command request: setContext[test_dev_derby.TestDevDerbyPage.test_derby_links_visible, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:34.030 INFO - Got result: OK on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:34.039 INFO - Command request: open[en-US/demos/devderby, True] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:34.103 INFO - Got result: OK,6fe0e8cfc70e4a7088abad576bd0c4c8 on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:34.109 INFO - Command request: setTimeout[180000, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:34.131 INFO - Got result: OK on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:34.140 INFO - Command request: setContext[test_dev_derby.TestDevDerbyPage.test_judge_images_visible, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:34.153 INFO - Got result: OK on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:34.164 INFO - Command request: open[en-US/demos/devderby, True] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:35.579 INFO - Got result: OK,9599512a76704003aaecc86a27916972 on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.586 INFO - Command request: setTimeout[180000, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.605 INFO - Got result: OK on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.618 INFO - Command request: setContext[test_homepage.TestHomepage.test_demo_image, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.634 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@59c0a9a at URL: /status)
[java] 13:35:35.634 INFO - Done: /status
[java] 13:35:35.664 INFO - Got result: OK on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.682 INFO - Got result: OK,1541ba8daf564366b8cf9b05d1c31500 on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.686 INFO - Command request: open[/demos, True] on session 9599512a76704003aaecc86a27916972
[java] 13:35:35.694 INFO - Command request: setTimeout[180000, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.705 INFO - Got result: OK on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.711 INFO - Command request: setContext[test_dev_derby.TestDevDerbyPage.test_are_previous_challenges_present, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.718 INFO - Got result: OK on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.748 INFO - Command request: open[en-US/demos/devderby, True] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:35.768 INFO - Got result: OK,f2156a162e2244be8ae2c36c9ab750af on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:35.775 INFO - Command request: setTimeout[180000, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:35.788 INFO - Got result: OK on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:35.808 INFO - Command request: setContext[test_learn_page.TestLearnPage.test_are_footer_links_visible, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:35.818 INFO - Got result: OK on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:35.826 INFO - Command request: open[en-US/learn, True] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:40.637 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@218d9941 at URL: /status)
[java] 13:35:40.637 INFO - Done: /status
[java] 13:35:42.451 INFO - Got result: OK on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.458 INFO - Command request: waitForPageToLoad[180000, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.467 INFO - Got result: OK on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.480 INFO - Command request: isVisible[css=#nav-derby>ul>li>em, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.487 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.501 INFO - Command request: isElementPresent[css=#nav-derby>ul>li>em, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.508 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.514 INFO - Command request: isVisible[css=#nav-derby>ul:nth-child(1)>li:nth-child(2)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.525 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.531 INFO - Command request: isElementPresent[css=#nav-derby>ul:nth-child(1)>li:nth-child(2)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.540 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.551 INFO - Command request: isVisible[css=#nav-derby>ul:nth-child(1)>li:nth-child(3)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.562 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.567 INFO - Command request: isElementPresent[css=#nav-derby>ul:nth-child(1)>li:nth-child(3)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.580 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.593 INFO - Command request: isVisible[css=#nav-derby>ul:nth-child(1)>li:nth-child(4)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.618 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.624 INFO - Command request: isElementPresent[css=#nav-derby>ul:nth-child(1)>li:nth-child(4)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.648 INFO - Got result: OK on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.650 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.656 INFO - Command request: isVisible[css=#nav-derby>ul:nth-child(1)>li:nth-child(5)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.666 INFO - Command request: waitForPageToLoad[180000, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.672 INFO - Got result: OK on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.677 INFO - Command request: getCssCount[css=.judges>vcard>h3>a>img, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.688 INFO - Got result: OK,0 on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.698 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:42.713 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.727 INFO - Command request: isElementPresent[css=#nav-derby>ul:nth-child(1)>li:nth-child(5)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.748 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.757 INFO - Command request: isVisible[css=#nav-derby>ul:nth-child(1)>li:nth-child(6)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.766 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.774 INFO - Command request: isElementPresent[css=#nav-derby>ul:nth-child(1)>li:nth-child(6)>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.790 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.804 INFO - Command request: isVisible[css=.submit>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.815 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.821 INFO - Command request: isElementPresent[css=.submit>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.831 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.838 INFO - Command request: isVisible[css=.presents>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.848 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.861 INFO - Command request: isElementPresent[css=.presents>a, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.883 INFO - Got result: OK,true on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.898 INFO - Command request: isVisible[css=#prev-winner>h1, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:42.923 INFO - Got result: ERROR: Element css=#prev-winner>h1 not found on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:43.311 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:44.044 INFO - Got result: [base64 encoded PNG] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:44.057 INFO - Got result: OK on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.071 INFO - Command request: waitForPageToLoad[180000, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.077 INFO - Got result: OK on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.087 INFO - Command request: isVisible[css=#legal > img, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.096 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.102 INFO - Command request: isElementPresent[css=#legal > img, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.110 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.119 INFO - Command request: isVisible[css=#footbar > div.wrap > p > a, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.137 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.149 INFO - Command request: isElementPresent[css=#footbar > div.wrap > p > a, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.176 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.184 INFO - Command request: isVisible[css=#legal > p > a:nth-child(1), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.188 INFO - Command request: getHtmlSource[, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:44.211 INFO - Got result: [HTML source] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:44.219 INFO - Command request: testComplete[, ] on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:44.219 INFO - Killing Firefox...
[java] 13:35:44.302 INFO - Got result: OK on session 6fe0e8cfc70e4a7088abad576bd0c4c8
[java] 13:35:44.316 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:44.316 INFO - creating new remote session
[java] 13:35:44.318 INFO - Allocated session e0bcdf0e636447edb8c54422bf9c1e06 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:44.367 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.389 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(1), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.393 INFO - Preparing Firefox profile...
[java] 13:35:44.487 INFO - Got result: OK on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.488 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.498 INFO - Command request: isVisible[css=#legal > p > a:nth-child(2), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.503 INFO - Command request: waitForPageToLoad[180000, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.528 INFO - Got result: OK on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.592 INFO - Command request: getCssCount[css=.ul.previous>li>h3, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.942 INFO - Got result: OK,0 on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.966 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:44.971 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:44.975 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(2), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.000 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.017 INFO - Command request: isVisible[css=#legal > p > a:nth-child(3), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.034 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.041 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(3), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.049 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.057 INFO - Command request: isVisible[css=#legal > p > a:nth-child(4), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.064 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.071 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(4), ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.082 INFO - Got result: OK,true on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.091 INFO - Command request: captureEntirePageScreenshotToString[, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:45.587 INFO - Got result: [base64 encoded PNG] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:45.640 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@5f206335 at URL: /status)
[java] 13:35:45.640 INFO - Done: /status
[java] 13:35:45.732 INFO - Command request: getHtmlSource[, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:45.771 INFO - Got result: [HTML source] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:45.795 INFO - Command request: testComplete[, ] on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:45.795 INFO - Killing Firefox...
[java] 13:35:45.941 INFO - Got result: OK on session 828823e773c04d6694e35037486ff4f1
[java] 13:35:45.947 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:45.947 INFO - creating new remote session
[java] 13:35:45.948 INFO - Allocated session f049e9b5e79f42c29ca978ef5c94b158 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:46.135 INFO - Preparing Firefox profile...
[java] 13:35:46.487 INFO - Got result: [base64 encoded PNG] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:46.604 INFO - Command request: getHtmlSource[, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:46.664 INFO - Got result: [HTML source] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:46.676 INFO - Command request: testComplete[, ] on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:46.677 INFO - Killing Firefox...
[java] 13:35:46.787 INFO - Got result: OK on session f2156a162e2244be8ae2c36c9ab750af
[java] 13:35:46.801 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:46.801 INFO - creating new remote session
[java] 13:35:46.802 INFO - Allocated session 12adb38561cb4d9e8ae459a6470a37d3 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:47.001 INFO - Preparing Firefox profile...
[java] 13:35:47.342 INFO - Launching Firefox...
[java] 13:35:47.603 INFO - Got result: [base64 encoded PNG] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:48.094 INFO - Command request: getHtmlSource[, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:48.129 INFO - Got result: [HTML source] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:48.140 INFO - Command request: testComplete[, ] on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:48.140 INFO - Killing Firefox...
[java] 13:35:48.261 INFO - Got result: OK on session 1541ba8daf564366b8cf9b05d1c31500
[java] 13:35:48.326 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:48.326 INFO - creating new remote session
[java] 13:35:48.328 INFO - Allocated session ea51a5be297d43a5a8c3c30d21221440 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:48.463 INFO - Preparing Firefox profile...
[java] 13:35:49.324 INFO - Launching Firefox...
[java] 13:35:49.893 INFO - Launching Firefox...
[java] 13:35:50.642 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@4c869122 at URL: /status)
[java] 13:35:50.642 INFO - Done: /status
[java] 13:35:51.147 INFO - Launching Firefox...
[java] 13:35:52.423 INFO - Got result: OK,e0bcdf0e636447edb8c54422bf9c1e06 on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:52.434 INFO - Command request: setTimeout[180000, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:52.445 INFO - Got result: OK on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:52.451 INFO - Command request: setContext[test_homepage.TestHomepage.test_header_links, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:52.455 INFO - Got result: OK on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:52.462 INFO - Command request: open[/demos, True] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:35:53.571 INFO - Got result: OK,f049e9b5e79f42c29ca978ef5c94b158 on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.579 INFO - Command request: setTimeout[180000, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.614 INFO - Got result: OK on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.620 INFO - Command request: setContext[test_learn_page.TestLearnPage.test_header_links_visible, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.630 INFO - Got result: OK on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.638 INFO - Command request: open[en-US/learn, True] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:35:53.793 INFO - Got result: OK,12adb38561cb4d9e8ae459a6470a37d3 on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:53.799 INFO - Command request: setTimeout[180000, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:53.811 INFO - Got result: OK on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:53.817 INFO - Command request: setContext[test_homepage.TestHomepage.test_demo_title, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:53.821 INFO - Got result: OK on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:53.831 INFO - Command request: open[/demos, True] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:35:54.017 INFO - Got result: OK on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.023 INFO - Command request: waitForPageToLoad[180000, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.032 INFO - Got result: OK on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.042 INFO - Command request: getCssCount[css=.demo > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.050 INFO - Got result: OK,12 on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.070 INFO - Command request: isVisible[css=.demo:nth-child(1) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.078 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.087 INFO - Command request: isElementPresent[css=.demo:nth-child(1) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.097 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.102 INFO - Command request: isVisible[css=.demo:nth-child(2) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.109 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.115 INFO - Command request: isElementPresent[css=.demo:nth-child(2) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.123 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.128 INFO - Command request: isVisible[css=.demo:nth-child(3) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.137 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.143 INFO - Command request: isElementPresent[css=.demo:nth-child(3) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.148 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.154 INFO - Command request: isVisible[css=.demo:nth-child(4) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.162 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.168 INFO - Command request: isElementPresent[css=.demo:nth-child(4) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.175 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.181 INFO - Command request: isVisible[css=.demo:nth-child(5) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.189 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.198 INFO - Command request: isElementPresent[css=.demo:nth-child(5) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.203 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.209 INFO - Command request: isVisible[css=.demo:nth-child(6) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.216 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.222 INFO - Command request: isElementPresent[css=.demo:nth-child(6) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.227 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.234 INFO - Command request: isVisible[css=.demo:nth-child(7) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.241 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.248 INFO - Command request: isElementPresent[css=.demo:nth-child(7) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.256 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.262 INFO - Command request: isVisible[css=.demo:nth-child(8) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.272 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.278 INFO - Command request: isElementPresent[css=.demo:nth-child(8) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.287 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.292 INFO - Command request: isVisible[css=.demo:nth-child(9) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.304 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.309 INFO - Command request: isElementPresent[css=.demo:nth-child(9) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.314 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.320 INFO - Command request: isVisible[css=.demo:nth-child(10) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.331 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.337 INFO - Command request: isElementPresent[css=.demo:nth-child(10) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.342 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.348 INFO - Command request: isVisible[css=.demo:nth-child(11) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.361 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.366 INFO - Command request: isElementPresent[css=.demo:nth-child(11) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.372 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.378 INFO - Command request: isVisible[css=.demo:nth-child(12) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.387 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.395 INFO - Command request: isElementPresent[css=.demo:nth-child(12) > .demo-title > a > img, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.401 INFO - Got result: OK,true on session 9599512a76704003aaecc86a27916972
[java] 13:35:54.412 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.077 INFO - Got result: OK,ea51a5be297d43a5a8c3c30d21221440 on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.091 INFO - Command request: setTimeout[180000, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.105 INFO - Got result: OK on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.111 INFO - Command request: setContext[test_dev_derby.TestDevDerbyPage.test_are_footer_links_visible, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.114 INFO - Got result: OK on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.124 INFO - Command request: open[en-US/demos/devderby, True] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:35:55.624 INFO - Got result: [base64 encoded PNG] on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.646 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@118669c1 at URL: /status)
[java] 13:35:55.646 INFO - Done: /status
[java] 13:35:55.775 INFO - Command request: getHtmlSource[, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.822 INFO - Got result: [HTML source] on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.830 INFO - Command request: testComplete[, ] on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.830 INFO - Killing Firefox...
[java] 13:35:55.904 INFO - Got result: OK on session 9599512a76704003aaecc86a27916972
[java] 13:35:55.914 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:35:55.915 INFO - creating new remote session
[java] 13:35:55.916 INFO - Allocated session 1107d4514eff4d7f9136a93def8a6844 for http://developer-stage9.mozilla.org, launching...
[java] 13:35:55.984 INFO - Preparing Firefox profile...
[java] 13:35:58.863 INFO - Launching Firefox...
[java] 13:36:00.648 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@78d53fb8 at URL: /status)
[java] 13:36:00.648 INFO - Done: /status
[java] 13:36:01.286 INFO - Got result: OK on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.294 INFO - Command request: waitForPageToLoad[180000, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.322 INFO - Got result: OK on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.336 INFO - Command request: isVisible[css=.toggle, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.353 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.360 INFO - Command request: isElementPresent[css=.toggle, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.386 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.394 INFO - Command request: isVisible[css=.docs, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.407 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.413 INFO - Command request: isElementPresent[css=.docs, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.422 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.428 INFO - Command request: isVisible[css=.demos, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.498 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.508 INFO - Got result: OK on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.517 INFO - Command request: waitForPageToLoad[180000, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.518 INFO - Command request: isElementPresent[css=.demos, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.524 INFO - Got result: OK on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.530 INFO - Command request: isVisible[css=.toggle, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.532 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.541 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.544 INFO - Command request: isVisible[css=.learning, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.547 INFO - Command request: isElementPresent[css=.toggle, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.555 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.561 INFO - Command request: isVisible[css=.docs, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.570 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.578 INFO - Command request: isElementPresent[css=.learning, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.581 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.587 INFO - Command request: isElementPresent[css=.docs, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.588 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.596 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.600 INFO - Command request: isVisible[css=.community, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.612 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.616 INFO - Command request: isVisible[css=.demos, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.633 INFO - Command request: isElementPresent[css=.community, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.640 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.645 INFO - Command request: isElementPresent[css=.demos, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.655 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.693 INFO - Command request: isVisible[css=.learning, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.702 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.715 INFO - Command request: isElementPresent[css=.learning, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.724 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.732 INFO - Command request: isVisible[css=.community, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.743 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.749 INFO - Command request: isElementPresent[css=.community, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.763 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.763 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.768 INFO - Command request: isVisible[css=.wrap > p:nth-child(1) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.770 INFO - Command request: isVisible[css=.wrap > p:nth-child(1) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.783 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.790 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.797 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(1) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.812 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(1) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.832 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.838 INFO - Command request: isVisible[css=.wrap > p:nth-child(2) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.840 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.847 INFO - Command request: isVisible[css=.wrap > p:nth-child(2) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.852 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.863 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(2) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.874 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.881 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.884 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(2) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.890 INFO - Command request: isVisible[css=#q, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.900 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.901 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.908 INFO - Command request: isVisible[css=#q, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.918 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.922 INFO - Command request: isElementPresent[css=#q, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:01.929 INFO - Command request: isElementPresent[css=#q, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.935 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.942 INFO - Command request: isVisible[css=#demos-head > h1, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.951 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.959 INFO - Command request: isElementPresent[css=#demos-head > h1, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.988 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:01.999 INFO - Command request: isVisible[css=.learning, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.009 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.021 INFO - Command request: isElementPresent[css=.learning, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.031 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.045 INFO - Command request: isVisible[css=.community, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.058 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.065 INFO - Command request: isElementPresent[css=.community, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.077 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.083 INFO - Command request: isVisible[css=.wrap > p:nth-child(1) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.094 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.102 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(1) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.112 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.120 INFO - Command request: isVisible[css=.wrap > p:nth-child(2) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.132 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.139 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(2) > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.148 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.154 INFO - Command request: isVisible[css=#q, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.162 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.169 INFO - Command request: isElementPresent[css=#q, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.177 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.183 INFO - Command request: isVisible[css=#demos-head > h1, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.189 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.198 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.199 INFO - Command request: isElementPresent[css=#demos-head > h1, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.205 INFO - Command request: isVisible[css=.learning, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.206 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.213 INFO - Command request: isVisible[css=.learnmore > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.227 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.233 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.236 INFO - Command request: isElementPresent[css=.learnmore > a, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.239 INFO - Command request: isElementPresent[css=.learning, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.246 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.249 INFO - Got result: OK,true on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.255 INFO - Command request: isVisible[css=.community, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.264 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.269 INFO - Command request: captureEntirePageScreenshotToString[, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:02.272 INFO - Command request: isElementPresent[css=.community, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.281 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.290 INFO - Command request: isVisible[css=.wrap > p:nth-child(1) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.299 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.305 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(1) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.313 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.325 INFO - Command request: isVisible[css=.wrap > p:nth-child(2) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.335 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.344 INFO - Command request: isElementPresent[css=.wrap > p:nth-child(2) > a, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.357 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.367 INFO - Command request: isVisible[css=#q, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.375 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.386 INFO - Command request: isElementPresent[css=#q, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.391 INFO - Got result: OK,true on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.404 INFO - Command request: captureEntirePageScreenshotToString[, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:02.542 INFO - Got result: OK on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.547 INFO - Command request: waitForPageToLoad[180000, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.559 INFO - Got result: OK on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.564 INFO - Command request: getCssCount[css=.demo > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.572 INFO - Got result: OK,12 on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.580 INFO - Command request: isVisible[css=.demo:nth-child(1) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.591 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.597 INFO - Command request: isElementPresent[css=.demo:nth-child(1) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.608 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.613 INFO - Command request: isVisible[css=.demo:nth-child(2) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.620 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.630 INFO - Command request: isElementPresent[css=.demo:nth-child(2) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.683 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.700 INFO - Command request: isVisible[css=.demo:nth-child(3) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.711 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.728 INFO - Command request: isElementPresent[css=.demo:nth-child(3) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.733 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.740 INFO - Command request: isVisible[css=.demo:nth-child(4) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.753 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.772 INFO - Command request: isElementPresent[css=.demo:nth-child(4) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.783 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.814 INFO - Command request: isVisible[css=.demo:nth-child(5) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.821 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.828 INFO - Command request: isElementPresent[css=.demo:nth-child(5) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.836 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.846 INFO - Command request: isVisible[css=.demo:nth-child(6) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.862 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.868 INFO - Command request: isElementPresent[css=.demo:nth-child(6) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.874 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.888 INFO - Command request: isVisible[css=.demo:nth-child(7) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.895 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.916 INFO - Command request: isElementPresent[css=.demo:nth-child(7) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.924 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.937 INFO - Command request: isVisible[css=.demo:nth-child(8) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.953 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.970 INFO - Command request: isElementPresent[css=.demo:nth-child(8) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.980 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.987 INFO - Command request: isVisible[css=.demo:nth-child(9) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:02.999 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.006 INFO - Command request: isElementPresent[css=.demo:nth-child(9) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.021 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.028 INFO - Command request: isVisible[css=.demo:nth-child(10) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.040 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.049 INFO - Command request: isElementPresent[css=.demo:nth-child(10) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.058 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.067 INFO - Command request: isVisible[css=.demo:nth-child(11) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.078 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.085 INFO - Command request: isElementPresent[css=.demo:nth-child(11) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.094 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.101 INFO - Command request: isVisible[css=.demo:nth-child(12) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.121 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.127 INFO - Command request: isElementPresent[css=.demo:nth-child(12) > .demo-title > a, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.148 INFO - Got result: OK,true on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.173 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:03.399 INFO - Got result: [base64 encoded PNG] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:03.431 INFO - Got result: OK,1107d4514eff4d7f9136a93def8a6844 on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.436 INFO - Command request: setTimeout[180000, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.440 INFO - Command request: getHtmlSource[, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:03.453 INFO - Got result: OK on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.458 INFO - Got result: [HTML source] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:03.460 INFO - Command request: setContext[test_homepage.TestHomepage.test_footer_links, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.473 INFO - Got result: OK on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.479 INFO - Command request: testComplete[, ] on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:03.479 INFO - Killing Firefox...
[java] 13:36:03.480 INFO - Command request: open[/demos, True] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:03.532 INFO - Got result: OK on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.540 INFO - Command request: waitForPageToLoad[180000, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.568 INFO - Got result: OK on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.573 INFO - Command request: isVisible[css=#legal > img, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.592 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.597 INFO - Command request: isElementPresent[css=#legal > img, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.603 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.608 INFO - Command request: isVisible[css=#footbar > div.wrap > p > a, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.636 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.643 INFO - Command request: isElementPresent[css=#footbar > div.wrap > p > a, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.672 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.675 INFO - Got result: OK on session f049e9b5e79f42c29ca978ef5c94b158
[java] 13:36:03.679 INFO - Command request: isVisible[css=#legal > p > a:nth-child(1), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.702 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:36:03.702 INFO - creating new remote session
[java] 13:36:03.703 INFO - Allocated session 4bf56910a3174405a5d456dadfd6ced6 for http://developer-stage9.mozilla.org, launching...
[java] 13:36:03.860 INFO - Preparing Firefox profile...
[java] 13:36:03.952 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.965 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(1), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.971 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:03.983 INFO - Command request: isVisible[css=#legal > p > a:nth-child(2), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.440 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.452 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(2), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.466 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.472 INFO - Command request: isVisible[css=#legal > p > a:nth-child(3), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.483 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.492 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(3), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.500 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.505 INFO - Command request: isVisible[css=#legal > p > a:nth-child(4), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.565 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.575 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(4), ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.584 INFO - Got result: OK,true on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:04.604 INFO - Command request: captureEntirePageScreenshotToString[, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:05.030 INFO - Got result: [base64 encoded PNG] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:05.260 INFO - Command request: getHtmlSource[, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:05.325 INFO - Got result: [HTML source] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:05.359 INFO - Command request: testComplete[, ] on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:05.359 INFO - Killing Firefox...
[java] 13:36:05.540 INFO - Got result: OK on session e0bcdf0e636447edb8c54422bf9c1e06
[java] 13:36:05.653 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@54906d5b at URL: /status)
[java] 13:36:05.653 INFO - Done: /status
[java] 13:36:06.142 INFO - Got result: [base64 encoded PNG] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:06.277 INFO - Got result: [base64 encoded PNG] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:06.317 INFO - Command request: getHtmlSource[, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:06.347 INFO - Got result: [HTML source] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:06.355 INFO - Command request: testComplete[, ] on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:06.355 INFO - Killing Firefox...
[java] 13:36:06.401 INFO - Command request: getHtmlSource[, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:06.433 INFO - Got result: [HTML source] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:06.439 INFO - Command request: testComplete[, ] on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:06.439 INFO - Killing Firefox...
[java] 13:36:06.545 INFO - Got result: OK on session 12adb38561cb4d9e8ae459a6470a37d3
[java] 13:36:06.674 INFO - Launching Firefox...
[java] 13:36:06.808 INFO - Got result: OK on session ea51a5be297d43a5a8c3c30d21221440
[java] 13:36:06.865 INFO - Command request: getNewBrowserSession[*firefox /Applications/Firefox 7.app/Contents/MacOS/firefox-bin, http://developer-stage9.mozilla.org, ] on session null
[java] 13:36:06.865 INFO - creating new remote session
[java] 13:36:06.866 INFO - Allocated session a22e9443040744fb963b7e7397c75778 for http://developer-stage9.mozilla.org, launching...
[java] 13:36:07.064 INFO - Preparing Firefox profile...
[java] 13:36:09.643 INFO - Launching Firefox...
[java] 13:36:10.351 INFO - Got result: OK,4bf56910a3174405a5d456dadfd6ced6 on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.360 INFO - Command request: setTimeout[180000, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.422 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.428 INFO - Command request: setContext[test_login.TestLogin.test_login, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.438 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.447 INFO - Command request: open[/en-US/profiles/testaccount/, True] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:10.656 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@6396bd9e at URL: /status)
[java] 13:36:10.656 INFO - Done: /status
[java] 13:36:11.270 INFO - Got result: OK on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.276 INFO - Command request: waitForPageToLoad[180000, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.284 INFO - Got result: OK on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.289 INFO - Command request: isVisible[css=#legal > img, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.296 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.302 INFO - Command request: isElementPresent[css=#legal > img, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.307 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.312 INFO - Command request: isVisible[css=.feed > a, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.319 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.326 INFO - Command request: isElementPresent[css=.feed > a, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.331 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.336 INFO - Command request: isVisible[css=#footbar > div.wrap > p > a, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.343 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.349 INFO - Command request: isElementPresent[css=#footbar > div.wrap > p > a, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.354 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.360 INFO - Command request: isVisible[css=#legal > p > a:nth-child(1), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.366 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.373 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(1), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.378 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.387 INFO - Command request: isVisible[css=#legal > p > a:nth-child(2), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.396 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.402 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(2), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.411 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.421 INFO - Command request: isVisible[css=#legal > p > a:nth-child(3), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.428 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.433 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(3), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.438 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.444 INFO - Command request: isVisible[css=#legal > p > a:nth-child(4), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.451 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.457 INFO - Command request: isElementPresent[css=#legal > p > a:nth-child(4), ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.462 INFO - Got result: OK,true on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:11.471 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.444 INFO - Got result: [base64 encoded PNG] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.599 INFO - Got result: OK,a22e9443040744fb963b7e7397c75778 on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.620 INFO - Command request: getHtmlSource[, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.648 INFO - Got result: [HTML source] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.654 INFO - Command request: captureEntirePageScreenshotToString[, ] on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.656 INFO - Command request: testComplete[, ] on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.656 INFO - Killing Firefox...
[java] 13:36:12.667 INFO - Got result: [base64 encoded PNG] on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.688 INFO - Command request: getHtmlSource[, ] on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.723 INFO - Got result: [HTML source] on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.839 INFO - Got result: OK on session 1107d4514eff4d7f9136a93def8a6844
[java] 13:36:12.842 INFO - Command request: testComplete[, ] on session a22e9443040744fb963b7e7397c75778
[java] 13:36:12.842 INFO - Killing Firefox...
[java] 13:36:12.992 INFO - Got result: OK on session a22e9443040744fb963b7e7397c75778
[java] 13:36:15.658 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@2c0a48cd at URL: /status)
[java] 13:36:15.658 INFO - Done: /status
[java] 13:36:16.556 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:16.561 INFO - Command request: waitForPageToLoad[180000, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:16.565 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:16.570 INFO - Command request: open[/Special:UserLogin?returntotitle=%2Fen-US%2Fprofiles%2Ftestaccount%2F, True] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:20.660 INFO - Executing: org.openqa.selenium.remote.server.handler.Status@343d8159 at URL: /status)
[java] 13:36:20.660 INFO - Done: /status
[java] 13:36:22.697 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.703 INFO - Command request: waitForPageToLoad[180000, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.714 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.720 INFO - Command request: type[css=#text-username, testaccount] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.727 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.733 INFO - Command request: type[css=#password-password, theplanet] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.739 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.745 INFO - Command request: click[css=.input-button, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.755 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:22.762 INFO - Command request: waitForPageToLoad[180000, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.241 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.246 INFO - Command request: isVisible[css=.nickname, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.254 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.260 INFO - Command request: isElementPresent[css=.nickname, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.267 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.274 INFO - Command request: isVisible[css=.irc, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.281 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.287 INFO - Command request: isElementPresent[css=.irc, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.292 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.297 INFO - Command request: isVisible[css=.org, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.304 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.309 INFO - Command request: isElementPresent[css=.org, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.314 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.319 INFO - Command request: isVisible[css=.title, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.326 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.332 INFO - Command request: isElementPresent[css=.title, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.336 INFO - Got result: OK,true on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.343 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.540 INFO - Got result: [base64 encoded PNG] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.555 INFO - Command request: getHtmlSource[, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.565 INFO - Got result: [HTML source] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.571 INFO - Command request: testComplete[, ] on session 4bf56910a3174405a5d456dadfd6ced6
[java] 13:36:25.571 INFO - Killing Firefox...
[java] 13:36:25.624 INFO - Got result: OK on session 4bf56910a3174405a5d456dadfd6ced6
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment