Closed Bug 1637174 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last,/IndexedDB/structured-clone.any.html?101-last | Blob: [object Blob] - expected TIMEOUT

Categories

(Core :: Storage: IndexedDB, defect, P5)

defect

Tracking

()

RESOLVED FIXED
mozilla78
Tracking Status
firefox76 --- unaffected
firefox77 --- unaffected
firefox78 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: iain)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell needswork:owner])

Attachments

(1 file)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=301844985&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MAhRCEe8QYeTaPEfL8kigQ/runs/0/artifacts/public/logs/live_backing.log


[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Array: foo,bar,,,,,,,,,true,false,,,,,,,,,123,456,,,,,,,,,
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Array: foo,bar
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Object: [object Object]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMMatrix: [object DOMMatrix]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMMatrixReadOnly: [object DOMMatrixReadOnly]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMPoint: [object DOMPoint]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMPointReadOnly: [object DOMPointReadOnly]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMRect: [object DOMRect]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMRectReadOnly: [object DOMRectReadOnly]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | ImageData: [object ImageData]
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Blob: [object Blob] - expected TIMEOUT
[task 2020-05-12T04:50:53.576Z] 04:50:53 INFO - TEST-INFO | expected TIMEOUT
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | File: [object File] - expected NOTRUN
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Not serializable: function: function() {} - expected NOTRUN
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - TEST-UNEXPECTED-FAIL | /IndexedDB/structured-clone.any.worker.html?101-last | Not serializable: symbol: Symbol(desc) - assert_throws_dom: function "() => store.put(value, 'key')" threw object "TypeError: unsupported type for structured data" that is not a DOMException DataCloneError: property "code" is equal to undefined, expected 25
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - cloneFailureTest/<@http://web-platform.test:8000/IndexedDB/structured-clone.any.js:75:22
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - asyncTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:1988:25
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:592:36
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:591:20
[task 2020-05-12T04:50:53.577Z] 04:50:53 INFO - promise callback
promise_test@http://web-platform.test:8000/resources/testharness.js:590:51
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - subsetTest@http://web-platform.test:8000/common/subset-tests.js:54:14
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - cloneFailureTest@http://web-platform.test:8000/IndexedDB/structured-clone.any.js:63:13
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - @http://web-platform.test:8000/IndexedDB/structured-clone.any.js:324:3
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - @http://web-platform.test:8000/IndexedDB/structured-clone.any.worker.js?101-last:10:14
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Not serializable: DedicatedWorkerGlobalScope: [object DedicatedWorkerGlobalScope] - expected NOTRUN
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Not serializable: Event: [object Event] - expected NOTRUN
[task 2020-05-12T04:50:53.578Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.579Z] 04:50:53 INFO -
[task 2020-05-12T04:50:53.579Z] 04:50:53 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Not serializable: MessageChannel: [object MessageChannel] - expected NOTRUN
[task 2020-05-12T04:50:53.579Z] 04:50:53 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-12T04:50:53.579Z] 04:50:53 INFO - TEST-UNEXPECTED-OK | /IndexedDB/structured-clone.any.worker.html?101-last | expected TIMEOUT
[task 2020-05-12T04:50:53.579Z] 04:50:53 INFO - TEST-INFO expected TIMEOUT | took 2135ms

Component: web-platform-tests → Storage: IndexedDB
Product: Testing → Core
Summary: Intermittent TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Blob: [object Blob] - expected TIMEOUT → Intermittent TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last,/IndexedDB/structured-clone.any.html?101-last | Blob: [object Blob] - expected TIMEOUT
Version: Version 3 → unspecified

Is this by chance a (positive) fallout of bug 1634135, as we already changed WPT test expectations there?
See also Bug 1634135: Update expectations for wpt IndexedDB structured clone tests.

Flags: needinfo?(iireland)

It looks that way to me.

Until I changed the test expectations in that patch, this test perma-failed because it was using unsupported regexp syntax. It looks like the blob case only times out intermittently (although it consistently timed out on my machine).

Flags: needinfo?(iireland)

Iain, can you take a look? should we update the expectancy here?

There are 69 total failures in the last 7 days on windows type platforms

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=302654306&repo=mozilla-central&lineNumber=4755

[task 2020-05-17T22:39:59.323Z] 22:39:59 INFO - TEST-START | /dom/interface-objects.html
[task 2020-05-17T22:39:59.332Z] 22:39:59 INFO - Closing window 38
[task 2020-05-17T22:39:59.604Z] 22:39:59 INFO - .......................
[task 2020-05-17T22:39:59.604Z] 22:39:59 INFO - TEST-OK | /dom/interface-objects.html | took 280ms
[task 2020-05-17T22:39:59.624Z] 22:39:59 INFO - PID 9908 | 1589755199614 Marionette INFO Stopped listening on port 52427
[task 2020-05-17T22:40:00.130Z] 22:40:00 INFO - Browser exited with return code 0
[task 2020-05-17T22:40:00.130Z] 22:40:00 INFO - PROCESS LEAKS None
[task 2020-05-17T22:40:00.132Z] 22:40:00 INFO - Closing logging queue
[task 2020-05-17T22:40:00.132Z] 22:40:00 INFO - queue closed
[task 2020-05-17T22:40:00.192Z] 22:40:00 INFO - Application command: Z:\task_1589754006\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\task_1589754006\appdata\local\temp\tmp6ooxjc
[task 2020-05-17T22:40:00.192Z] 22:40:00 INFO - Starting runner
[task 2020-05-17T22:40:02.584Z] 22:40:02 INFO - PID 1580 | 1589755202579 Marionette INFO Listening on port 52493
[task 2020-05-17T22:40:02.764Z] 22:40:02 INFO -
[task 2020-05-17T22:40:02.764Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Array: foo,bar,,,,,,,,,true,false,,,,,,,,,123,456,,,,,,,,,
[task 2020-05-17T22:40:02.764Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Array: foo,bar
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Object: [object Object]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMMatrix: [object DOMMatrix]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMMatrixReadOnly: [object DOMMatrixReadOnly]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMPoint: [object DOMPoint]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMPointReadOnly: [object DOMPointReadOnly]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMRect: [object DOMRect]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | DOMRectReadOnly: [object DOMRectReadOnly]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | ImageData: [object ImageData]
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-UNEXPECTED-PASS | /IndexedDB/structured-clone.any.worker.html?101-last | Blob: [object Blob] - expected TIMEOUT
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-INFO | expected TIMEOUT
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO -
[task 2020-05-17T22:40:02.765Z] 22:40:02 INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/structured-clone.any.worker.html?101-last | File: [object File] - Test timed out
[task 2020-05-17T22:40:02.766Z] 22:40:02 INFO - TEST-INFO | expected NOTRUN
[task 2020-05-17T22:40:02.766Z] 22:40:02 INFO - .....
[task 2020-05-17T22:40:02.766Z] 22:40:02 INFO - TEST-TIMEOUT | /IndexedDB/structured-clone.any.worker.html?101-last | took 60289ms
[task 2020-05-17T22:40:02.786Z] 22:40:02 INFO - PID 5236 | 1589755202777 Marionette INFO Stopped listening on port 51488
[task 2020-05-17T22:40:03.037Z] 22:40:03 INFO - TEST-START | /dom/lists/DOMTokenList-Iterable.html
[task 2020-05-17T22:40:03.132Z] 22:40:03 INFO - PID 5236 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-17T22:40:03.253Z] 22:40:03 INFO - Browser exited with return code 0
[task 2020-05-17T22:40:03.253Z] 22:40:03 INFO - PROCESS LEAKS None
[task 2020-05-17T22:40:03.254Z] 22:40:03 INFO - Closing logging queue
[task 2020-05-17T22:40:03.254Z] 22:40:03 INFO - queue closed
[task 2020-05-17T22:40:03.334Z] 22:40:03 INFO - TEST-OK | /dom/lists/DOMTokenList-Iterable.html | took 292ms

Flags: needinfo?(iireland)
Whiteboard: [stockwell needswork:owner]
Regressed by: 1634135
Has Regression Range: --- → yes
Keywords: regression

Set release status flags based on info from the regressing bug 1634135

The structured-clone-any.worker Blob test times out fairly consistently, but it is intermittently succeeding on try. This patch updates the expectations for that test, as well as the subsequent tests, based on the try results.

If there are any further problems with this, somebody who actually knows about structured cloning should probably take a look.

Assignee: nobody → iireland
Status: NEW → ASSIGNED
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla78
Flags: needinfo?(iireland)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: