1610111 - Intermittent /service-workers/service-worker/navigation-preload/request-headers.https.html | TypeError: can't access property "addMessageListener", this.mm is null

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-01-18T10:05:38.362Z] 10:05:38     INFO - TEST-START | /service-workers/service-worker/navigation-preload/request-headers.https.html
[task 2020-01-18T10:05:38.362Z] 10:05:38     INFO - Closing window 35
[task 2020-01-18T10:05:38.596Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.598Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x3700B5,name=PContent::Msg_SetURITitle) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.601Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E0001,name=PBrowser::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.601Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x690028,name=PNecko::Msg_RequestContextAfterDOMContentLoaded) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.609Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x370130,name=PContent::Msg_CommitBrowsingContextTransaction) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.610Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002C,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.611Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E002C,name=PBrowser::Msg_SetInputContext) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.611Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E0029,name=PBrowser::Msg_EnableDisableCommands) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.611Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x1E004A,name=PBrowser::Msg_OnStateChange) Channel closing: too late to send/recv, messages will be lost
[task 2020-01-18T10:05:38.611Z] 10:05:38     INFO - PID 27236 | JavaScript error: resource:///actors/AboutReaderChild.jsm, line 95: NS_ERROR_UNEXPECTED:
[task 2020-01-18T10:05:38.611Z] 10:05:38     INFO - PID 27236 | ###!!! [Child][MessageChannel] Error: (msgtype=0x370135,name=PContent::Msg_DetachBrowsingContext) Closed channel: cannot send/recv
[task 2020-01-18T10:05:38.667Z] 10:05:38  WARNING - Traceback (most recent call last):
[task 2020-01-18T10:05:38.667Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 642, in run_func
[task 2020-01-18T10:05:38.668Z] 10:05:38  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-18T10:05:38.668Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 748, in do_testharness
[task 2020-01-18T10:05:38.668Z] 10:05:38  WARNING -     self.protocol.base.set_window(test_window)
[task 2020-01-18T10:05:38.669Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 82, in set_window
[task 2020-01-18T10:05:38.669Z] 10:05:38  WARNING -     self.marionette.switch_to_window(handle)
[task 2020-01-18T10:05:38.672Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1356, in switch_to_window
[task 2020-01-18T10:05:38.672Z] 10:05:38  WARNING -     {"focus": focus, "name": handle, "handle": handle})
[task 2020-01-18T10:05:38.672Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-01-18T10:05:38.672Z] 10:05:38  WARNING -     return func(*args, **kwargs)
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING -     self._handle_error(err)
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING - UnknownException: TypeError: can't access property "addMessageListener", this.mm is null
[task 2020-01-18T10:05:38.673Z] 10:05:38  WARNING - stacktrace:
[task 2020-01-18T10:05:38.674Z] 10:05:38  WARNING - 	WebElementEventTarget@chrome://marionette/content/dom.js:46:5
[task 2020-01-18T10:05:38.674Z] 10:05:38  WARNING - 	switchToTab@chrome://marionette/content/browser.js:511:26
[task 2020-01-18T10:05:38.674Z] 10:05:38  WARNING - 	GeckoDriver.prototype.setWindowHandle@chrome://marionette/content/driver.js:1694:29
[task 2020-01-18T10:05:38.675Z] 10:05:38  WARNING - 	GeckoDriver.prototype.switchToWindow@chrome://marionette/content/driver.js:1602:16
[task 2020-01-18T10:05:38.675Z] 10:05:38  WARNING - 	despatch@chrome://marionette/content/server.js:305:40
[task 2020-01-18T10:05:38.675Z] 10:05:38  WARNING - 	execute@chrome://marionette/content/server.js:275:16
[task 2020-01-18T10:05:38.676Z] 10:05:38  WARNING - 	onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-01-18T10:05:38.676Z] 10:05:38  WARNING - 	onPacket@chrome://marionette/content/server.js:249:9
[task 2020-01-18T10:05:38.677Z] 10:05:38  WARNING - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-01-18T10:05:38.677Z] 10:05:38  WARNING - 
[task 2020-01-18T10:05:38.678Z] 10:05:38  WARNING - 
[task 2020-01-18T10:05:38.678Z] 10:05:38     INFO - PID 27236 | Marionette threw an error: TypeError: can't access property "addMessageListener", this.mm is null
[task 2020-01-18T10:05:38.679Z] 10:05:38     INFO - PID 27236 | WebElementEventTarget@chrome://marionette/content/dom.js:46:5
[task 2020-01-18T10:05:38.679Z] 10:05:38     INFO - PID 27236 | switchToTab@chrome://marionette/content/browser.js:511:26
[task 2020-01-18T10:05:38.679Z] 10:05:38     INFO - PID 27236 | GeckoDriver.prototype.setWindowHandle@chrome://marionette/content/driver.js:1694:29
[task 2020-01-18T10:05:38.679Z] 10:05:38     INFO - PID 27236 | GeckoDriver.prototype.switchToWindow@chrome://marionette/content/driver.js:1602:16
[task 2020-01-18T10:05:38.684Z] 10:05:38     INFO - PID 27236 | despatch@chrome://marionette/content/server.js:305:40
[task 2020-01-18T10:05:38.685Z] 10:05:38     INFO - PID 27236 | execute@chrome://marionette/content/server.js:275:16
[task 2020-01-18T10:05:38.685Z] 10:05:38     INFO - PID 27236 | onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-01-18T10:05:38.685Z] 10:05:38     INFO - PID 27236 | onPacket@chrome://marionette/content/server.js:249:9
[task 2020-01-18T10:05:38.686Z] 10:05:38     INFO - PID 27236 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-01-18T10:05:38.686Z] 10:05:38     INFO - TEST-UNEXPECTED-ERROR | /service-workers/service-worker/navigation-preload/request-headers.https.html | TypeError: can't access property "addMessageListener", this.mm is null
[task 2020-01-18T10:05:38.687Z] 10:05:38     INFO - Traceback (most recent call last):
[task 2020-01-18T10:05:38.687Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 642, in run_func
[task 2020-01-18T10:05:38.687Z] 10:05:38     INFO -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-18T10:05:38.687Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 748, in do_testharness
[task 2020-01-18T10:05:38.687Z] 10:05:38     INFO -     self.protocol.base.set_window(test_window)
[task 2020-01-18T10:05:38.688Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 82, in set_window
[task 2020-01-18T10:05:38.688Z] 10:05:38     INFO -     self.marionette.switch_to_window(handle)
[task 2020-01-18T10:05:38.688Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1356, in switch_to_window
[task 2020-01-18T10:05:38.688Z] 10:05:38     INFO -     {"focus": focus, "name": handle, "handle": handle})
[task 2020-01-18T10:05:38.688Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-01-18T10:05:38.689Z] 10:05:38     INFO -     return func(*args, **kwargs)
[task 2020-01-18T10:05:38.689Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2020-01-18T10:05:38.689Z] 10:05:38     INFO -     self._handle_error(err)
[task 2020-01-18T10:05:38.689Z] 10:05:38     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2020-01-18T10:05:38.690Z] 10:05:38     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-01-18T10:05:38.690Z] 10:05:38     INFO - UnknownException: TypeError: can't access property "addMessageListener", this.mm is null
[task 2020-01-18T10:05:38.690Z] 10:05:38     INFO - stacktrace:
[task 2020-01-18T10:05:38.690Z] 10:05:38     INFO - 	WebElementEventTarget@chrome://marionette/content/dom.js:46:5
[task 2020-01-18T10:05:38.691Z] 10:05:38     INFO - 	switchToTab@chrome://marionette/content/browser.js:511:26
[task 2020-01-18T10:05:38.691Z] 10:05:38     INFO - 	GeckoDriver.prototype.setWindowHandle@chrome://marionette/content/driver.js:1694:29
[task 2020-01-18T10:05:38.691Z] 10:05:38     INFO - 	GeckoDriver.prototype.switchToWindow@chrome://marionette/content/driver.js:1602:16
[task 2020-01-18T10:05:38.692Z] 10:05:38     INFO - 	despatch@chrome://marionette/content/server.js:305:40
[task 2020-01-18T10:05:38.692Z] 10:05:38     INFO - 	execute@chrome://marionette/content/server.js:275:16
[task 2020-01-18T10:05:38.692Z] 10:05:38     INFO - 	onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-01-18T10:05:38.693Z] 10:05:38     INFO - 	onPacket@chrome://marionette/content/server.js:249:9
[task 2020-01-18T10:05:38.693Z] 10:05:38     INFO - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-01-18T10:05:38.694Z] 10:05:38     INFO - 
[task 2020-01-18T10:05:38.694Z] 10:05:38     INFO - TEST-INFO expected TIMEOUT | took 327ms
[task 2020-01-18T10:05:38.715Z] 10:05:38     INFO - PID 27236 | 1579341938706	Marionette	INFO	Stopped listening on port 48825
[task 2020-01-18T10:05:38.737Z] 10:05:38     INFO - PID 27236 | JavaScript error: chrome://browser/content/browser.js, line 8113: TypeError: can't access property "processMessageManager", browser.messageManager is null
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 | WARNING: A blocker encountered an error while we were waiting.
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 |           Blocker:  SessionStore: flushing all windows
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 |           Phase: quit-application-granted
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 |           State: {"total":-1,"current":-1}
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 | WARNING: TypeError: can't access property "sendAsyncMessage", mm is null
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 | WARNING: flush@resource:///modules/sessionstore/TabStateFlusher.jsm:97:5
[task 2020-01-18T10:05:38.792Z] 10:05:38     INFO - PID 27236 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:127:28
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:31:36
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | flushAllWindowsAsync@resource:///modules/sessionstore/SessionStore.jsm:1906:50
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | ssi_onQuitApplicationGranted/<@resource:///modules/sessionstore/SessionStore.jsm:1830:32
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | trigger@resource://gre/modules/AsyncShutdown.jsm:725:23
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | _wait@resource://gre/modules/AsyncShutdown.jsm:877:15
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | wait@resource://gre/modules/AsyncShutdown.jsm:858:34
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | observe@resource://gre/modules/AsyncShutdown.jsm:533:10
[task 2020-01-18T10:05:38.793Z] 10:05:38     INFO - PID 27236 | GeckoDriver.prototype.quit@chrome://marionette/content/driver.js:3502:20
[task 2020-01-18T10:05:38.795Z] 10:05:38     INFO - PID 27236 | despatch@chrome://marionette/content/server.js:305:40
[task 2020-01-18T10:05:38.795Z] 10:05:38     INFO - PID 27236 | execute@chrome://marionette/content/server.js:275:16
[task 2020-01-18T10:05:38.795Z] 10:05:38     INFO - PID 27236 | onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-01-18T10:05:38.796Z] 10:05:38     INFO - PID 27236 | onPacket@chrome://marionette/content/server.js:249:9
[task 2020-01-18T10:05:38.796Z] 10:05:38     INFO - PID 27236 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-01-18T10:05:38.832Z] 10:05:38     INFO - PID 27236 | JavaScript error: resource:///modules/sessionstore/TabStateFlusher.jsm, line 97: TypeError: can't access property "sendAsyncMessage", mm is null
[task 2020-01-18T10:05:39.453Z] 10:05:39     INFO - Browser exited with return code 0

Comment 1

[Intermittent Failures Robot]

2 failures in 3773 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64-shippable: 1
• linux1804-64: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 3

[Intermittent Failures Robot]

2 failures in 2190 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• linux1804-64-qr: 1
• linux1804-64-shippable: 1

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

Comment 4

[Intermittent Failures Robot]

1 failures in 3364 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=1610111&startday=2020-02-03&endday=2020-02-09&tree=all

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:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-shippable-qr: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610111&startday=2020-03-16&endday=2020-03-22&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: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-qr: 1

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

Comment 11

[BugBot [:suhaib / :marco]]

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