1591764 - Intermittent TEST-UNEXPECTED-TIMEOUT | /cookie-store/serviceworker_cookieStore_subscriptions_mismatch.tentative.https.html | expected OK

Status: RESOLVED INCOMPLETE

(Testing :: web-platform-tests, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-10-27T00:14:23.789Z] 00:14:23 INFO - TEST-START | /cookie-store/serviceworker_cookieStore_subscriptions_eventhandler_attribute.tentative.https.html
[task 2019-10-27T00:14:23.797Z] 00:14:23 INFO - Closing window 89
[task 2019-10-27T00:14:24.344Z] 00:14:24 INFO - .
[task 2019-10-27T00:14:24.345Z] 00:14:24 INFO - TEST-OK | /cookie-store/serviceworker_cookieStore_subscriptions_eventhandler_attribute.tentative.https.html | took 552ms
[task 2019-10-27T00:14:24.345Z] 00:14:24 INFO - TEST-START | /cookie-store/serviceworker_cookieStore_subscriptions_mismatch.tentative.https.html
[task 2019-10-27T00:14:24.348Z] 00:14:24 INFO - Closing window 91
[task 2019-10-27T00:14:34.850Z] 00:14:34 INFO - TEST-UNEXPECTED-TIMEOUT | /cookie-store/serviceworker_cookieStore_subscriptions_mismatch.tentative.https.html | expected OK
[task 2019-10-27T00:14:34.850Z] 00:14:34 INFO - TEST-INFO took 10507ms
[task 2019-10-27T00:14:34.866Z] 00:14:34 INFO - PID 5628 | 1572135274857 Marionette INFO Stopped listening on port 55327
[task 2019-10-27T00:14:45.012Z] 00:14:45 INFO - PID 5628 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ServiceWorkerShutdownBlocker: shutting down Service Workers","state":{"pendingPromises":2,"acceptingPromises":false},"filename":"/builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp","lineNumber":82,"stack":"Service Workers shutdown"}] Barrier: profile-change-teardown
[task 2019-10-27T00:14:49.984Z] 00:14:49 INFO - PID 5628 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 433))
[task 2019-10-27T00:15:36.016Z] 00:15:36 INFO - PID 5628 | FATAL ERROR: AsyncShutdown timeout in profile-change-teardown Conditions: [{"name":"ServiceWorkerShutdownBlocker: shutting down Service Workers","state":{"pendingPromises":2,"acceptingPromises":false},"filename":"/builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp","lineNumber":82,"stack":"Service Workers shutdown"}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2019-10-27T00:15:36.017Z] 00:15:36 INFO - PID 5628 | [Parent 5628, Main Thread] ###!!! ABORT: file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp, line 82
[task 2019-10-27T00:15:36.017Z] 00:15:36 INFO - PID 5628 | [Parent 5628, Main Thread] ###!!! ABORT: file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp, line 82
[task 2019-10-27T00:15:36.020Z] 00:15:36 INFO - PID 5628 | ExceptionHandler::GenerateDump cloned child 7072
[task 2019-10-27T00:15:36.021Z] 00:15:36 INFO - PID 5628 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2019-10-27T00:15:36.022Z] 00:15:36 INFO - PID 5628 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2019-10-27T00:15:36.224Z] 00:15:36 INFO - PID 5628 | Exiting due to channel error.
[task 2019-10-27T00:15:36.225Z] 00:15:36 INFO - PID 5628 | Exiting due to channel error.
[task 2019-10-27T00:15:36.281Z] 00:15:36 INFO - Browser exited with return code 11
[task 2019-10-27T00:15:36.281Z] 00:15:36 INFO - PROCESS LEAKS None
[task 2019-10-27T00:15:36.282Z] 00:15:36 INFO - Closing logging queue
[task 2019-10-27T00:15:36.282Z] 00:15:36 INFO - queue closed
[task 2019-10-27T00:15:36.304Z] 00:15:36 INFO - Setting up ssl
[task 2019-10-27T00:15:36.332Z] 00:15:36 INFO - certutil |
[task 2019-10-27T00:15:36.354Z] 00:15:36 INFO - certutil |
[task 2019-10-27T00:15:36.369Z] 00:15:36 INFO - certutil |
[task 2019-10-27T00:15:36.369Z] 00:15:36 INFO - Certificate Nickname Trust Attributes
[task 2019-10-27T00:15:36.370Z] 00:15:36 INFO - SSL,S/MIME,JAR/XPI
[task 2019-10-27T00:15:36.370Z] 00:15:36 INFO -
[task 2019-10-27T00:15:36.370Z] 00:15:36 INFO - web-platform-tests CT,,
[task 2019-10-27T00:15:36.370Z] 00:15:36 INFO -
[task 2019-10-27T00:15:36.398Z] 00:15:36 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpthYMqz.mozrunner
[task 2019-10-27T00:15:36.414Z] 00:15:36 INFO - Starting runner
[task 2019-10-27T00:15:36.975Z] 00:15:36 INFO - PID 7077 | 1572135336970 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-10-27T00:15:36.976Z] 00:15:36 INFO - PID 7077 | 1572135336970 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-10-27T00:15:36.977Z] 00:15:36 INFO - PID 7077 | 1572135336971 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-10-27T00:15:36.978Z] 00:15:36 INFO - PID 7077 | 1572135336971 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2019-10-27T00:15:44.382Z] 00:15:44 INFO - PID 7077 | 1572135344370 Marionette INFO Listening on port 55327
[task 2019-10-27T00:15:44.683Z] 00:15:44 INFO - TEST-START | /cookie-store/serviceworker_cookieStore_subscriptions_reset.tentative.https.html

Comment 1

[Intermittent Failures Robot]

1 failures in 4182 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1591764&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 4314 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1591764&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 3

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

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