Closed Bug 1157901 Opened 10 years ago Closed 10 years ago

Intermittent test_workerupdatefoundevent.html | Test timed out

Categories

(Core :: DOM: Workers, defect)

Product:
Core
Component:
DOM: Workers
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1134841

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: intermittent-failure)

13:27:43 INFO - 147 INFO TEST-START | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html 13:27:43 INFO - 148 INFO TEST-UNEXPECTED-FAIL | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html | Test timed out. - expected PASS 13:27:43 INFO - 149 INFO TEST-OK | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html | took 327099ms
:nsm -- These failures seem quite frequent today. Are you investigating?
Flags: needinfo?(nsm.nikhil)
Failures on Windows are an exciting twist to this.
OS: Android → All
Hardware: ARM → All
Bisection range: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&filter-searchStr=mozilla-inbound%20opt%20test%20mochitest-13&fromchange=2274f5b28b31&tochange=83c9e4db68b3 There's a bunch of other unfiled serviceworker-related orange floating about today as well; seems like a decent chance it's related.
I backed out bug 1154494 for said other orange, but I think this is separate.
https://hg.mozilla.org/mozilla-central/rev/5ce83454a0e4
Assignee: nobody → ehsan
Status: NEW → RESOLVED
Closed: 10 years ago
status-firefox40: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla40
Status: RESOLVED → REOPENED
status-firefox40: fixed → ---
Resolution: FIXED → ---
Target Milestone: mozilla40 → ---
(In reply to Treeherder Robot from comment #144) > log: > https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=1838304 > repository: b2g-inbound > start_time: 2015-05-01T18:25:15 > who: philringnalda[at]gmail[dot]com > machine: t-w732-ix-075 > buildname: Windows 7 32-bit b2g-inbound pgo test mochitest-4 > revision: c59fc5403728 18:32:10 INFO - 527 INFO TEST-START | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html 18:32:10 INFO - 528 INFO start got called 18:32:10 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:10 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:10 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x140001,name=PBackgroundTest::Msg___delete__) Channel closing: too late to send/recv, messages will be lost 18:32:10 INFO - IPDL protocol error: Handler for PBackgroundTest returned error code 18:32:10 INFO - ###!!! [Parent][DispatchAsyncMessage] Error: (msgtype=0x120001,name=PBackground::Msg_PBackgroundTestConstructor) Processing error: message was deserialized, but the handler returned false (indicating failure) 18:32:10 INFO - 529 INFO startWaitForUpdateFound got called 18:32:10 INFO - 530 INFO registerNext got called 18:32:10 INFO - 531 INFO onupdatefound 18:32:11 INFO - worker_updatefoundevent2.js loaded 18:32:11 INFO - 532 INFO waitForUpdateFound got called 18:32:11 INFO - 533 INFO onupdatefound 18:32:12 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x140001,name=PBackgroundTest::Msg___delete__) Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - IPDL protocol error: Handler for PBackgroundTest returned error code 18:32:15 INFO - ###!!! [Parent][DispatchAsyncMessage] Error: (msgtype=0x120001,name=PBackground::Msg_PBackgroundTestConstructor) Processing error: message was deserialized, but the handler returned false (indicating failure) 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:15 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:22 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost 18:32:31 INFO - [NPAPI 2852] WARNING: pipe error: 109: file c:/builds/moz2_slave/b2g-in-w32-pgo-000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 333 18:37:28 INFO - 1430530648185 Browser.Experiments.Experiments TRACE Experiments #0::updateManifest() 18:37:28 INFO - 1430530648185 Browser.Experiments.Experiments TRACE Experiments #0::_run 18:37:28 INFO - 1430530648185 Browser.Experiments.Experiments TRACE Experiments #0::_main iteration 18:37:28 INFO - 1430530648186 Browser.Experiments.Experiments TRACE Experiments #0::_loadManifest 18:37:28 INFO - 1430530648186 Browser.Experiments.Experiments TRACE Experiments #0::httpGetRequest(http://127.0.0.1:8888/experiments-dummy/manifest) 18:37:28 INFO - 1430530648193 Browser.Experiments.Experiments ERROR Experiments #0::httpGetRequest::onLoad() - Request to http://127.0.0.1:8888/experiments-dummy/manifest returned status 404 18:37:28 INFO - 1430530648193 Browser.Experiments.Experiments ERROR Experiments #0::_loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404 18:37:28 INFO - 1430530648193 Browser.Experiments.Experiments TRACE Experiments #0::_evaluateExperiments 18:37:28 INFO - 1430530648194 Browser.Experiments.Experiments TRACE Experiments #0::_main finished, scheduling next run 18:37:36 INFO - TEST-INFO | screenshot: exit status 0 18:37:36 INFO - 534 INFO TEST-UNEXPECTED-FAIL | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html | Test timed out. - expected PASS 18:37:36 INFO - MEMORY STAT vsize after test: 644751360 18:37:36 INFO - MEMORY STAT vsizeMaxContiguous after test: 621215744 18:37:36 INFO - MEMORY STAT residentFast after test: 65941504 18:37:36 INFO - MEMORY STAT heapAllocated after test: 52769996 18:37:36 INFO - 535 INFO TEST-OK | dom/workers/test/serviceworkers/test_workerupdatefoundevent.html | took 326879ms
Flags: needinfo?(nsm.nikhil)
I think this was caused by the same underlying issue that was causing bug 1134841 (the worker going away before the activate event's waitUntil promise was resolved.) Thanks, Ben!
Assignee: ehsan → bkelly
Status: REOPENED → RESOLVED
Closed: 10 years ago → 10 years ago
Resolution: --- → DUPLICATE
Assignee: bkelly → nobody
You need to log in before you can comment on or make changes to this bug.