Closed Bug 1567794 Opened 6 years ago Closed 5 years ago

Intermittent dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled

Categories

(Core :: DOM: Service Workers, defect, P5)

Product:
Core
Component:
DOM: Service Workers
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=257605768&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Av1l0SSCQM2mwhbBpCOmrQ/runs/0/artifacts/public/logs/live_backing.log

[task 2019-07-21T22:53:42.706Z] 22:53:42 INFO - TEST-START | dom/serviceworkers/test/browser_download_canceled.js
[task 2019-07-21T22:53:42.706Z] 22:53:42 INFO - GECKO(3494) | Chrome file doesn't exist: /builds/worker/workspace/build/tests/mochitest/browser/dom/serviceworkers/test/head.js
[task 2019-07-21T22:53:42.755Z] 22:53:42 INFO - GECKO(3494) | ++DOCSHELL 0x7face8ec3000 == 1 [pid = 3566] [id = {44db7ebf-4532-44b1-884e-016ba34e0b6a}]
[task 2019-07-21T22:53:42.756Z] 22:53:42 INFO - GECKO(3494) | ++DOMWINDOW == 1 (0x7face9f5fd40) [pid = 3566] [serial = 5] [outer = (nil)]
[task 2019-07-21T22:53:42.791Z] 22:53:42 INFO - GECKO(3494) | [Child 3566, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:42.807Z] 22:53:42 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-21T22:53:42.807Z] 22:53:42 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-21T22:53:42.919Z] 22:53:42 INFO - GECKO(3494) | ++DOMWINDOW == 2 (0x7face8d18400) [pid = 3566] [serial = 6] [outer = 0x7face9f5fd40]
[task 2019-07-21T22:53:43.000Z] 22:53:43 INFO - GECKO(3494) | WARN 2019-07-21T22:53:42Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-07-21T22:53:43.001Z] 22:53:43 INFO - GECKO(3494) | WARN 2019-07-21T22:53:42Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-07-21T22:53:43.002Z] 22:53:43 INFO - GECKO(3494) | WARN 2019-07-21T22:53:42Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-07-21T22:53:43.003Z] 22:53:43 INFO - GECKO(3494) | WARN 2019-07-21T22:53:42Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-07-21T22:53:43.041Z] 22:53:43 INFO - GECKO(3494) | ++DOCSHELL 0x7f3cb1cc8000 == 1 [pid = 3812] [id = {7c99100a-91cb-4608-bb06-e21bcbf5b333}]
[task 2019-07-21T22:53:43.118Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 2 (0x7f2a503ad800) [pid = 3644] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-07-21T22:53:43.134Z] 22:53:43 INFO - GECKO(3494) | [Child 3566, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:43.230Z] 22:53:43 INFO - GECKO(3494) | ++DOMWINDOW == 1 (0x7f3cb1c0bd40) [pid = 3812] [serial = 1] [outer = (nil)]
[task 2019-07-21T22:53:43.266Z] 22:53:43 INFO - GECKO(3494) | [Child 3812, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:43.363Z] 22:53:43 INFO - GECKO(3494) | ++DOMWINDOW == 2 (0x7f3cb2b1d000) [pid = 3812] [serial = 2] [outer = 0x7f3cb1c0bd40]
[task 2019-07-21T22:53:43.425Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 9 (0x7f847c40f000) [pid = 3730] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-07-21T22:53:43.425Z] 22:53:43 INFO - GECKO(3494) | --DOCSHELL 0x7f847b066000 == 2 [pid = 3730] [id = {eb0c1c57-e9ae-4b64-a1e8-d2482a5569bf}] [url = https://example.org/browser/dom/serviceworkers/test/page_post_controlled.html]
[task 2019-07-21T22:53:43.425Z] 22:53:43 INFO - GECKO(3494) | --DOCSHELL 0x7f847b068800 == 1 [pid = 3730] [id = {baa6a30f-3780-4bae-8483-67d12286a997}] [url = https://example.org/browser/dom/serviceworkers/test/page_post_controlled.html]
[task 2019-07-21T22:53:43.440Z] 22:53:43 INFO - GECKO(3494) | --DOCSHELL 0x7f847b5c5800 == 0 [pid = 3730] [id = {1d9aced6-9827-46ef-a7f2-cc101bd7eb5d}] [url = https://example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2019-07-21T22:53:43.522Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 4 (0x7fe536118800) [pid = 3674] [serial = 4] [outer = (nil)] [url = http://mochi.test:8888/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2019-07-21T22:53:43.523Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 3 (0x7fe5362e9800) [pid = 3674] [serial = 7] [outer = (nil)] [url = https://tracking.example.org/browser/dom/serviceworkers/test/page_post_controlled.html]
[task 2019-07-21T22:53:43.524Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 2 (0x7fe5362de400) [pid = 3674] [serial = 3] [outer = (nil)] [url = https://tracking.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2019-07-21T22:53:43.593Z] 22:53:43 INFO - [3469, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:43.722Z] 22:53:43 INFO - GECKO(3494) | --DOMWINDOW == 1 (0x7fe53512fd40) [pid = 3674] [serial = 1] [outer = (nil)] [url = https://tracking.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2019-07-21T22:53:43.798Z] 22:53:43 INFO - GECKO(3494) | ++DOMWINDOW == 3 (0x7face9ead000) [pid = 3566] [serial = 7] [outer = 0x7face9f5fd40]
[task 2019-07-21T22:53:44.211Z] 22:53:44 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-07-21T22:53:44.252Z] 22:53:44 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-07-21T22:53:44.434Z] 22:53:44 INFO - [3469, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:44.454Z] 22:53:44 INFO - server-stream-download.js: processing request for /browser/dom/serviceworkers/test/download_canceled/server-stream-download.sjs?monitor
[task 2019-07-21T22:53:44.462Z] 22:53:44 INFO - server-stream-download.js: monitor headers issued
[task 2019-07-21T22:53:44.478Z] 22:53:44 INFO - server-stream-download.js: processing request for /browser/dom/serviceworkers/test/download_canceled/server-stream-download.sjs?
[task 2019-07-21T22:53:44.486Z] 22:53:44 INFO - server-stream-download.js: stream headers + first payload issued
[task 2019-07-21T22:53:44.543Z] 22:53:44 INFO - GECKO(3494) | ++DOCSHELL 0x7f80f8a75000 == 8 [pid = 3494] [id = {0d874022-e788-4b2a-b2a0-5ad95366fa2f}]
...
[task 2019-07-21T22:53:55.201Z] 22:53:55 INFO - GECKO(3494) | --DOMWINDOW == 0 (0x7f9341acb800) [pid = 3620] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-07-21T22:53:56.310Z] 22:53:56 INFO - GECKO(3494) | --DOMWINDOW == 1 (0x7fdacb829d40) [pid = 3706] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2019-07-21T22:53:57.247Z] 22:53:57 INFO - TEST-INFO | started process screentopng
[task 2019-07-21T22:53:57.753Z] 22:53:57 INFO - TEST-INFO | screentopng: exit 0
[task 2019-07-21T22:53:57.755Z] 22:53:57 INFO - Buffered messages logged at 22:53:42
[task 2019-07-21T22:53:57.756Z] 22:53:57 INFO - Entering test bound interruptedDownloads
[task 2019-07-21T22:53:57.757Z] 22:53:57 INFO - Buffered messages logged at 22:53:44
[task 2019-07-21T22:53:57.758Z] 22:53:57 INFO - TEST-PASS | dom/serviceworkers/test/browser_download_canceled.js | page became controlled -
[task 2019-07-21T22:53:57.758Z] 22:53:57 INFO - watching for download popup
[task 2019-07-21T22:53:57.760Z] 22:53:57 INFO - triggering download of "sw-passthrough-download"
[task 2019-07-21T22:53:57.761Z] 22:53:57 INFO - waiting for download popup
[task 2019-07-21T22:53:57.763Z] 22:53:57 INFO - TEST-PASS | dom/serviceworkers/test/browser_download_canceled.js | canceled download -
[task 2019-07-21T22:53:57.763Z] 22:53:57 INFO - wait for the sw-passthrough-download stream to close.
[task 2019-07-21T22:53:57.765Z] 22:53:57 INFO - clicking cancel button
[task 2019-07-21T22:53:57.766Z] 22:53:57 INFO - Buffered messages logged at 22:53:45
[task 2019-07-21T22:53:57.767Z] 22:53:57 INFO - TEST-PASS | dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. -
[task 2019-07-21T22:53:57.772Z] 22:53:57 INFO - Cancellation reason: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIOutputStream.write] after undefined ticks
[task 2019-07-21T22:53:57.773Z] 22:53:57 INFO - watching for download popup
[task 2019-07-21T22:53:57.773Z] 22:53:57 INFO - triggering download of "sw-stream-download"
[task 2019-07-21T22:53:57.774Z] 22:53:57 INFO - waiting for download popup
[task 2019-07-21T22:53:57.774Z] 22:53:57 INFO - TEST-PASS | dom/serviceworkers/test/browser_download_canceled.js | canceled download -
[task 2019-07-21T22:53:57.775Z] 22:53:57 INFO - wait for the sw-stream-download stream to close.
[task 2019-07-21T22:53:57.775Z] 22:53:57 INFO - clicking cancel button
[task 2019-07-21T22:53:57.776Z] 22:53:57 INFO - Buffered messages finished
[task 2019-07-21T22:53:57.779Z] 22:53:57 INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled
[task 2019-07-21T22:53:57.782Z] 22:53:57 INFO - Stack trace:
[task 2019-07-21T22:53:57.782Z] 22:53:57 INFO - chrome://mochikit/content/browser-test.js:test_is:1591
[task 2019-07-21T22:53:57.784Z] 22:53:57 INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_download_canceled.js:performCanceledDownload:92
[task 2019-07-21T22:53:57.785Z] 22:53:57 INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_download_canceled.js:interruptedDownloads:139
[task 2019-07-21T22:53:57.787Z] 22:53:57 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1346
[task 2019-07-21T22:53:57.788Z] 22:53:57 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1381
[task 2019-07-21T22:53:57.789Z] 22:53:57 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1209
[task 2019-07-21T22:53:57.791Z] 22:53:57 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
[task 2019-07-21T22:53:57.792Z] 22:53:57 INFO - Cancellation reason: timeout after 3002 ticks
[task 2019-07-21T22:53:57.794Z] 22:53:57 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-21T22:53:57.795Z] 22:53:57 INFO - GECKO(3494) | [Parent 3494, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-07-21T22:53:57.797Z] 22:53:57 INFO - GECKO(3494) | [Child 3812, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-21T22:53:57.801Z] 22:53:57 INFO - Leaving test bound interruptedDownloads
[task 2019-07-21T22:53:57.803Z] 22:53:57 INFO - GECKO(3494) | MEMORY STAT | vsize 3020MB | residentFast 342MB | heapAllocated 116MB
[task 2019-07-21T22:53:57.804Z] 22:53:57 INFO - TEST-OK | dom/serviceworkers/test/browser_download_canceled.js | took 14762ms

Summary: Intermittent dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled → Intermittent Tier 2 dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=274921546&repo=autoland&lineNumber=30489

[task 2019-11-06T21:35:33.217Z] 21:35:33 INFO - TEST-PASS | dom/serviceworkers/test/browser_download_canceled.js | canceled download -
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - wait for the sw-passthrough-download stream to close.
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - Buffered messages finished
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - Stack trace:
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - chrome://mochikit/content/browser-test.js:test_is:1314
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_download_canceled.js:performCanceledDownload:96
[task 2019-11-06T21:35:33.218Z] 21:35:33 INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_download_canceled.js:interruptedDownloads:140
[task 2019-11-06T21:35:33.219Z] 21:35:33 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1069
[task 2019-11-06T21:35:33.219Z] 21:35:33 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2019-11-06T21:35:33.219Z] 21:35:33 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2019-11-06T21:35:33.219Z] 21:35:33 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-11-06T21:35:33.219Z] 21:35:33 INFO - Cancellation reason: timeout after undefined ticks
[task 2019-11-06T21:35:33.220Z] 21:35:33 INFO - watching for download popup
[task 2019-11-06T21:35:33.220Z] 21:35:33 INFO - triggering download of "sw-stream-download"

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent Tier 2 dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled → Intermittent dom/serviceworkers/test/browser_download_canceled.js | Ensure the stream canceled instead of timing out. - Got timeout, expected canceled

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 6 years ago → 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.