1719721 - High frequency /browser/component/browser_agent.js,browser_dispatchKeyEvent.js | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm

Reporter

Description

•

3 years ago

treeherder

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

[task 2021-07-08T17:47:15.613Z] 17:47:15     INFO - TEST-START | remote/cdp/test/browser/io/browser_close.js
[task 2021-07-08T17:47:15.622Z] 17:47:15     INFO - GECKO(1423) | 1625766435621	RemoteAgent	DEBUG	Setting recommended pref browser.contentblocking.introCount to 99
[task 2021-07-08T17:47:15.622Z] 17:47:15     INFO - GECKO(1423) | 1625766435621	RemoteAgent	DEBUG	Setting recommended pref browser.newtabpage.enabled to false
[task 2021-07-08T17:47:15.623Z] 17:47:15     INFO - GECKO(1423) | 1625766435622	RemoteAgent	DEBUG	Setting recommended pref browser.safebrowsing.blockedURIs.enabled to false
[task 2021-07-08T17:47:15.624Z] 17:47:15     INFO - GECKO(1423) | 1625766435624	RemoteAgent	DEBUG	Setting recommended pref browser.safebrowsing.downloads.enabled to false
[task 2021-07-08T17:47:15.626Z] 17:47:15     INFO - GECKO(1423) | 1625766435625	RemoteAgent	DEBUG	Setting recommended pref browser.safebrowsing.passwords.enabled to false
[task 2021-07-08T17:47:15.626Z] 17:47:15     INFO - GECKO(1423) | 1625766435625	RemoteAgent	DEBUG	Setting recommended pref browser.safebrowsing.malware.enabled to false
[task 2021-07-08T17:47:15.628Z] 17:47:15     INFO - GECKO(1423) | 1625766435627	RemoteAgent	DEBUG	Setting recommended pref browser.safebrowsing.phishing.enabled to false
[task 2021-07-08T17:47:15.629Z] 17:47:15     INFO - GECKO(1423) | 1625766435628	RemoteAgent	DEBUG	Setting recommended pref browser.search.update to false
[task 2021-07-08T17:47:15.630Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.closeWindowWithLastTab to false
[task 2021-07-08T17:47:15.630Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.remote.separatePrivilegedContentProcess to false
[task 2021-07-08T17:47:15.630Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnClose to false
[task 2021-07-08T17:47:15.631Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
[task 2021-07-08T17:47:15.631Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnOpen to false
[task 2021-07-08T17:47:15.631Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.toolbars.bookmarks.visibility to never
[task 2021-07-08T17:47:15.632Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.usedOnWindows10.introURL to
[task 2021-07-08T17:47:15.632Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref browser.uitour.enabled to false
[task 2021-07-08T17:47:15.632Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref datareporting.healthreport.logging.consoleEnabled to false
[task 2021-07-08T17:47:15.633Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref datareporting.healthreport.service.enabled to false
[task 2021-07-08T17:47:15.633Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref datareporting.healthreport.service.firstRun to false
[task 2021-07-08T17:47:15.634Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref datareporting.policy.dataSubmissionEnabled to false
[task 2021-07-08T17:47:15.634Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
[task 2021-07-08T17:47:15.634Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref dom.file.createInChild to true
[task 2021-07-08T17:47:15.635Z] 17:47:15     INFO - GECKO(1423) | 1625766435629	RemoteAgent	DEBUG	Setting recommended pref extensions.update.notifyUser to false
[task 2021-07-08T17:47:15.635Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref focusmanager.testmode to true
[task 2021-07-08T17:47:15.635Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref geo.wifi.scan to false
[task 2021-07-08T17:47:15.636Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref network.http.phishy-userpass-length to 255
[task 2021-07-08T17:47:15.636Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref privacy.trackingprotection.enabled to false
[task 2021-07-08T17:47:15.636Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref security.fileuri.strict_origin_policy to false
[task 2021-07-08T17:47:15.637Z] 17:47:15     INFO - GECKO(1423) | 1625766435630	RemoteAgent	DEBUG	Setting recommended pref signon.autofillForms to false
[task 2021-07-08T17:47:15.637Z] 17:47:15     INFO - GECKO(1423) | 1625766435631	RemoteAgent	DEBUG	Setting recommended pref toolkit.startup.max_resumed_crashes to -1
[task 2021-07-08T17:47:15.637Z] 17:47:15     INFO - GECKO(1423) | 1625766435631	RemoteAgent	DEBUG	Setting recommended pref browser.contentblocking.features.standard to -tp,tpPrivate,cookieBehavior0,-cm,-fp
[task 2021-07-08T17:47:15.732Z] 17:47:15     INFO - TEST-INFO | started process screencapture
[task 2021-07-08T17:47:15.850Z] 17:47:15     INFO - TEST-INFO | screencapture: exit 0
[task 2021-07-08T17:47:15.851Z] 17:47:15     INFO - Buffered messages logged at 17:47:15
[task 2021-07-08T17:47:15.851Z] 17:47:15     INFO - Entering test bound fileRemovedAfterClose
[task 2021-07-08T17:47:15.851Z] 17:47:15     INFO - Remote agent started
[task 2021-07-08T17:47:15.852Z] 17:47:15     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'MarionetteEvents'"]
[task 2021-07-08T17:47:15.852Z] 17:47:15     INFO - Buffered messages finished
[task 2021-07-08T17:47:15.853Z] 17:47:15     INFO - TEST-UNEXPECTED-FAIL | remote/cdp/test/browser/io/browser_close.js | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:6204:18
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:528:26
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - createBrowser/b.afterChangeRemoteness@chrome://browser/content/tabbrowser.js:2069:24
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - finishChangeRemoteness@chrome://global/content/elements/browser-custom-element.js:1899:19
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - getInterfaceProxy/get/<@chrome://global/content/customElements.js:696:35
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - 
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - Stack trace:
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:simpletestOnerror:1964
[task 2021-07-08T17:47:15.854Z] 17:47:15     INFO - GECKO(1423) | JavaScript error: resource:///modules/sessionstore/SessionStore.jsm, line 6204: TypeError: can't access property "ownerGlobal", aTab is undefined
[task 2021-07-08T17:47:15.855Z] 17:47:15     INFO - Console message: [JavaScript Error: "TypeError: can't access property "ownerGlobal", aTab is undefined" {file: "resource:///modules/sessionstore/SessionStore.jsm" line: 6204}]
[task 2021-07-08T17:47:15.855Z] 17:47:15     INFO - finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:6204:18
[task 2021-07-08T17:47:15.855Z] 17:47:15     INFO - finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:528:26
[task 2021-07-08T17:47:15.856Z] 17:47:15     INFO - createBrowser/b.afterChangeRemoteness@chrome://browser/content/tabbrowser.js:2069:24
[task 2021-07-08T17:47:15.856Z] 17:47:15     INFO - finishChangeRemoteness@chrome://global/content/elements/browser-custom-element.js:1899:19
[task 2021-07-08T17:47:15.856Z] 17:47:15     INFO - getInterfaceProxy/get/<@chrome://global/content/customElements.js:696:35
[task 2021-07-08T17:47:15.856Z] 17:47:15     INFO - 
[task 2021-07-08T17:47:16.132Z] 17:47:16     INFO - GECKO(1423) | 1625766436131	RemoteAgent	DEBUG	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} accepted
[task 2021-07-08T17:47:16.132Z] 17:47:16     INFO - CDP client instantiated
[task 2021-07-08T17:47:16.133Z] 17:47:16     INFO - GECKO(1423) | 1625766436132	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} -> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2021-07-08T17:47:16.133Z] 17:47:16     INFO - GECKO(1423) | 1625766436133	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.134Z] 17:47:16     INFO - GECKO(1423) | 1625766436133	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"32b36cf5-8414-1748-878a-99dfa46abda3","type":"browser","attached":false}}}
[task 2021-07-08T17:47:16.134Z] 17:47:16     INFO - GECKO(1423) | 1625766436133	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"6c682fab-9128-5b45-a12d-bee2c4b88d7a","type":"page","title":"","url":"about:blank","attached":true,"browserContextId":null}}}
[task 2021-07-08T17:47:16.135Z] 17:47:16     INFO - GECKO(1423) | 1625766436133	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"id":1,"result":{}}
[task 2021-07-08T17:47:16.136Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | All data has been written to file - 
[task 2021-07-08T17:47:16.136Z] 17:47:16     INFO - GECKO(1423) | 1625766436136	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} -> {"id":2,"method":"IO.close","params":{"handle":"05d470e1-eafe-fc49-bb14-dd0c348986b2"}}
[task 2021-07-08T17:47:16.137Z] 17:47:16     INFO - GECKO(1423) | 1625766436136	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"id":2,"result":{}}
[task 2021-07-08T17:47:16.138Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Discarded the temporary backing storage - 
[task 2021-07-08T17:47:16.141Z] 17:47:16     INFO - GECKO(1423) | 1625766436140	RemoteAgent	DEBUG	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} closed
[task 2021-07-08T17:47:16.141Z] 17:47:16     INFO - GECKO(1423) | 1625766436140	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetDestroyed","params":{"targetId":"6c682fab-9128-5b45-a12d-bee2c4b88d7a"}}
[task 2021-07-08T17:47:16.146Z] 17:47:16     INFO - CDP client closed
[task 2021-07-08T17:47:16.147Z] 17:47:16     INFO - Leaving test bound fileRemovedAfterClose
[task 2021-07-08T17:47:16.147Z] 17:47:16     INFO - Entering test bound unknownHandle
[task 2021-07-08T17:47:16.211Z] 17:47:16     INFO - GECKO(1423) | 1625766436210	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"0ec75f37-60cf-0143-aa6a-c28d74b947d7","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.566Z] 17:47:16     INFO - GECKO(1423) | 1625766436565	RemoteAgent	DEBUG	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} accepted
[task 2021-07-08T17:47:16.566Z] 17:47:16     INFO - CDP client instantiated
[task 2021-07-08T17:47:16.567Z] 17:47:16     INFO - GECKO(1423) | 1625766436566	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} -> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2021-07-08T17:47:16.568Z] 17:47:16     INFO - GECKO(1423) | 1625766436566	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.568Z] 17:47:16     INFO - GECKO(1423) | 1625766436566	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"32b36cf5-8414-1748-878a-99dfa46abda3","type":"browser","attached":false}}}
[task 2021-07-08T17:47:16.569Z] 17:47:16     INFO - GECKO(1423) | 1625766436567	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"0ec75f37-60cf-0143-aa6a-c28d74b947d7","type":"page","title":"","url":"about:blank","attached":true,"browserContextId":null}}}
[task 2021-07-08T17:47:16.569Z] 17:47:16     INFO - GECKO(1423) | 1625766436567	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"id":1,"result":{}}
[task 2021-07-08T17:47:16.570Z] 17:47:16     INFO - GECKO(1423) | 1625766436567	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} -> {"id":2,"method":"IO.close","params":{"handle":"1000000"}}
[task 2021-07-08T17:47:16.571Z] 17:47:16     INFO - GECKO(1423) | 1625766436568	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"id":2,"error":{"message":"Invalid stream handle","data":"get@chrome://remote/content/cdp/StreamRegistry.jsm:104:13\nremove@ ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.571Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.572Z] 17:47:16     INFO - GECKO(1423) | 1625766436571	RemoteAgent	DEBUG	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} closed
[task 2021-07-08T17:47:16.572Z] 17:47:16     INFO - GECKO(1423) | 1625766436571	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetDestroyed","params":{"targetId":"0ec75f37-60cf-0143-aa6a-c28d74b947d7"}}
[task 2021-07-08T17:47:16.573Z] 17:47:16     INFO - GECKO(1423) | 1625766436571	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetDestroyed","params":{"targetId":"0ec75f37-60cf-0143-aa6a-c28d74b947d7"}}
[task 2021-07-08T17:47:16.577Z] 17:47:16     INFO - CDP client closed
[task 2021-07-08T17:47:16.578Z] 17:47:16     INFO - Leaving test bound unknownHandle
[task 2021-07-08T17:47:16.578Z] 17:47:16     INFO - Entering test bound invalidHandleTypes
[task 2021-07-08T17:47:16.601Z] 17:47:16     INFO - GECKO(1423) | 1625766436599	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.601Z] 17:47:16     INFO - GECKO(1423) | 1625766436599	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.946Z] 17:47:16     INFO - GECKO(1423) | 1625766436945	RemoteAgent	DEBUG	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} accepted
[task 2021-07-08T17:47:16.946Z] 17:47:16     INFO - CDP client instantiated
[task 2021-07-08T17:47:16.947Z] 17:47:16     INFO - GECKO(1423) | 1625766436946	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2021-07-08T17:47:16.948Z] 17:47:16     INFO - GECKO(1423) | 1625766436946	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.948Z] 17:47:16     INFO - GECKO(1423) | 1625766436946	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"32b36cf5-8414-1748-878a-99dfa46abda3","type":"browser","attached":false}}}
[task 2021-07-08T17:47:16.949Z] 17:47:16     INFO - GECKO(1423) | 1625766436946	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc","type":"page","title":"","url":"about:blank","attached":true,"browserContextId":null}}}
[task 2021-07-08T17:47:16.950Z] 17:47:16     INFO - GECKO(1423) | 1625766436946	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":1,"result":{}}
[task 2021-07-08T17:47:16.951Z] 17:47:16     INFO - GECKO(1423) | 1625766436947	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":2,"method":"IO.close","params":{"handle":null}}
[task 2021-07-08T17:47:16.951Z] 17:47:16     INFO - GECKO(1423) | 1625766436947	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":2,"error":{"message":"handle: string value expected","data":"close@chrome://remote/content/cdp/domains/parent/IO.jsm:39 ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.952Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.953Z] 17:47:16     INFO - GECKO(1423) | 1625766436948	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":3,"method":"IO.close","params":{"handle":true}}
[task 2021-07-08T17:47:16.953Z] 17:47:16     INFO - GECKO(1423) | 1625766436948	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":3,"error":{"message":"handle: string value expected","data":"close@chrome://remote/content/cdp/domains/parent/IO.jsm:39 ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.954Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.955Z] 17:47:16     INFO - GECKO(1423) | 1625766436949	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":4,"method":"IO.close","params":{"handle":1}}
[task 2021-07-08T17:47:16.956Z] 17:47:16     INFO - GECKO(1423) | 1625766436949	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":4,"error":{"message":"handle: string value expected","data":"close@chrome://remote/content/cdp/domains/parent/IO.jsm:39 ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.956Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.957Z] 17:47:16     INFO - GECKO(1423) | 1625766436950	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":5,"method":"IO.close","params":{"handle":[]}}
[task 2021-07-08T17:47:16.957Z] 17:47:16     INFO - GECKO(1423) | 1625766436950	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":5,"error":{"message":"handle: string value expected","data":"close@chrome://remote/content/cdp/domains/parent/IO.jsm:39 ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.958Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.959Z] 17:47:16     INFO - GECKO(1423) | 1625766436951	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} -> {"id":6,"method":"IO.close","params":{"handle":{}}}
[task 2021-07-08T17:47:16.960Z] 17:47:16     INFO - GECKO(1423) | 1625766436951	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"id":6,"error":{"message":"handle: string value expected","data":"close@chrome://remote/content/cdp/domains/parent/IO.jsm:39 ... ote/content/server/WebSocketTransport.jsm:89:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14\n"}}
[task 2021-07-08T17:47:16.960Z] 17:47:16     INFO - TEST-PASS | remote/cdp/test/browser/io/browser_close.js | Error contains expected message - 
[task 2021-07-08T17:47:16.961Z] 17:47:16     INFO - GECKO(1423) | 1625766436955	RemoteAgent	DEBUG	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} closed
[task 2021-07-08T17:47:16.961Z] 17:47:16     INFO - GECKO(1423) | 1625766436955	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetDestroyed","params":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc"}}
[task 2021-07-08T17:47:16.962Z] 17:47:16     INFO - GECKO(1423) | 1625766436955	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetDestroyed","params":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc"}}
[task 2021-07-08T17:47:16.962Z] 17:47:16     INFO - GECKO(1423) | 1625766436955	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetDestroyed","params":{"targetId":"6c102cba-c410-3249-89dc-c330773431fc"}}
[task 2021-07-08T17:47:16.963Z] 17:47:16     INFO - CDP client closed
[task 2021-07-08T17:47:16.964Z] 17:47:16     INFO - Leaving test bound invalidHandleTypes
[task 2021-07-08T17:47:16.966Z] 17:47:16     INFO - GECKO(1423) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-07-08T17:47:16.967Z] 17:47:16     INFO - GECKO(1423) | MEMORY STAT | vsize 7066MB | residentFast 271MB | heapAllocated 126MB
[task 2021-07-08T17:47:16.967Z] 17:47:16     INFO - TEST-OK | remote/cdp/test/browser/io/browser_close.js | took 1353ms
[task 2021-07-08T17:47:16.970Z] 17:47:16     INFO - GECKO(1423) | 1625766436968	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d3d01188-2441-e44f-9723-f9881b59e79c","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.971Z] 17:47:16     INFO - GECKO(1423) | 1625766436968	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d3d01188-2441-e44f-9723-f9881b59e79c","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.972Z] 17:47:16     INFO - GECKO(1423) | 1625766436969	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d3d01188-2441-e44f-9723-f9881b59e79c","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-07-08T17:47:16.974Z] 17:47:16     INFO - GECKO(1423) | 1625766436973	CDP	TRACE	CDPConnection {c1257f75-2700-9c4b-aa1c-fed5a3a0b24e} <- {"method":"Target.targetDestroyed","params":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43"}}
[task 2021-07-08T17:47:16.975Z] 17:47:16     INFO - GECKO(1423) | 1625766436973	CDP	TRACE	CDPConnection {4b5d12be-e21c-1241-b1a3-7f2360bc14d0} <- {"method":"Target.targetDestroyed","params":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43"}}
[task 2021-07-08T17:47:16.976Z] 17:47:16     INFO - GECKO(1423) | 1625766436973	CDP	TRACE	CDPConnection {a2130825-750b-aa41-aa6d-b5c6876ac3c2} <- {"method":"Target.targetDestroyed","params":{"targetId":"9eb46167-3183-6c49-a450-7bc2174f9a43"}}
[task 2021-07-08T17:47:16.976Z] 17:47:16     INFO - checking window state
[task 2021-07-08T17:47:16.981Z] 17:47:16     INFO - TEST-START | remote/cdp/test/browser/io/browser_read.js

Alexandru Michis [:malexandru]

Comment 1

•

3 years ago

Looks to be caused by one of these bugs:
https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=linux%2C18.04%2Cx64%2Cccov%2Copt%2Cmochitests%2Ctest-linux1804-64-ccov%2Fopt-mochitest-remote-e10s%2Cremote&tochange=27cbb13d5199bec57cab3a979f21f595a3cb8bc3&fromchange=1f558a68ac6ed78b9c4a1b36445d6e6013a06ca4&selectedTaskRun=D3p1536QRqSKDmpPiMDnRg.0

Julian, could you please take a look?

Flags: needinfo?(jdescottes)

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Updated

•

3 years ago

status-firefox90: --- → unaffected

status-firefox91: --- → affected

Keywords: regression, regressionwindow-wanted

Whiteboard: [webdriver:triage]

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Updated

•

3 years ago

Summary: High frequency remote/cdp/test/browser/io/browser_close.js | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:6204:18 → High frequency remote/cdp/test/browser/<random> | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:6204:18

Julian Descottes [:jdescottes]

Assignee

Comment 4

•

3 years ago

I am looking at this right now. Keeping the ni.

Andreea Pavel [:apavel]

Updated

•

3 years ago

Summary: High frequency remote/cdp/test/browser/<random> | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm:6204:18 → High frequency /browser/component/browser_agent.js,browser_dispatchKeyEvent.js | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm

Julian Descottes [:jdescottes]

Assignee

Comment 7

•

3 years ago

I can't reproduce this locally for now, so I have a few try pushes in progress. On try I cannot get it on regular macos builds, so it seems to only be happening on Shippable.

I can imagine two reasons for the intermittent:

either there is a common preference which is triggering the failure. If this is the case, we might just identify it and move it to the marionette specific preferences. The failures seem related to restoring a tab during session restore, so it might be a preference related to tab management or to sessions.
or additional logging is for some reason disturbing the harness. We are now falling back on marionette.log.level, even for CDP logs. However, the mochitest test harness forces marionette.log.level to Trace (https://searchfox.org/mozilla-central/rev/183b0cfc30f2d40f818a08cbea960f6119e2d196/testing/mochitest/runtests.py#2113). This leads to a lot more logs during remote/cdp/test/browser/ . Regardless of what is the actual cause here, we might restrict the usage of marionette.log.level to MARIONETTE and REMOTE_AGENT loggers, since we introduced separate loggers anyway.

Julian Descottes [:jdescottes]

Assignee

Updated

•

3 years ago

Assignee: nobody → jdescottes

Status: NEW → ASSIGNED

Flags: needinfo?(jdescottes)

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Comment 8

•

3 years ago

There are some more consumers of marionette.log.level that would have to be updated for remote.log.level. It's fine when this is causing more logging output for remote/cdp/test/browser because it is expected that way. It's more like a bug nowadays that we don't get the remote agent trace logs.

https://searchfox.org/mozilla-central/search?q=marionette.log.level&path=

Maybe the underlying problem here is related to bug 1719667? Maybe its landing will fix it?

Julian Descottes [:jdescottes]

Assignee

Comment 9

•

3 years ago

According to my try pushes, Bug 1719667 does fix the issue:

Status: ASSIGNED → RESOLVED

Closed: 3 years ago

Resolution: --- → DUPLICATE

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Comment 10

•

3 years ago

Given that this is a regression bug lets not mark it as duplicate. We will mark as fixed by bug 1719667 once landed.

Status: RESOLVED → REOPENED

Depends on: 1719667

Keywords: regressionwindow-wanted

Regressed by: 1693803

Resolution: DUPLICATE → ---

Julian Descottes [:jdescottes]

Assignee

Updated

•

3 years ago

Assignee: jdescottes → nobody

Julian Descottes [:jdescottes]

Assignee

Updated

•

3 years ago

Status: REOPENED → RESOLVED

Closed: 3 years ago → 3 years ago

Resolution: --- → DUPLICATE

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Comment 12

•

3 years ago

Again this is a regression, and as such should be marked as fixed. See my comment 10.

Assignee: nobody → jdescottes

status-firefox91: affected → fixed

Resolution: DUPLICATE → FIXED

Target Milestone: --- → 91 Branch

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Updated

•

3 years ago

Whiteboard: [webdriver:triage] → \

Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)

Updated

•

3 years ago

Whiteboard: \

Bugzilla

Quick Search

High frequency /browser/component/browser_agent.js,browser_dispatchKeyEvent.js | uncaught exception - TypeError: can't access property "ownerGlobal", aTab is undefined at finishTabRemotenessChange@resource:///modules/sessionstore/SessionStore.jsm

Categories

(Remote Protocol :: CDP, defect, P5)

Tracking

(firefox90 unaffected, firefox91 fixed)

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Crash Data

Security

(public)

User Story

Description

Comment 1

Updated

Comment 2

Updated

Comment 4

Updated

Comment 7

Updated

Comment 8

Comment 9

Comment 10

Updated

Updated

Comment 12

Comment 13

Updated

Updated