Closed Bug 1649184 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /background-fetch/fetch.https.window.html | recordsAvailable is false after onbackgroundfetchsuccess finishes execution. - Test timed out

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)

Details

(Keywords: intermittent-failure)

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

[task 2020-06-29T16:23:12.485Z] 16:23:12     INFO - TEST-OK | /background-fetch/fetch-uploads.https.window.html | took 1015ms
[task 2020-06-29T16:23:12.486Z] 16:23:12     INFO - TEST-START | /background-fetch/fetch.https.window.html
[task 2020-06-29T16:23:12.486Z] 16:23:12     INFO - Closing window 24
[task 2020-06-29T16:23:13.106Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.116Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.116Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.165Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.165Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.165Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.189Z] 16:23:13     INFO - PID 1001 | [Child 1021, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.191Z] 16:23:13     INFO - PID 1001 | [Child 1021, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.218Z] 16:23:13     INFO - PID 1001 | [Child 1021, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.219Z] 16:23:13     INFO - PID 1001 | [Child 1021, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.219Z] 16:23:13     INFO - PID 1001 | [Child 1021, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.265Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.266Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-06-29T16:23:13.266Z] 16:23:13     INFO - PID 1001 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-29T16:23:13.266Z] 16:23:13     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-06-29T16:23:13.266Z] 16:23:13     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-06-29T16:23:13.266Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.271Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.273Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.314Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.316Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.318Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.341Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.342Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-06-29T16:23:13.342Z] 16:23:13     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-06-29T16:23:13.342Z] 16:23:13     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-06-29T16:23:13.364Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.390Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.390Z] 16:23:13     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.437Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.439Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.439Z] 16:23:13     INFO - PID 1001 | [Child 1008, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:13.439Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp, line 572
[task 2020-06-29T16:23:13.439Z] 16:23:13     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-06-29T16:23:13.440Z] 16:23:13     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
<...>
[task 2020-06-29T16:23:15.619Z] 16:23:15     INFO - PID 1001 | [Child 1021, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:15.637Z] 16:23:15     INFO - PID 1001 | [Child 1021, DOM Worker] WARNING: '!rv', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp, line 282
[task 2020-06-29T16:23:15.638Z] 16:23:15     INFO - 
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Background Fetch requires an activated Service Worker - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", serviceWorkerRegistration.backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Argument verification is done for BackgroundFetchManager.fetch() - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | IDs must be unique among active Background Fetch registrations - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Empty URL is OK. - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Requests with PUT method require CORS Preflight and succeed. - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Requests with text/json content type require CORS Preflight and succeed. - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Using Background Fetch to successfully fetch a single resource - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Registration object gets updated values when a background fetch completes. - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Background Fetch that exceeds the quota throws a QuotaExceededError - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-FAIL | /background-fetch/fetch.https.window.html | Fetches can have requests with duplicate URLs - promise_test: Unhandled rejection with value: object "TypeError: can't access property "fetch", backgroundFetch is undefined"
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-UNEXPECTED-TIMEOUT | /background-fetch/fetch.https.window.html | recordsAvailable is false after onbackgroundfetchsuccess finishes execution. - Test timed out
[task 2020-06-29T16:23:15.639Z] 16:23:15     INFO - TEST-INFO | expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - 
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-UNEXPECTED-NOTRUN | /background-fetch/fetch.https.window.html | Using Background Fetch to fetch a non-existent resource should fail. - expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-INFO | expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - 
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-UNEXPECTED-NOTRUN | /background-fetch/fetch.https.window.html | Fetches with mixed content should fail. - expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-INFO | expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - 
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-UNEXPECTED-NOTRUN | /background-fetch/fetch.https.window.html | Responses failing CORS checks are not leaked - expected FAIL
[task 2020-06-29T16:23:15.640Z] 16:23:15     INFO - TEST-INFO | expected FAIL
[task 2020-06-29T16:23:15.641Z] 16:23:15     INFO - TEST-UNEXPECTED-TIMEOUT | /background-fetch/fetch.https.window.html | expected OK
[task 2020-06-29T16:23:15.641Z] 16:23:15     INFO - TEST-INFO took 3160ms
[task 2020-06-29T16:23:15.666Z] 16:23:15     INFO - PID 1001 | 1593447795656	Marionette	INFO	Stopped listening on port 50187
[task 2020-06-29T16:23:16.364Z] 16:23:16     INFO - PID 1001 | [Child 1006, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-06-29T16:23:16.364Z] 16:23:16     INFO - PID 1001 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-29T16:23:16.364Z] 16:23:16     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-06-29T16:23:16.365Z] 16:23:16     INFO - PID 1001 | [Parent 1001, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-06-29T16:23:16.387Z] 16:23:16     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-06-29T16:23:16.388Z] 16:23:16     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-06-29T16:23:16.419Z] 16:23:16     INFO - PID 1001 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-29T16:23:16.419Z] 16:23:16     INFO - PID 1001 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-29T16:23:16.420Z] 16:23:16     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-06-29T16:23:16.440Z] 16:23:16     INFO - PID 1001 | [Parent 1001, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-06-29T16:23:16.590Z] 16:23:16     INFO - PID 1001 | [Child 1006, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-29T16:23:16.964Z] 16:23:16     INFO - PID 1001 | [2020-06-29T16:23:16Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-29T16:23:16.964Z] 16:23:16     INFO - PID 1001 | [2020-06-29T16:23:16Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-29T16:23:17.042Z] 16:23:17     INFO - PID 1001 | [Child 1021, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-06-29T16:23:17.042Z] 16:23:17     INFO - PID 1001 | [Child 1021, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp, line 291
[task 2020-06-29T16:23:17.042Z] 16:23:17     INFO - PID 1001 | [Child 1021, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp, line 291
[task 2020-06-29T16:23:17.043Z] 16:23:17     INFO - PID 1001 | [Child 1008, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-06-29T16:23:17.043Z] 16:23:17     INFO - PID 1001 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost

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

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