Closed Bug 1583000 Opened 5 years ago Closed 5 years ago

High Occurrence TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out

Categories

(Core :: DOM: Service Workers, defect)

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

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla73
Tracking Flags:
Tracking Status
firefox-esr68 --- unaffected
firefox71 --- unaffected
firefox72 --- unaffected
firefox73 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell fixed:backout][retriggered])

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

[task 2019-09-21T22:32:55.247Z] 22:32:55 INFO - TEST-START | /service-workers/service-worker/postmessage-blob-url.https.html
[task 2019-09-21T22:32:55.251Z] 22:32:55 INFO - Closing window 239
[task 2019-09-21T22:32:55.361Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 5 (0x7f6c3f9152e0) [pid = 20970] [serial = 4] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/resources/opaque-response-being-preloaded-xhr.html]
[task 2019-09-21T22:32:55.379Z] 22:32:55 INFO - PID 16739 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-21T22:32:55.395Z] 22:32:55 INFO - PID 16739 | ++DOCSHELL 0x7f5d0cab9000 == 1 [pid = 21009] [id = {3958bf7b-a86f-4f9c-99d4-7ed1423fb497}]
[task 2019-09-21T22:32:55.477Z] 22:32:55 INFO - PID 16739 | --DOCSHELL 0x7f6c3f5b7800 == 1 [pid = 20970] [id = {0fadbaf7-13ca-4990-84e9-eb8973300a98}] [url = https://web-platform.test:8443/service-workers/service-worker/resources/opaque-response-preloaded-xhr.html]
[task 2019-09-21T22:32:55.477Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 4 (0x7f6c3ffad000) [pid = 20970] [serial = 5] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/resources/opaque-response-being-preloaded-xhr.html]
[task 2019-09-21T22:32:55.477Z] 22:32:55 INFO - PID 16739 | --DOCSHELL 0x7f6c3f9ba000 == 0 [pid = 20970] [id = {a12a4995-2380-467c-b4ed-06aed53e2503}] [url = https://web-platform.test:8443/service-workers/service-worker/opaque-response-preloaded.https.html]
[task 2019-09-21T22:32:55.485Z] 22:32:55 INFO - PID 16739 | ++DOMWINDOW == 1 (0x7f5d0c907f20) [pid = 21009] [serial = 1] [outer = (nil)]
[task 2019-09-21T22:32:55.485Z] 22:32:55 INFO - PID 16739 | ++DOMWINDOW == 2 (0x7f5d0ca24800) [pid = 21009] [serial = 2] [outer = 0x7f5d0c907f20]
[task 2019-09-21T22:32:55.563Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 3 (0x7f6c3f9154c0) [pid = 20970] [serial = 6] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/resources/opaque-response-preloaded-xhr.html]
[task 2019-09-21T22:32:55.563Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 2 (0x7f6c3f914f20) [pid = 20970] [serial = 1] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/opaque-response-preloaded.https.html]
[task 2019-09-21T22:32:55.563Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 1 (0x7f6c40d62800) [pid = 20970] [serial = 7] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/resources/opaque-response-preloaded-xhr.html]
[task 2019-09-21T22:32:55.563Z] 22:32:55 INFO - PID 16739 | --DOMWINDOW == 0 (0x7f6c3ffadc00) [pid = 20970] [serial = 3] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/opaque-response-preloaded.https.html]
[task 2019-09-21T22:32:55.605Z] 22:32:55 INFO - PID 16739 | nsStringStats
[task 2019-09-21T22:32:55.606Z] 22:32:55 INFO - PID 16739 | => mAllocCount: 9858
[task 2019-09-21T22:32:55.606Z] 22:32:55 INFO - PID 16739 | => mReallocCount: 0
[task 2019-09-21T22:32:55.606Z] 22:32:55 INFO - PID 16739 | => mFreeCount: 9858
[task 2019-09-21T22:32:55.606Z] 22:32:55 INFO - PID 16739 | => mShareCount: 11168
[task 2019-09-21T22:32:55.606Z] 22:32:55 INFO - PID 16739 | => mAdoptCount: 708
[task 2019-09-21T22:32:55.608Z] 22:32:55 INFO - PID 16739 | => mAdoptFreeCount: 728
[task 2019-09-21T22:32:55.608Z] 22:32:55 INFO - PID 16739 | => Process ID: 20970, Thread ID: 140103356340032
[task 2019-09-21T22:32:55.664Z] 22:32:55 INFO - PID 16739 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpTyCotx.mozrunner/runtests_leaks_870_tab_pid21049.log
[task 2019-09-21T22:32:55.802Z] 22:32:55 INFO - PID 16739 | ++DOMWINDOW == 3 (0x7f5d0d4f6800) [pid = 21009] [serial = 3] [outer = 0x7f5d0c907f20]
[task 2019-09-21T22:32:55.818Z] 22:32:55 INFO - PID 16739 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-21T22:32:55.942Z] 22:32:55 INFO - PID 16739 | [2019-09-21T22:32:55Z WARN audio_thread_priority::rt_linux] Could not make thread real-time.
[task 2019-09-21T22:32:55.943Z] 22:32:55 INFO - PID 16739 | [2019-09-21T22:32:55Z WARN audioipc_client] Could not promote audio threads to real-time during initialization.
[task 2019-09-21T22:32:56.108Z] 22:32:56 INFO - PID 16739 | ++DOMWINDOW == 4 (0x7f5d0d4f8400) [pid = 21009] [serial = 4] [outer = 0x7f5d0c907f20]
[task 2019-09-21T22:32:56.351Z] 22:32:56 INFO - PID 16739 | [Parent 16739, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-21T22:32:56.359Z] 22:32:56 INFO - PID 16739 | [Child 16821, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-21T22:32:56.381Z] 22:32:56 INFO - PID 16739 | [Child 16821, DOM Worker] WARNING: '!rv', file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerOp.cpp, line 277
[task 2019-09-21T22:32:56.385Z] 22:32:56 INFO - PID 16739 | [Child 16821, DOM Worker] WARNING: '!rv', file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerOp.cpp, line 277
[task 2019-09-21T22:32:56.385Z] 22:32:56 INFO - PID 16739 | [Child 16821, DOM Worker] WARNING: '!rv', file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerOp.cpp, line 277
[task 2019-09-21T22:32:56.387Z] 22:32:56 INFO - PID 16739 | [Child 16821, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsCORSListenerProxy.cpp, line 911
[task 2019-09-21T22:32:56.388Z] 22:32:56 INFO - PID 16739 | [Child 16821, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 359[task 2019-09-21T22:32:56.389Z] 22:32:56 INFO - PID 16739 | [Child 16821, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 887
[task 2019-09-21T22:33:02.975Z] 22:33:02 INFO - PID 16739 | --DOCSHELL 0x7f9cb6bbb800 == 0 [pid = 20929] [id = {667e6228-c2ea-4c64-a43b-fd1c08d19477}] [url = https://web-platform.test:8443/service-workers/service-worker/oninstall-script-error.https.html]
[task 2019-09-21T22:33:07.046Z] 22:33:07 INFO - PID 16739 | --DOMWINDOW == 2 (0x7f9cb6b56c00) [pid = 20929] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-09-21T22:33:07.087Z] 22:33:07 INFO - PID 16739 | --DOMWINDOW == 1 (0x7f9cb6b19f20) [pid = 20929] [serial = 1] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/oninstall-script-error.https.html]
[task 2019-09-21T22:33:09.973Z] 22:33:09 INFO - PID 16739 | --DOMWINDOW == 3 (0x7f5d0d4f6800) [pid = 21009] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2019-09-21T22:33:09.973Z] 22:33:09 INFO - PID 16739 | --DOMWINDOW == 2 (0x7f5d0ca24800) [pid = 21009] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-09-21T22:33:10.392Z] 22:33:10 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:10.394Z] 22:33:10 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:11.122Z] 22:33:11 INFO - PID 16739 | --DOMWINDOW == 0 (0x7f9cb6b61c00) [pid = 20929] [serial = 3] [outer = (nil)] [url = https://web-platform.test:8443/service-workers/service-worker/oninstall-script-error.https.html]
[task 2019-09-21T22:33:23.261Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:23.261Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:23.309Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:23.309Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:23.467Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:23.467Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:23.475Z] 22:33:23 INFO - PID 16739 | [Child 20929, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1418
[task 2019-09-21T22:33:23.476Z] 22:33:23 INFO - PID 16739 | [Child 20929, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1895
[task 2019-09-21T22:33:23.476Z] 22:33:23 INFO - PID 16739 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-21T22:33:23.512Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:23.512Z] 22:33:23 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:23.554Z] 22:33:23 INFO - PID 16739 | nsStringStats
[task 2019-09-21T22:33:23.554Z] 22:33:23 INFO - PID 16739 | => mAllocCount: 9806
[task 2019-09-21T22:33:23.555Z] 22:33:23 INFO - PID 16739 | => mReallocCount: 0
[task 2019-09-21T22:33:23.555Z] 22:33:23 INFO - PID 16739 | => mFreeCount: 9806
[task 2019-09-21T22:33:23.555Z] 22:33:23 INFO - PID 16739 | => mShareCount: 10300
[task 2019-09-21T22:33:23.556Z] 22:33:23 INFO - PID 16739 | => mAdoptCount: 569
[task 2019-09-21T22:33:23.556Z] 22:33:23 INFO - PID 16739 | => mAdoptFreeCount: 599
[task 2019-09-21T22:33:23.556Z] 22:33:23 INFO - PID 16739 | => Process ID: 20929, Thread ID: 140311513896768
[task 2019-09-21T22:33:26.257Z] 22:33:26 INFO - PID 16739 | [Parent 16739, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-21T22:33:26.257Z] 22:33:26 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-09-21T22:33:26.259Z] 22:33:26 INFO - PID 16739 | [Parent 16739, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-09-21T22:33:26.261Z] 22:33:26 INFO - PID 16739 | JavaScript error: , line 0: TypeError: NetworkError when attempting to fetch resource.
[task 2019-09-21T22:33:26.314Z] 22:33:26 INFO -
[task 2019-09-21T22:33:26.314Z] 22:33:26 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out

This is almost perma failing on Central: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&searchStr=windows%2C7%2Cdebug%2Cweb%2Cplatform%2Ctests%2Ctest-windows7-32%2Fdebug-web-platform-tests-e10s-5%2Cw%28wpt5%29&fromchange=c8ce8b91465a7192b056102481d633fef850fac6&tochange=de230ce6bd5058c0608cddfb4b6c1fb3afb62759&selectedJob=282259710

Started back fills: https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=windows%2C7%2Cdebug%2Cweb%2Cplatform%2Ctests%2Ctest-windows7-32%2Fdebug-web-platform-tests-e10s&tochange=028b40d8140ccd71dde8bf1322df03247e64750c&fromchange=5a7641c75a2e3d54864c23242f2b986c7ec77796&selectedJob=282263491

Flags: needinfo?(btara)
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out → High Occurrence Central Tier 1 TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out

Started to frequently fail with this push:

https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=Windows%2C7%2Cdebug%2CWeb%2Cplatform%2Ctests%2Ctest-windows7-32%2Fdebug-web-platform-tests-e10s-5%2CW%28wpt5%29&tochange=028b40d8140ccd71dde8bf1322df03247e64750c&fromchange=0cc5dce6603393d3e94a419861c03c44563a6bd7&selectedJob=282265108

Andrea, can you please take a look?

Flags: needinfo?(amarchesini)
Regressed by: 1472158
Summary: High Occurrence Central Tier 1 TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out → High Occurrence TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/postmessage-blob-url.https.html | postMessage Blob URL to a ServiceWorker - Test timed out
Whiteboard: [stockwell needswork:owner] → [stockwell needswork:owner][retriggered]

fixed by backout of bug 1472158

Status: NEW → RESOLVED
Closed: 5 years ago
Flags: needinfo?(btara)
Flags: needinfo?(amarchesini)
Resolution: --- → FIXED
Whiteboard: [stockwell needswork:owner][retriggered] → [stockwell fixed:backout][retriggered]
Assignee: nobody → amarchesini
status-firefox71: --- → unaffected
status-firefox72: --- → unaffected
status-firefox73: --- → fixed
status-firefox-esr68: --- → unaffected
Target Milestone: --- → mozilla73
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.