1092460 - Intermittent browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Status: RESOLVED WORKSFORME

(Toolkit :: Add-ons Manager, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

15:17:23     INFO -  305 INFO TEST-START | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js
15:17:23     INFO -  1414793843485	addons.repository	DEBUG	No addons.json found.
15:17:23     INFO -  1414793843487	DeferredSave.addons.json	DEBUG	Save changes
15:17:23     INFO -  1414793843490	DeferredSave.addons.json	DEBUG	Starting timer
15:17:23     INFO -  1414793843523	addons.repository	DEBUG	Repopulate add-on cache with ["[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "{972ce4c6-7e08-4474-a285-3208198ce6fd}", "gmp-gmpopenh264", "{84e372b2-f1a3-032a-0001-b725ee38d1ed}", "{fcdc99b5-45d4-daeb-9239-0a41e6c9b7ce}", "{f2d261dc-c5c4-ca3c-ae02-ccb3ff227c7f}", "{24f3e033-1a7c-ae8b-3fc7-4ac494c18e91}", "{81483a79-4662-220f-c0b3-08db1c26b031}", "{a4254731-ee3a-a1a3-c7e3-22006ca21b3c}", "{e2c52c1c-5ee1-cc23-15fa-35945fd58806}", "{77a62e7b-7c5c-cbe4-bdc6-c8085a32dfdb}"]
15:17:23     INFO -  1414793843556	addons.repository	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/repositoryGetWithPerformanceURL
15:17:23     INFO -  1414793843825	DeferredSave.addons.json	DEBUG	Starting write
15:17:23     INFO -  306 INFO checking window state
15:17:23     INFO -  307 INFO Entering test amo_ping_timeout
15:17:23     INFO -  308 INFO Opened XHR for GET http://127.0.0.1:8888/extensions-dummy/repositoryGetWithPerformanceURL
15:17:23     INFO -  309 INFO Send XHR for GET http://127.0.0.1:8888/extensions-dummy/repositoryGetWithPerformanceURL handlers: [object Map Iterator]
15:17:23     INFO -  310 INFO Console message: 1414793843485	addons.repository	DEBUG	No addons.json found.
15:17:23     INFO -  311 INFO Console message: 1414793843487	DeferredSave.addons.json	DEBUG	Save changes
15:17:23     INFO -  312 INFO Console message: 1414793843490	DeferredSave.addons.json	DEBUG	Starting timer
15:17:23     INFO -  313 INFO Console message: 1414793843523	addons.repository	DEBUG	Repopulate add-on cache with ["[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "{972ce4c6-7e08-4474-a285-3208198ce6fd}", "gmp-gmpopenh264", "{84e372b2-f1a3-032a-0001-b725ee38d1ed}", "{fcdc99b5-45d4-daeb-9239-0a41e6c9b7ce}", "{f2d261dc-c5c4-ca3c-ae02-ccb3ff227c7f}", "{24f3e033-1a7c-ae8b-3fc7-4ac494c18e91}", "{81483a79-4662-220f-c0b3-08db1c26b031}", "{a4254731-ee3a-a1a3-c7e3-22006ca21b3c}", "{e2c52c1c-5ee1-cc23-15fa-35945fd58806}", "{77a62e7b-7c5c-cbe4-bdc6-c8085a32dfdb}"]
15:17:23     INFO -  314 INFO Console message: 1414793843556	addons.repository	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/repositoryGetWithPerformanceURL
15:17:23     INFO -  315 INFO Compatibility dialog opened
15:17:23     INFO -  316 INFO Page versioninfo shown
15:17:23     INFO -  317 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000
15:17:23     INFO -  Stack trace:
15:17:23     INFO -      chrome://mochikit/content/browser-test.js:test_is:828
15:17:23     INFO -      chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js:amo_ping_timeout:105
15:17:23     INFO -      self-hosted:next:883
15:17:23     INFO -      Tester_execTest@chrome://mochikit/content/browser-test.js:645:9
15:17:23     INFO -      Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:571:7
15:17:23     INFO -      SimpleTest.waitForFocus/maybeRunTests/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:683:49
15:17:23     INFO -  1414793843868	addons.repository	WARN	Search failed when repopulating cache
15:17:23     INFO -  1414793843877	addons.repository	DEBUG	Repopulate add-on cache with ["[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "[e-mail]", "{972ce4c6-7e08-4474-a285-3208198ce6fd}", "gmp-gmpopenh264", "{84e372b2-f1a3-032a-0001-b725ee38d1ed}", "{fcdc99b5-45d4-daeb-9239-0a41e6c9b7ce}", "{f2d261dc-c5c4-ca3c-ae02-ccb3ff227c7f}", "{24f3e033-1a7c-ae8b-3fc7-4ac494c18e91}", "{81483a79-4662-220f-c0b3-08db1c26b031}", "{a4254731-ee3a-a1a3-c7e3-22006ca21b3c}", "{e2c52c1c-5ee1-cc23-15fa-35945fd58806}", "{77a62e7b-7c5c-cbe4-bdc6-c8085a32dfdb}"]
15:17:23     INFO -  1414793843910	addons.xpi	DEBUG	updateAddonRepositoryData found 6 visible add-ons
15:17:23     INFO -  1414793843926	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843932	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843939	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843945	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843950	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843956	addons.update-checker	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL
15:17:23     INFO -  1414793843963	addons.manager	DEBUG	onUpdateFinished for gmp-gmpopenh264
15:17:23     INFO -  1414793843970	addons.manager	DEBUG	onUpdateFinished for {84e372b2-f1a3-032a-0001-b725ee38d1ed}
15:17:23     INFO -  1414793843972	addons.manager	DEBUG	onUpdateFinished for {fcdc99b5-45d4-daeb-9239-0a41e6c9b7ce}
15:17:23     INFO -  1414793843973	addons.manager	DEBUG	onUpdateFinished for {f2d261dc-c5c4-ca3c-ae02-ccb3ff227c7f}
15:17:23     INFO -  1414793843975	addons.manager	DEBUG	onUpdateFinished for {24f3e033-1a7c-ae8b-3fc7-4ac494c18e91}
15:17:23     INFO -  1414793843977	addons.manager	DEBUG	onUpdateFinished for {81483a79-4662-220f-c0b3-08db1c26b031}
15:17:23     INFO -  1414793843978	addons.manager	DEBUG	onUpdateFinished for {a4254731-ee3a-a1a3-c7e3-22006ca21b3c}
15:17:23     INFO -  1414793843980	addons.manager	DEBUG	onUpdateFinished for {e2c52c1c-5ee1-cc23-15fa-35945fd58806}
15:17:23     INFO -  1414793843981	addons.manager	DEBUG	onUpdateFinished for {77a62e7b-7c5c-cbe4-bdc6-c8085a32dfdb}
15:17:24     INFO -  1414793844004	addons.repository	DEBUG	Requesting http://127.0.0.1:8888/extensions-dummy/repositoryGetURL
15:17:24     INFO -  1414793844117	DeferredSave.addons.json	DEBUG	Write succeeded
15:17:24     INFO -  1414793844260	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844263	addons.manager	DEBUG	onUpdateFinished for [e-mail]
15:17:24     INFO -  1414793844282	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844283	addons.manager	DEBUG	onUpdateFinished for [e-mail]
15:17:24     INFO -  1414793844289	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844293	addons.manager	DEBUG	onUpdateFinished for [e-mail]
15:17:24     INFO -  1414793844316	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844318	addons.manager	DEBUG	onUpdateFinished for [e-mail]
15:17:24     INFO -  1414793844338	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844339	addons.manager	DEBUG	onUpdateFinished for [e-mail]
15:17:24     INFO -  1414793844361	addons.update-checker	WARN	Request failed: http://127.0.0.1:8888/extensions-dummy/updateBackgroundURL - 404: Not Found
15:17:24     INFO -  1414793844362	addons.manager	DEBUG	onUpdateFinished for {972ce4c6-7e08-4474-a285-3208198ce6fd}
15:17:24     INFO -  1414793844368	addons.repository	DEBUG	Got metadata search load event
15:17:24     INFO -  1414793844369	addons.repository	WARN	Search failed when repopulating cache
15:17:24     INFO -  1414793844378	addons.manager	DEBUG	Background update check complete
15:17:24     INFO -  1414793844383	addons.xpi	DEBUG	updateAddonRepositoryData found 6 visible add-ons
15:17:24     INFO -  1414793844389	addons.update-dialog	DEBUG	VersionInfo updates finished: found []
15:17:24     INFO -  1414793844390	addons.update-dialog	INFO	VersionInfo: No updates require further action
15:17:24     INFO -  Compatibility dialog closed
15:17:24     INFO -  318 INFO TEST-OK | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js | took 1196ms

Comment 1

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-10-31T19:15:01
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=mozilla-inbound&job_id=3491887
repository: mozilla-inbound
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-859
buildname: Ubuntu ASAN VM 12.04 x64 mozilla-inbound opt test mochitest-browser-chrome-3
revision: fdad88f6319a

317 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Comment 2

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-11-03T05:44:38
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=fx-team&job_id=1073098
repository: fx-team
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-1029
buildname: Ubuntu VM 12.04 fx-team pgo test mochitest-browser-chrome-3
revision: aa13d320bff0

451 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Comment 3

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-11-04T06:18:44
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=mozilla-central&job_id=583628
repository: mozilla-central
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-648
buildname: Ubuntu VM 12.04 mozilla-central pgo test mochitest-browser-chrome-3
revision: 5dde8ea48fef

450 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Comment 4

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-11-13T06:33:51
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=mozilla-central&job_id=626210
repository: mozilla-central
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-799
buildname: Ubuntu ASAN VM 12.04 x64 mozilla-central opt test mochitest-browser-chrome-3
revision: b699303be959

319 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Comment 5

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-11-14T08:00:34
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=fx-team&job_id=1198763
repository: fx-team
who: tomcat[at]mozilla[dot]com
machine: tst-linux32-spot-018
buildname: Ubuntu VM 12.04 fx-team pgo test mochitest-browser-chrome-3
revision: 91fa67078183

451 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_metadataTimeout.js | XHR request should have 30 second timeout - Got undefined, expected 30000

Comment 6

[Ed Morley [:emorley]]

Inactive; closing (see bug 1180138).