1131290 - Intermittent run-by-dir test_0162_notify_unsupported.xul | Test timed out. Maximum time allowed is 240 seconds | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = unsupported

Status: RESOLVED FIXED

(Toolkit :: Application Update, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

12:41:57 INFO - 3400 INFO TEST-START | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul
12:41:58 INFO - ++DOMWINDOW == 131 (0x7f5754082000) [pid = 9897] [serial = 661] [outer = 0x7f5757047c00]
12:41:58 INFO - 12:41:58:080 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js | [runTestDefault : 1258] entering
12:41:58 INFO - *** AUS:SVC UpdateManager:_loadXMLFileIntoArray: XML file does not exist
12:41:58 INFO - *** AUS:SVC UpdateManager:_loadXMLFileIntoArray: XML file does not exist
12:41:58 INFO - 12:41:58:369 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js | [setupAddons : 2097] entering
12:41:58 INFO - 12:41:58:435 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | [runTest : 27] entering
12:41:58 INFO - 12:41:58:438 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js | [setUpdateURLOverride : 756] setting app.update.url.override to http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC readStringFromFile - file doesn't exist: /builds/slave/test/build/application/firefox/updates/0/update.status
12:41:58 INFO - *** AUS:SVC readStatusFile - status: null, path: /builds/slave/test/build/application/firefox/updates/0/update.status
12:41:58 INFO - *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC Checker: checkForUpdates, force: false
12:41:58 INFO - *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC recordInHealthReport - updateCheckStart - 0
12:41:58 INFO - *** AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC readStringFromFile - file doesn't exist: /builds/slave/test/build/application/firefox/updates/0/update.status
12:41:58 INFO - *** AUS:SVC readStatusFile - status: null, path: /builds/slave/test/build/application/firefox/updates/0/update.status
12:41:58 INFO - *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC Checker: checkForUpdates, force: false
12:41:58 INFO - *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC recordInHealthReport - updateCheckStart - 0
12:41:58 INFO - *** AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/chrome/toolkit/mozapps/update/tests/chrome/update.sjs?unsupported=1
12:41:58 INFO - *** AUS:SVC Checker:onLoad - request completed downloading document
12:41:58 INFO - *** AUS:SVC Checker:onLoad - there was a problem checking for updates. Exception: TypeError: this._request.responseXML is null
12:41:58 INFO - *** AUS:SVC Checker:onLoad - request.status: 200
12:41:58 INFO - *** AUS:SVC getStatusTextFromCode - transfer error: Update XML file malformed (200), code: 200
12:41:58 INFO - *** AUS:SVC recordInHealthReport - updateCheckFailed - 1200
12:41:58 INFO - *** AUS:SVC UpdateService:onError - error during background update. error code: 1200, status text: Update XML file malformed (200)
12:41:58 INFO - *** AUS:SVC Checker:onLoad - request completed downloading document
12:41:58 INFO - *** AUS:SVC Checker:onLoad - there was a problem checking for updates. Exception: TypeError: this._request is null
12:41:58 INFO - *** AUS:SVC Checker:onLoad - request.status: 200
12:41:58 INFO - *** AUS:SVC getStatusTextFromCode - transfer error: Update XML file malformed (200), code: 200
12:41:58 INFO - *** AUS:SVC recordInHealthReport - updateCheckFailed - 1200
12:41:58 INFO - JavaScript error: resource://gre/components/nsUpdateService.js, line 3183: TypeError: this._callback is null 
12:46:00 INFO - 3401 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Test timed out. Maximum time allowed is 240 seconds - expected PASS
12:46:00 INFO - 12:45:58:442 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js | [finishTestDefault : 1314] entering
12:46:00 INFO - 12:45:58:442 | TEST-INFO | chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js | [verifyTestsRan : 1829] entering
12:46:00 INFO - JavaScript strict warning: chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js, line 1843: ReferenceError: reference to undefined property test.ranTest
12:46:00 INFO - 3402 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = unsupported - expected PASS
12:46:00 INFO - 3403 INFO MEMORY STAT vsize after test: 955535360
12:46:00 INFO - 3404 INFO MEMORY STAT residentFast after test: 205402112
12:46:00 INFO - 3405 INFO MEMORY STAT heapAllocated after test: 54003960
12:46:00 INFO - 3406 INFO TEST-OK | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | took 241170ms

Comment 1

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=6425504
repository: mozilla-inbound
start_time: 2015-02-09T11:40:47
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-175
buildname: Ubuntu VM 12.04 x64 mozilla-inbound debug test mochitest-other
revision: 8d1bb05dffa7

3401 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Test timed out. Maximum time allowed is 240 seconds - expected PASS
3402 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = unsupported - expected PASS

Comment 2

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=6446190
repository: mozilla-inbound
start_time: 2015-02-09T19:49:11
who: philringnalda[at]gmail[dot]com
machine: tst-linux32-spot-444
buildname: Ubuntu VM 12.04 mozilla-inbound debug test mochitest-other
revision: 01fb7f94f61d

3401 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Test timed out. Maximum time allowed is 240 seconds - expected PASS
3402 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = unsupported - expected PASS

Comment 3

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=1952195
repository: fx-team
start_time: 2015-02-10T05:51:12
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-1059
buildname: Ubuntu VM 12.04 fx-team debug test mochitest-other
revision: 6ad00b017a2b

3408 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Test timed out. Maximum time allowed is 240 seconds - expected PASS
3409 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0162_notify_unsupported.xul | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = unsupported - expected PASS

Comment 4

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=1329076
repository: b2g-inbound
start_time: 2015-02-10T10:17:43
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux32-spot-792
buildname: Ubuntu VM 12.04 b2g-inbound debug test mochitest-other
revision: a0a2cc23a31c

3395 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0142_notify_invalidCertAttrs_hasUpdate.xul | Test timed out. Maximum time allowed is 240 seconds - expected PASS
3396 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0142_notify_invalidCertAttrs_hasUpdate.xul | Checking if TESTS[0] test was performed... callback function name = defaultCallback, pageid = errorextra - expected PASS
3397 INFO TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/chrome/test_0142_notify_invalidCertAttrs_hasUpdate.xul | uncaught exception - NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getIntPref] at chrome://mochitests/content/chrome/toolkit/mozapps/update/tests/chrome/utils.js:2411
3402 INFO TEST-UNEXPECTED-ERROR | toolkit/mozapps/update/tests/chrome/test_0142_notify_invalidCertAttrs_hasUpdate.xul | called finish() multiple times

Comment 5

[Robert Strong (they/them - no direct email)]

Looking at the log I am fairly certain this will be fixed by bug 1134334.

Comment 6

[Robert Strong (they/them - no direct email)]

Should be fixed by the patch in bug 1134334

Comment 7

[Robert Strong (they/them - no direct email)]

Fixed by the patch in bug 1134334