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

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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 callbackpromise_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

Comment 2

[Jens Stutte [:jstutte]]

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.

Comment 3

[Iain Ireland [:iain]]

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).

Comment 4

[Intermittent Failures Robot]

19 failures in 970 pushes (0.02 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 16
• try: 3

Platform breakdown:

• windows10-64-qr: 6
• windows10-64: 7
• windows10-64-shippable-qr: 2
• windows10-64-shippable: 2
• macosx1014-64: 1
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1637174&startday=2020-05-14&endday=2020-05-14&tree=all

Comment 5

[Bogdan Tara[:bogdan_tara | bogdant]]

Seems to be starting with the expectations update on bug 1634135 - https://hg.mozilla.org/mozilla-central/rev/3d92a3a541ffab165ca1590ed57ea99ec7dd2f0e

Comment 6

[Andreea Pavel [:apavel]]

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

Comment 7

[Intermittent Failures Robot]

72 failures in 5488 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #16 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 48
• try: 24

Platform breakdown:

• windows10-64: 23
• windows10-64-shippable-qr: 11
• windows10-64-shippable: 15
• windows7-32: 1
• windows10-64-qr: 11
• macosx1014-64: 1
• linux1804-64: 1
• android-em-7-0-x86_64: 1
• windows10-64-devedition: 5
• windows10-64-ccov: 1
• linux1804-64-qr: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1637174&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 9

[Iain Ireland [:iain]]

Attachment: Bug 1637174: Update WPT test expectations r=asuth

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.

Comment 10

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/bf2b0af70217
Update WPT test expectations r=asuth

Comment 11

[Intermittent Failures Robot]

16 failures in 1049 pushes (0.015 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 10
• try: 6

Platform breakdown:

• windows10-64: 5
• windows10-64-qr: 5
• windows10-64-shippable-qr: 3
• windows10-64-devedition: 1
• android-em-7-0-x86_64: 1
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1637174&startday=2020-05-19&endday=2020-05-19&tree=all

Comment 12

[Atila Butkovits]

https://hg.mozilla.org/mozilla-central/rev/bf2b0af70217

Comment 13

[Intermittent Failures Robot]

32 failures in 4727 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #47 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 24
• try: 8

Platform breakdown:

• windows10-64-qr: 9
• windows10-64-ccov: 2
• windows10-64: 8
• windows7-32: 2
• android-em-7-0-x86_64: 3
• windows10-64-shippable: 4
• windows10-64-shippable-qr: 3
• windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1637174&startday=2020-05-18&endday=2020-05-24&tree=all