1596306 - Intermittent Tier 2 /service-workers/service-worker/detached-context.https.html | TypeError: this.curBrowser.messageManager is null

Status: RESOLVED INCOMPLETE

(Core :: DOM: Service Workers, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-11-13T23:52:15.651Z] 23:52:15 INFO - TEST-START | /service-workers/service-worker/dedicated-worker-service-worker-interception.https.html
[task 2019-11-13T23:52:15.658Z] 23:52:15 INFO - Closing window 83
[task 2019-11-13T23:52:26.088Z] 23:52:26 INFO - ...
[task 2019-11-13T23:52:26.088Z] 23:52:26 INFO - TEST-ERROR | /service-workers/service-worker/dedicated-worker-service-worker-interception.https.html | took 10439ms
[task 2019-11-13T23:52:26.088Z] 23:52:26 INFO - TEST-START | /service-workers/service-worker/detached-context.https.html
[task 2019-11-13T23:52:26.095Z] 23:52:26 INFO - Closing window 85
[task 2019-11-13T23:52:26.399Z] 23:52:26 INFO - PID 17448 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002C,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2019-11-13T23:52:26.400Z] 23:52:26 INFO - PID 17448 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002C,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2019-11-13T23:52:26.400Z] 23:52:26 INFO - PID 17448 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E0029,name=PBrowser::Msg_EnableDisableCommands) Channel closing: too late to send/recv, messages will be lost
[task 2019-11-13T23:52:26.400Z] 23:52:26 INFO - PID 17448 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E004A,name=PBrowser::Msg_OnStateChange) Channel closing: too late to send/recv, messages will be lost
[task 2019-11-13T23:52:26.400Z] 23:52:26 INFO - PID 17448 | JavaScript error: resource:///actors/AboutReaderChild.jsm, line 95: NS_ERROR_UNEXPECTED:
[task 2019-11-13T23:52:26.401Z] 23:52:26 INFO - PID 17448 | ###!!! [Child][MessageChannel] Error: (msgtype=0x37012A,name=PContent::Msg_DetachBrowsingContext) Closed channel: cannot send/recv
[task 2019-11-13T23:52:26.405Z] 23:52:26 INFO - PID 17448 | Marionette threw an error: TypeError: this.curBrowser.messageManager is null
[task 2019-11-13T23:52:26.405Z] 23:52:26 INFO - PID 17448 | GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:367:7
[task 2019-11-13T23:52:26.406Z] 23:52:26 INFO - PID 17448 | executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2019-11-13T23:52:26.406Z] 23:52:26 INFO - PID 17448 | GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:364:19
[task 2019-11-13T23:52:26.406Z] 23:52:26 INFO - PID 17448 | send/<@chrome://marionette/content/proxy.js:194:12
[task 2019-11-13T23:52:26.408Z] 23:52:26 INFO - PID 17448 | send@chrome://marionette/content/proxy.js:128:12
[task 2019-11-13T23:52:26.408Z] 23:52:26 INFO - PID 17448 | get/<@chrome://marionette/content/proxy.js:41:29
[task 2019-11-13T23:52:26.409Z] 23:52:26 INFO - PID 17448 | GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1116:27
[task 2019-11-13T23:52:26.409Z] 23:52:26 INFO - PID 17448 | Async*despatch@chrome://marionette/content/server.js:305:40
[task 2019-11-13T23:52:26.410Z] 23:52:26 INFO - PID 17448 | execute@chrome://marionette/content/server.js:275:16
[task 2019-11-13T23:52:26.411Z] 23:52:26 INFO - PID 17448 | onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-11-13T23:52:26.412Z] 23:52:26 INFO - PID 17448 | onPacket@chrome://marionette/content/server.js:249:9
[task 2019-11-13T23:52:26.412Z] 23:52:26 INFO - PID 17448 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-11-13T23:52:26.413Z] 23:52:26 WARNING - Traceback (most recent call last):
[task 2019-11-13T23:52:26.413Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 627, in _run
[task 2019-11-13T23:52:26.414Z] 23:52:26 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2019-11-13T23:52:26.414Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 738, in do_testharness
[task 2019-11-13T23:52:26.414Z] 23:52:26 WARNING - protocol.marionette.navigate(url)
[task 2019-11-13T23:52:26.414Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1467, in navigate
[task 2019-11-13T23:52:26.415Z] 23:52:26 WARNING - {"url": url})
[task 2019-11-13T23:52:26.416Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2019-11-13T23:52:26.417Z] 23:52:26 WARNING - return func(args, **kwargs)
[task 2019-11-13T23:52:26.417Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2019-11-13T23:52:26.418Z] 23:52:26 WARNING - self._handle_error(err)
[task 2019-11-13T23:52:26.418Z] 23:52:26 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2019-11-13T23:52:26.418Z] 23:52:26 WARNING - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2019-11-13T23:52:26.419Z] 23:52:26 WARNING - UnknownException: TypeError: this.curBrowser.messageManager is null
[task 2019-11-13T23:52:26.419Z] 23:52:26 WARNING - stacktrace:
[task 2019-11-13T23:52:26.419Z] 23:52:26 WARNING - GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:367:7
[task 2019-11-13T23:52:26.420Z] 23:52:26 WARNING - executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2019-11-13T23:52:26.420Z] 23:52:26 WARNING - GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:364:19
[task 2019-11-13T23:52:26.420Z] 23:52:26 WARNING - send/<@chrome://marionette/content/proxy.js:194:12
[task 2019-11-13T23:52:26.420Z] 23:52:26 WARNING - send@chrome://marionette/content/proxy.js:128:12
[task 2019-11-13T23:52:26.421Z] 23:52:26 WARNING - get/<@chrome://marionette/content/proxy.js:41:29
[task 2019-11-13T23:52:26.421Z] 23:52:26 WARNING - GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1116:27
[task 2019-11-13T23:52:26.421Z] 23:52:26 WARNING - Asyncdespatch@chrome://marionette/content/server.js:305:40
[task 2019-11-13T23:52:26.422Z] 23:52:26 WARNING - execute@chrome://marionette/content/server.js:275:16
[task 2019-11-13T23:52:26.422Z] 23:52:26 WARNING - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-11-13T23:52:26.422Z] 23:52:26 WARNING - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-11-13T23:52:26.423Z] 23:52:26 WARNING - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-11-13T23:52:26.423Z] 23:52:26 WARNING -
[task 2019-11-13T23:52:26.424Z] 23:52:26 WARNING -
[task 2019-11-13T23:52:26.425Z] 23:52:26 INFO - TEST-UNEXPECTED-ERROR | /service-workers/service-worker/detached-context.https.html | TypeError: this.curBrowser.messageManager is null
[task 2019-11-13T23:52:26.425Z] 23:52:26 INFO - Traceback (most recent call last):
[task 2019-11-13T23:52:26.426Z] 23:52:26 INFO - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 627, in _run
[task 2019-11-13T23:52:26.426Z] 23:52:26 INFO - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2019-11-13T23:52:26.426Z] 23:52:26 INFO - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 738, in do_testharness
[task 2019-11-13T23:52:26.427Z] 23:52:26 INFO - protocol.marionette.navigate(url)
[task 2019-11-13T23:52:26.428Z] 23:52:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1467, in navigate
[task 2019-11-13T23:52:26.428Z] 23:52:26 INFO - {"url": url})
[task 2019-11-13T23:52:26.429Z] 23:52:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2019-11-13T23:52:26.430Z] 23:52:26 INFO - return func(args, **kwargs)
[task 2019-11-13T23:52:26.430Z] 23:52:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2019-11-13T23:52:26.430Z] 23:52:26 INFO - self._handle_error(err)
[task 2019-11-13T23:52:26.430Z] 23:52:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2019-11-13T23:52:26.431Z] 23:52:26 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2019-11-13T23:52:26.431Z] 23:52:26 INFO - UnknownException: TypeError: this.curBrowser.messageManager is null
[task 2019-11-13T23:52:26.432Z] 23:52:26 INFO - stacktrace:
[task 2019-11-13T23:52:26.432Z] 23:52:26 INFO - GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:367:7
[task 2019-11-13T23:52:26.432Z] 23:52:26 INFO - executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2019-11-13T23:52:26.433Z] 23:52:26 INFO - GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:364:19
[task 2019-11-13T23:52:26.433Z] 23:52:26 INFO - send/<@chrome://marionette/content/proxy.js:194:12
[task 2019-11-13T23:52:26.433Z] 23:52:26 INFO - send@chrome://marionette/content/proxy.js:128:12
[task 2019-11-13T23:52:26.434Z] 23:52:26 INFO - get/<@chrome://marionette/content/proxy.js:41:29
[task 2019-11-13T23:52:26.434Z] 23:52:26 INFO - GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1116:27
[task 2019-11-13T23:52:26.435Z] 23:52:26 INFO - Asyncdespatch@chrome://marionette/content/server.js:305:40
[task 2019-11-13T23:52:26.435Z] 23:52:26 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-11-13T23:52:26.435Z] 23:52:26 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-11-13T23:52:26.436Z] 23:52:26 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-11-13T23:52:26.436Z] 23:52:26 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-11-13T23:52:26.437Z] 23:52:26 INFO -
[task 2019-11-13T23:52:26.437Z] 23:52:26 INFO - TEST-INFO took 325ms
[task 2019-11-13T23:52:26.438Z] 23:52:26 INFO - PID 17448 | [Parent 17448, Gecko_IOThread] WARNING: pipe error (82): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 358
[task 2019-11-13T23:52:26.439Z] 23:52:26 INFO - PID 17448 | [GPU 17489, Chrome_ChildThread] WARNING: pipe error (32): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 358
[task 2019-11-13T23:52:26.460Z] 23:52:26 INFO - PID 17448 | 1573689146446 Marionette INFO Stopped listening on port 54369
[task 2019-11-13T23:52:26.463Z] 23:52:26 INFO - PID 17448 | JavaScript error: chrome://browser/content/browser.js, line 8117: TypeError: browser.messageManager is null
[task 2019-11-13T23:52:26.527Z] 23:52:26 INFO - PID 17448 | WARNING: A blocker encountered an error while we were waiting.
[task 2019-11-13T23:52:26.527Z] 23:52:26 INFO - PID 17448 | Blocker: SessionStore: flushing all windows
[task 2019-11-13T23:52:26.527Z] 23:52:26 INFO - PID 17448 | Phase: quit-application-granted
[task 2019-11-13T23:52:26.527Z] 23:52:26 INFO - PID 17448 | State: {"total":-1,"current":-1}
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | WARNING: TypeError: mm is null
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | WARNING: flush@resource:///modules/sessionstore/TabStateFlusher.jsm:97:5
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:127:28
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:31:36
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | flushAllWindowsAsync@resource:///modules/sessionstore/SessionStore.jsm:1906:50
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | ssi_onQuitApplicationGranted/<@resource:///modules/sessionstore/SessionStore.jsm:1830:32
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | trigger@resource://gre/modules/AsyncShutdown.jsm:725:23
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | _wait@resource://gre/modules/AsyncShutdown.jsm:877:15
[task 2019-11-13T23:52:26.528Z] 23:52:26 INFO - PID 17448 | wait@resource://gre/modules/AsyncShutdown.jsm:858:34
[task 2019-11-13T23:52:26.529Z] 23:52:26 INFO - PID 17448 | observe@resource://gre/modules/AsyncShutdown.jsm:533:10
[task 2019-11-13T23:52:26.531Z] 23:52:26 INFO - PID 17448 | GeckoDriver.prototype.quit@chrome://marionette/content/driver.js:3471:20
[task 2019-11-13T23:52:26.531Z] 23:52:26 INFO - PID 17448 | despatch@chrome://marionette/content/server.js:305:40
[task 2019-11-13T23:52:26.531Z] 23:52:26 INFO - PID 17448 | execute@chrome://marionette/content/server.js:275:16
[task 2019-11-13T23:52:26.535Z] 23:52:26 INFO - PID 17448 | onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-11-13T23:52:26.536Z] 23:52:26 INFO - PID 17448 | onPacket@chrome://marionette/content/server.js:249:9
[task 2019-11-13T23:52:26.536Z] 23:52:26 INFO - PID 17448 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-11-13T23:52:26.593Z] 23:52:26 INFO - PID 17448 | JavaScript error: resource:///modules/sessionstore/TabStateFlusher.jsm, line 97: TypeError: mm is null
[task 2019-11-13T23:52:26.609Z] 23:52:26 INFO - PID 17448 | JavaScript error: chrome://marionette/content/proxy.js, line 222: TypeError: this.browser.driver.dialogObserver is null
[task 2019-11-13T23:52:27.235Z] 23:52:27 INFO - Browser exited with return code 0

Comment 1

[Intermittent Failures Robot]

1 failures in 4207 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=1596306&startday=2019-11-11&endday=2019-11-17&tree=all

Comment 2

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

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• linux1804-64-shippable: 1

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

Comment 4

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=291101376&repo=try&lineNumber=86055

[task 2020-02-29T14:48:33.408Z] 14:48:33     INFO - TEST-START | /service-workers/service-worker/detached-context.https.html
[task 2020-02-29T14:48:33.416Z] 14:48:33     INFO - Closing window 84
[task 2020-02-29T14:48:33.516Z] 14:48:33     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 573
[task 2020-02-29T14:48:33.516Z] 14:48:33     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 56
[task 2020-02-29T14:48:33.516Z] 14:48:33     INFO - PID 9585 | [Child 11147, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-02-29T14:48:33.516Z] 14:48:33     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 573
[task 2020-02-29T14:48:33.517Z] 14:48:33     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 56
[task 2020-02-29T14:48:33.534Z] 14:48:33     INFO - PID 9585 | [Parent 9585, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-02-29T14:48:33.534Z] 14:48:33     INFO - PID 9585 | [Child 11147, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-02-29T14:48:33.534Z] 14:48:33     INFO - PID 9585 | [Parent 9585, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-02-29T14:48:33.534Z] 14:48:33     INFO - PID 9585 | [Parent 9585, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-02-29T14:48:33.534Z] 14:48:33     INFO - PID 9585 | [Parent 9585, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/workspace/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-02-29T14:48:33.603Z] 14:48:33     INFO - PID 9585 | [Child 11107, 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 1435
[task 2020-02-29T14:48:33.603Z] 14:48:33     INFO - PID 9585 | [Child 11107, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1918
[task 2020-02-29T14:48:33.606Z] 14:48:33     INFO - PID 9585 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:33.607Z] 14:48:33     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2020-02-29T14:48:33.629Z] 14:48:33     INFO - PID 9585 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:33.807Z] 14:48:33     INFO - PID 9585 | nsStringStats
[task 2020-02-29T14:48:33.807Z] 14:48:33     INFO - PID 9585 |  => mAllocCount:          13694
[task 2020-02-29T14:48:33.808Z] 14:48:33     INFO - PID 9585 |  => mReallocCount:            0
[task 2020-02-29T14:48:33.808Z] 14:48:33     INFO - PID 9585 |  => mFreeCount:           13694
[task 2020-02-29T14:48:33.808Z] 14:48:33     INFO - PID 9585 |  => mShareCount:          17636
[task 2020-02-29T14:48:33.809Z] 14:48:33     INFO - PID 9585 |  => mAdoptCount:            503
[task 2020-02-29T14:48:33.809Z] 14:48:33     INFO - PID 9585 |  => mAdoptFreeCount:        525
[task 2020-02-29T14:48:33.809Z] 14:48:33     INFO - PID 9585 |  => Process ID: 11107, Thread ID: 140187980576640
[task 2020-02-29T14:48:33.926Z] 14:48:33     INFO - PID 9585 | [Child 11147, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-02-29T14:48:33.962Z] 14:48:33     INFO - PID 9585 | nsStringStats
[task 2020-02-29T14:48:33.963Z] 14:48:33     INFO - PID 9585 |  => mAllocCount:          13407
[task 2020-02-29T14:48:33.963Z] 14:48:33     INFO - PID 9585 |  => mReallocCount:            0
[task 2020-02-29T14:48:33.963Z] 14:48:33     INFO - PID 9585 |  => mFreeCount:           13407
[task 2020-02-29T14:48:33.964Z] 14:48:33     INFO - PID 9585 |  => mShareCount:          17856
[task 2020-02-29T14:48:33.964Z] 14:48:33     INFO - PID 9585 |  => mAdoptCount:            569
[task 2020-02-29T14:48:33.964Z] 14:48:33     INFO - PID 9585 |  => mAdoptFreeCount:        581
[task 2020-02-29T14:48:33.964Z] 14:48:33     INFO - PID 9585 |  => Process ID: 11147, Thread ID: 140663846844288
[task 2020-02-29T14:48:34.026Z] 14:48:34     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 477
[task 2020-02-29T14:48:34.026Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x37012C,name=PContent::Msg_CommitBrowsingContextTransaction) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.026Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.026Z] 14:48:34     INFO - PID 9585 | [Parent 9585, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 477
[task 2020-02-29T14:48:34.026Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002A,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.032Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.032Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x37013A,name=PContent::Msg_SetFocusedBrowsingContext) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.033Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.033Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002A,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E0027,name=PBrowser::Msg_EnableDisableCommands) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E0048,name=PBrowser::Msg_OnStateChange) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.035Z] 14:48:34     INFO - PID 9585 | IPDL protocol error: Handler returned error code!
[task 2020-02-29T14:48:34.039Z] 14:48:34     INFO - PID 9585 | ###!!! [Parent][DispatchAsyncMessage] Error: PWebRenderBridge::Msg_SetDisplayList Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2020-02-29T14:48:34.040Z] 14:48:34     INFO - PID 9585 | ###!!! [Child][MessageChannel] Error: (msgtype=0x3700DC,name=PContent::Msg_FirstIdle) Channel closing: too late to send/recv, messages will be lost
[task 2020-02-29T14:48:34.040Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 2382
[task 2020-02-29T14:48:34.041Z] 14:48:34     INFO - PID 9585 | Marionette threw an error: TypeError: this.curBrowser.messageManager is null
[task 2020-02-29T14:48:34.041Z] 14:48:34     INFO - PID 9585 | GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:371:7
[task 2020-02-29T14:48:34.041Z] 14:48:34     INFO - PID 9585 | executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2020-02-29T14:48:34.041Z] 14:48:34     INFO - PID 9585 | GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:368:19
[task 2020-02-29T14:48:34.041Z] 14:48:34     INFO - PID 9585 | send/<@chrome://marionette/content/proxy.js:194:12
[task 2020-02-29T14:48:34.043Z] 14:48:34     INFO - PID 9585 | send@chrome://marionette/content/proxy.js:128:12
[task 2020-02-29T14:48:34.043Z] 14:48:34     INFO - PID 9585 | get/<@chrome://marionette/content/proxy.js:41:29
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING - Traceback (most recent call last):
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 642, in run_func
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 750, in do_testharness
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -     protocol.marionette.navigate(url)
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1468, in navigate
[task 2020-02-29T14:48:34.047Z] 14:48:34  WARNING -     {"url": url})
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -     return func(*args, **kwargs)
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -     self._handle_error(err)
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING - UnknownException: TypeError: this.curBrowser.messageManager is null
[task 2020-02-29T14:48:34.048Z] 14:48:34  WARNING - stacktrace:
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:371:7
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:368:19
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	send/<@chrome://marionette/content/proxy.js:194:12
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	send@chrome://marionette/content/proxy.js:128:12
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	get/<@chrome://marionette/content/proxy.js:41:29
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 	GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1147:27
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 
[task 2020-02-29T14:48:34.049Z] 14:48:34  WARNING - 
[task 2020-02-29T14:48:34.049Z] 14:48:34     INFO - PID 9585 | GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1147:27
[task 2020-02-29T14:48:34.087Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 477
[task 2020-02-29T14:48:34.088Z] 14:48:34     INFO - PID 9585 | JavaScript error: resource:///actors/AboutReaderChild.jsm, line 95: NS_ERROR_UNEXPECTED:
[task 2020-02-29T14:48:34.088Z] 14:48:34     INFO - PID 9585 | [Child 11179, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 477
[task 2020-02-29T14:48:34.095Z] 14:48:34     INFO - TEST-UNEXPECTED-ERROR | /service-workers/service-worker/detached-context.https.html | TypeError: this.curBrowser.messageManager is null
[task 2020-02-29T14:48:34.095Z] 14:48:34     INFO - Traceback (most recent call last):
[task 2020-02-29T14:48:34.095Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 642, in run_func
[task 2020-02-29T14:48:34.095Z] 14:48:34     INFO -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-02-29T14:48:34.097Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 750, in do_testharness
[task 2020-02-29T14:48:34.097Z] 14:48:34     INFO -     protocol.marionette.navigate(url)
[task 2020-02-29T14:48:34.097Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1468, in navigate
[task 2020-02-29T14:48:34.098Z] 14:48:34     INFO -     {"url": url})
[task 2020-02-29T14:48:34.098Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO -     return func(*args, **kwargs)
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO -     self._handle_error(err)
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO - UnknownException: TypeError: this.curBrowser.messageManager is null
[task 2020-02-29T14:48:34.100Z] 14:48:34     INFO - stacktrace:
[task 2020-02-29T14:48:34.101Z] 14:48:34     INFO - 	GeckoDriver.prototype.sendAsync/<@chrome://marionette/content/driver.js:371:7
[task 2020-02-29T14:48:34.101Z] 14:48:34     INFO - 	executeWhenReady@chrome://marionette/content/browser.js:570:7
[task 2020-02-29T14:48:34.101Z] 14:48:34     INFO - 	GeckoDriver.prototype.sendAsync@chrome://marionette/content/driver.js:368:19
[task 2020-02-29T14:48:34.103Z] 14:48:34     INFO - 	send/<@chrome://marionette/content/proxy.js:194:12
[task 2020-02-29T14:48:34.103Z] 14:48:34     INFO - 	send@chrome://marionette/content/proxy.js:128:12
[task 2020-02-29T14:48:34.103Z] 14:48:34     INFO - 	get/<@chrome://marionette/content/proxy.js:41:29
[task 2020-02-29T14:48:34.103Z] 14:48:34     INFO - 	GeckoDriver.prototype.get@chrome://marionette/content/driver.js:1147:27
[task 2020-02-29T14:48:34.104Z] 14:48:34     INFO - 
[task 2020-02-29T14:48:34.105Z] 14:48:34     INFO - TEST-INFO took 680ms

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1596306&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• linux1804-64-qr: 1

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

Comment 8

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

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