923559 - Intermittent test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: timed out

Status: RESOLVED WONTFIX

(Firefox OS Graveyard :: Gaia::UI Tests, defect)

Description

[Phil Ringnalda (:philor)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=28718826&tree=B2g-Inbound
b2g_ubuntu64_vm b2g-inbound opt test gaia-ui-test on 2013-10-03 09:11:25 PDT for push b03199969d6b
slave: tst-linux64-ec2-132

09:27:09     INFO -  ERROR
09:27:09     INFO -  ======================================================================
09:27:09     INFO -  ERROR: test_sort_contacts (test_sort_contacts.TestContacts)
09:27:09     INFO -  Test sorting of contacts
09:27:09     INFO -  ----------------------------------------------------------------------
09:27:09     INFO -  Traceback (most recent call last):
09:27:09     INFO -    File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette_test.py", line 123, in run
09:27:09     INFO -      self.setUp()
09:27:09     INFO -    File "/builds/slave/test/gaia/tests/python/gaia-ui-tests/gaiatest/tests/functional/contacts/test_sort_contacts.py", line 21, in setUp
09:27:09     INFO -      self.data_layer.insert_contact(contact)
09:27:09     INFO -    File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/gaiatest/gaia_test.py", line 159, in insert_contact
09:27:09     INFO -      result = self.marionette.execute_async_script('return GaiaDataLayer.insertContact(%s);' % json.dumps(contact), special_powers=True)
09:27:09     INFO -    File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette.py", line 1068, in execute_async_script
09:27:09     INFO -      filename=os.path.basename(frame[0]))
09:27:09     INFO -    File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette.py", line 572, in _send_message
09:27:09     INFO -      self._handle_error(response)
09:27:09     INFO -    File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette.py", line 621, in _handle_error
09:27:09    ERROR -      raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
09:27:09    ERROR -  TEST-UNEXPECTED-FAIL | test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: timed out
09:27:09     INFO -  ----------------------------------------------------------------------
09:27:09     INFO -  Ran 1 test in 69.873s
09:27:09  WARNING -  FAILED (errors=1)

Comment 1

[Phil Ringnalda (:philor)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=28712541&tree=B2g-Inbound

Comment 2

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=28715119&tree=B2g-Inbound
b2g_ubuntu64_vm b2g-inbound opt test gaia-ui-test on 2013-10-03 07:14:48
revision: ad85ab887c87
slave: tst-linux64-ec2-407

raise ScriptTimeoutException(message=message, status=status, stacktrace=stacktrace)
TEST-UNEXPECTED-FAIL | test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: timed out
Return code: 2560
Marionette exited with return code 2560: harness failures
# TBPL FAILURE #

Comment 3

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=5773689
repository: mozilla-inbound
start_time: 2015-01-22T07:57:18
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-1222
buildname: b2g_ubuntu64_vm mozilla-inbound opt test gaia-ui-test-functional-3
revision: 068f4ad023db

TEST-UNEXPECTED-ERROR | test_calendar_flick_through_months.py TestCalendar.test_calendar_flick_through_months | TimeoutException: TimeoutException: Timed out after 10.1 seconds
ScriptTimeoutException: ScriptTimeoutException: timed out
TEST-UNEXPECTED-ERROR | test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: ScriptTimeoutException: timed out
Return code: 10

Comment 4

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=6595988
repository: mozilla-inbound
start_time: 2015-02-13T12:01:25
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-369
buildname: b2g_ubuntu64_vm mozilla-inbound opt test gaia-ui-test-functional-3
revision: 4ca1304f0b42

TEST-UNEXPECTED-ERROR | test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: ScriptTimeoutException: timed out
Return code: 10

Comment 5

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2151993
repository: b2g-inbound
start_time: 2015-06-12T10:46:40
who: philringnalda[at]gmail[dot]com
machine: unknown
revision: 4802621db720

TEST-UNEXPECTED-ERROR | test_sort_contacts.py TestContacts.test_sort_contacts | ScriptTimeoutException: ScriptTimeoutException: timed out
Return code: 10

Comment 6

[Martijn Wargers (dead)]

Dave, I'm trying to find out where things are going wrong with the test_sort_contacts.py script at
https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2151993

My theory is that it's somehow failing in the gaia_data_layer.js insertContact function, but it hasn't reached req.onsuccess or req.onerror there.
http://mxr.mozilla.org/gaia/source/tests/atoms/gaia_data_layer.js#88

So I would expect to find a js exception happening there.

I'm looking at 
http://docs.taskcluster.net/tools/task-inspector/#sR9P8d7lScOTSWXTQn5F8A/0
https://s3-us-west-2.amazonaws.com/taskcluster-public-artifacts/sR9P8d7lScOTSWXTQn5F8A/0/public/logs/live_backing.log

The gecko log doesn't seem to show any js exception in that regard: https://s3-us-west-2.amazonaws.com/taskcluster-public-artifacts/sR9P8d7lScOTSWXTQn5F8A/0/public/test_info//gecko-1434131789.log
It's only mentioning something about the test_power_button_long_press.py test, but that's not at all where the failure happened.
test_power_button_long_press.py is also the last test in that run.
Is taskcluster by any change only storing the last gecko.log run or something?

Comment 7

[Dave Hunt [:davehunt] [he/him] ⌚BST]

(In reply to Martijn Wargers [:mwargers] (QA) from comment #6)
> Dave, I'm trying to find out where things are going wrong with the
> test_sort_contacts.py script at
> https://treeherder.mozilla.org/logviewer.html#?repo=b2g-
> inbound&job_id=2151993
> 
> My theory is that it's somehow failing in the gaia_data_layer.js
> insertContact function, but it hasn't reached req.onsuccess or req.onerror
> there.
> http://mxr.mozilla.org/gaia/source/tests/atoms/gaia_data_layer.js#88

That sounds like a plausible explanation.

> So I would expect to find a js exception happening there.

That's possible (even likely) but there's also the possibility that we've just had nothing and the script has timed out. Maybe look to see if there are any other listeners we should be using as an indication that the script is finished.

> Is taskcluster by any change only storing the last gecko.log run or
> something?

Yes, this is a very real possibility. See bug 1174766.

Comment 8

[BMO Automation]

Firefox OS is not being worked on