Closed Bug 1722121 Opened 4 years ago Closed 4 years ago

Intermittent devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | A promise chain failed to handle a rejection: Can not send request 'getParentBrowsingContextID' because front 'watcher' is already destroyed. - stac

Tracking

(firefox-esr78 unaffected, firefox90 unaffected, firefox91 unaffected, firefox92 affected)

Status:

RESOLVED INCOMPLETE

Tracking Flags:

Tracking

Status

firefox-esr78

---

unaffected

firefox90

---

unaffected

firefox91

---

unaffected

firefox92

---

affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Treeherder Bug Filer

Reporter

Description

•

4 years ago

treeherder

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

[task 2021-07-23T23:38:50.403Z] 23:38:50     INFO - TEST-START | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js
[task 2021-07-23T23:38:52.058Z] 23:38:52     INFO - GECKO(15398) | DBG-SERVER threw an exception: TypeError: thread is null
[task 2021-07-23T23:38:52.059Z] 23:38:52     INFO - GECKO(15398) | Stack: initialize@resource://devtools/server/actors/source.js:89:5
[task 2021-07-23T23:38:52.060Z] 23:38:52     INFO - GECKO(15398) | cls@resource://devtools/shared/protocol/Actor.js:249:25
[task 2021-07-23T23:38:52.061Z] 23:38:52     INFO - GECKO(15398) | createSourceActor@resource://devtools/server/actors/utils/sources-manager.js:103:19
[task 2021-07-23T23:38:52.062Z] 23:38:52     INFO - GECKO(15398) | _addSource@resource://devtools/server/actors/thread.js:2047:41
[task 2021-07-23T23:38:52.062Z] 23:38:52     INFO - GECKO(15398) | onNewScript@resource://devtools/server/actors/thread.js:1982:10
[task 2021-07-23T23:38:52.064Z] 23:38:52     INFO - GECKO(15398) | Line: 89, column: 5
[task 2021-07-23T23:38:52.075Z] 23:38:52     INFO - GECKO(15398) | DBG-SERVER threw an exception: TypeError: thread is null
[task 2021-07-23T23:38:52.076Z] 23:38:52     INFO - GECKO(15398) | Stack: initialize@resource://devtools/server/actors/source.js:89:5
[task 2021-07-23T23:38:52.078Z] 23:38:52     INFO - GECKO(15398) | cls@resource://devtools/shared/protocol/Actor.js:249:25
[task 2021-07-23T23:38:52.079Z] 23:38:52     INFO - GECKO(15398) | createSourceActor@resource://devtools/server/actors/utils/sources-manager.js:103:19
[task 2021-07-23T23:38:52.080Z] 23:38:52     INFO - GECKO(15398) | _addSource@resource://devtools/server/actors/thread.js:2047:41
[task 2021-07-23T23:38:52.080Z] 23:38:52     INFO - GECKO(15398) | onNewScript@resource://devtools/server/actors/thread.js:1982:10
[task 2021-07-23T23:38:52.081Z] 23:38:52     INFO - GECKO(15398) | @resource://devtools/server/startup/worker.js:48:14
[task 2021-07-23T23:38:52.081Z] 23:38:52     INFO - GECKO(15398) | Line: 89, column: 5
[task 2021-07-23T23:38:52.082Z] 23:38:52     INFO - GECKO(15398) | DBG-SERVER threw an exception: TypeError: thread is null
[task 2021-07-23T23:38:52.083Z] 23:38:52     INFO - GECKO(15398) | Stack: initialize@resource://devtools/server/actors/source.js:89:5
[task 2021-07-23T23:38:52.083Z] 23:38:52     INFO - GECKO(15398) | cls@resource://devtools/shared/protocol/Actor.js:249:25
[task 2021-07-23T23:38:52.084Z] 23:38:52     INFO - GECKO(15398) | createSourceActor@resource://devtools/server/actors/utils/sources-manager.js:103:19
[task 2021-07-23T23:38:52.085Z] 23:38:52     INFO - GECKO(15398) | _addSource@resource://devtools/server/actors/thread.js:2047:41
[task 2021-07-23T23:38:52.085Z] 23:38:52     INFO - GECKO(15398) | onNewScript@resource://devtools/server/actors/thread.js:1982:10
[task 2021-07-23T23:38:52.086Z] 23:38:52     INFO - GECKO(15398) | Line: 89, column: 5
[task 2021-07-23T23:38:52.087Z] 23:38:52     INFO - GECKO(15398) | DBG-SERVER threw an exception: TypeError: thread is null
[task 2021-07-23T23:38:52.087Z] 23:38:52     INFO - GECKO(15398) | Stack: initialize@resource://devtools/server/actors/source.js:89:5
[task 2021-07-23T23:38:52.088Z] 23:38:52     INFO - GECKO(15398) | cls@resource://devtools/shared/protocol/Actor.js:249:25
[task 2021-07-23T23:38:52.088Z] 23:38:52     INFO - GECKO(15398) | createSourceActor@resource://devtools/server/actors/utils/sources-manager.js:103:19
[task 2021-07-23T23:38:52.090Z] 23:38:52     INFO - GECKO(15398) | _addSource@resource://devtools/server/actors/thread.js:2047:41
[task 2021-07-23T23:38:52.090Z] 23:38:52     INFO - GECKO(15398) | onNewScript@resource://devtools/server/actors/thread.js:1982:10
[task 2021-07-23T23:38:52.091Z] 23:38:52     INFO - GECKO(15398) | @resource://devtools/server/startup/worker.js:48:14
[task 2021-07-23T23:38:52.091Z] 23:38:52     INFO - GECKO(15398) | Line: 89, column: 5
[task 2021-07-23T23:38:52.402Z] 23:38:52     INFO - GECKO(15398) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-07-23T23:38:52.404Z] 23:38:52     INFO - GECKO(15398) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-07-23T23:38:52.407Z] 23:38:52     INFO - GECKO(15398) | console.error: "Error while calling actor 'tabDescriptor's method 'getTarget'" "Tab destroyed while performing a TabDescriptorActor update"
[task 2021-07-23T23:38:52.417Z] 23:38:52     INFO - GECKO(15398) | console.log: "Request to connect to TabDescriptor \"undefined\" failed: Error: Connection closed, pending request to server0.conn7.tabDescriptor1, type getTarget failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:289:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ngetTarget/this._targetFrontPromise<@resource://devtools/client/fronts/descriptors/tab.js:245:40\ngetTarget@resource://devtools/client/fronts/descriptors/tab.js:257:7\nonLocalTabRemotenessChange@resource://devtools/shared/commands/target/target-command.js:749:50\nAsync*_emit@resource://devtools/shared/event-emitter.js:244:34\nemit@resource://devtools/shared/event-emitter.js:190:18\nemit@resource://devtools/shared/event-emitter.js:342:18\n_onRemotenessChange@resource://devtools/client/fronts/descriptors/tab.js:290:10\n_handleTabEvent@resource://devtools/client/fronts/descriptors/tab.js:277:14\ndidChange@chrome://browser/content/tabbrowser.js:6080:15\nfinishChangeRemoteness@chrome://global/content/elements/browser-custom-element.js:1895:12\ngetInterfaceProxy/get/<@chrome://global/content/customElements.js:696:35\n"
[task 2021-07-23T23:38:52.420Z] 23:38:52     INFO - GECKO(15398) | console.warn: "Couldn't get the target for descriptor null"
[task 2021-07-23T23:38:52.545Z] 23:38:52     INFO - GECKO(15398) | JavaScript error: resource://devtools/shared/protocol/Front/FrontClassWithSpec.js, line 28: Error: Can not send request 'getParentBrowsingContextID' because front 'watcher' is already destroyed.
[task 2021-07-23T23:38:52.602Z] 23:38:52     INFO - TEST-INFO | started process screentopng
[task 2021-07-23T23:38:53.184Z] 23:38:53     INFO - TEST-INFO | screentopng: exit 0
[task 2021-07-23T23:38:53.185Z] 23:38:53     INFO - Buffered messages logged at 23:38:50
[task 2021-07-23T23:38:53.186Z] 23:38:53     INFO - Entering test bound 
[task 2021-07-23T23:38:53.187Z] 23:38:53     INFO - Adding a new tab with URL: https://example.com/browser/devtools/shared/commands/target/tests/fission_document.html?id=first-untargetted-tab&noServiceWorker
[task 2021-07-23T23:38:53.187Z] 23:38:53     INFO - Buffered messages logged at 23:38:51
[task 2021-07-23T23:38:53.188Z] 23:38:53     INFO - Tab added and finished loading
[task 2021-07-23T23:38:53.189Z] 23:38:53     INFO - Adding a new tab with URL: https://example.com/browser/devtools/shared/commands/target/tests/fission_document.html?id=second-untargetted-tab&noServiceWorker
[task 2021-07-23T23:38:53.190Z] 23:38:53     INFO - Tab added and finished loading
[task 2021-07-23T23:38:53.190Z] 23:38:53     INFO - Test bfcache navigations
[task 2021-07-23T23:38:53.195Z] 23:38:53     INFO - Adding a new tab with URL: https://example.com/browser/devtools/shared/commands/target/tests/fission_document.html?&noServiceWorker
[task 2021-07-23T23:38:53.195Z] 23:38:53     INFO - Tab added and finished loading
[task 2021-07-23T23:38:53.195Z] 23:38:53     INFO - Assert that watchTargets will call the onAvailable callback for existing dedicated workers
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - onAvailable called for https://example.com/browser/devtools/shared/commands/target/tests/test_worker.js#simple-worker
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - Handled 1 new targets
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - onAvailable called for test_worker.js#simple-worker-in-iframe
[task 2021-07-23T23:38:53.196Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.197Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.197Z] 23:38:53     INFO - Handled 2 new targets
[task 2021-07-23T23:38:53.198Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | watchTargets retrieved 2 workers… - 
[task 2021-07-23T23:38:53.200Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | …the dedicated worker in main page, which is the same front we received from getAllTargets - 
[task 2021-07-23T23:38:53.201Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | …the dedicated worker in iframe, which is the same front we received from getAllTargets - 
[task 2021-07-23T23:38:53.202Z] 23:38:53     INFO - Check that navigating away does destroy all targets
[task 2021-07-23T23:38:53.203Z] 23:38:53     INFO - Buffered messages logged at 23:38:52
[task 2021-07-23T23:38:53.204Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.205Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.206Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.207Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.208Z] 23:38:53     INFO - Navigate back to the first page
[task 2021-07-23T23:38:53.209Z] 23:38:53     INFO - onAvailable called for https://example.com/browser/devtools/shared/commands/target/tests/test_worker.js#simple-worker
[task 2021-07-23T23:38:53.210Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.211Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.214Z] 23:38:53     INFO - Handled 3 new targets
[task 2021-07-23T23:38:53.215Z] 23:38:53     INFO - onAvailable called for test_worker.js#simple-worker-in-iframe
[task 2021-07-23T23:38:53.216Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | We are only notified about worker targets - 
[task 2021-07-23T23:38:53.217Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The workers are never top level - 
[task 2021-07-23T23:38:53.217Z] 23:38:53     INFO - Handled 4 new targets
[task 2021-07-23T23:38:53.218Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The target list handled the worker created from the BF Cache - 
[task 2021-07-23T23:38:53.219Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The target list handled the worker created in the iframe from the BF Cache - 
[task 2021-07-23T23:38:53.219Z] 23:38:53     INFO - Leaving test bound 
[task 2021-07-23T23:38:53.220Z] 23:38:53     INFO - Removing tab.
[task 2021-07-23T23:38:53.221Z] 23:38:53     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.221Z] 23:38:53     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.222Z] 23:38:53     INFO - Tab removed and finished closing
[task 2021-07-23T23:38:53.223Z] 23:38:53     INFO - Removing tab.
[task 2021-07-23T23:38:53.223Z] 23:38:53     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.224Z] 23:38:53     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.225Z] 23:38:53     INFO - Tab removed and finished closing
[task 2021-07-23T23:38:53.225Z] 23:38:53     INFO - Removing tab.
[task 2021-07-23T23:38:53.226Z] 23:38:53     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.227Z] 23:38:53     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-07-23T23:38:53.227Z] 23:38:53     INFO - Tab removed and finished closing
[task 2021-07-23T23:38:53.228Z] 23:38:53     INFO - Console message: [JavaScript Error: "Error: Can not send request 'getParentBrowsingContextID' because front 'watcher' is already destroyed." {file: "resource://devtools/shared/protocol/Front/FrontClassWithSpec.js" line: 28}]
[task 2021-07-23T23:38:53.229Z] 23:38:53     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-07-23T23:38:53.229Z] 23:38:53     INFO - Buffered messages finished
[task 2021-07-23T23:38:53.232Z] 23:38:53     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | A promise chain failed to handle a rejection: Can not send request 'getParentBrowsingContextID' because front 'watcher' is already destroyed. - stack: generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:28:15
[task 2021-07-23T23:38:53.232Z] 23:38:53     INFO - getBrowsingContextTarget@resource://devtools/client/fronts/watcher.js:155:48
[task 2021-07-23T23:38:53.233Z] 23:38:53     INFO - async*_getTargetForWatcherResource@resource://devtools/shared/commands/resource/resource-command.js:834:30
[task 2021-07-23T23:38:53.234Z] 23:38:53     INFO - _onResourceAvailable@resource://devtools/shared/commands/resource/resource-command.js:572:34
[task 2021-07-23T23:38:53.234Z] 23:38:53     INFO - _emit@resource://devtools/shared/event-emitter.js:244:34
[task 2021-07-23T23:38:53.235Z] 23:38:53     INFO - emit@resource://devtools/shared/event-emitter.js:190:18
[task 2021-07-23T23:38:53.236Z] 23:38:53     INFO - emit@resource://devtools/shared/event-emitter.js:342:18
[task 2021-07-23T23:38:53.236Z] 23:38:53     INFO - onPacket@resource://devtools/shared/protocol/Front.js:333:13
[task 2021-07-23T23:38:53.240Z] 23:38:53     INFO - onPacket@resource://devtools/client/devtools-client.js:482:13
[task 2021-07-23T23:38:53.240Z] 23:38:53     INFO - send/<@resource://devtools/shared/transport/local-transport.js:68:25
[task 2021-07-23T23:38:53.240Z] 23:38:53     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-07-23T23:38:53.240Z] 23:38:53     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:54:21
[task 2021-07-23T23:38:53.240Z] 23:38:53     INFO - send@resource://devtools/shared/transport/local-transport.js:56:21
[task 2021-07-23T23:38:53.241Z] 23:38:53     INFO - send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-07-23T23:38:53.241Z] 23:38:53     INFO - _sendEvent@resource://devtools/shared/protocol/Actor.js:72:15
[task 2021-07-23T23:38:53.241Z] 23:38:53     INFO - initialize/<@resource://devtools/shared/protocol/Actor.js:46:16
[task 2021-07-23T23:38:53.241Z] 23:38:53     INFO - _emit@resource://devtools/shared/event-emitter.js:244:34
[task 2021-07-23T23:38:53.241Z] 23:38:53     INFO - emit@resource://devtools/shared/event-emitter.js:190:18
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - emit@resource://devtools/shared/event-emitter.js:342:18
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - _emitResourcesForm@resource://devtools/server/actors/watcher.js:282:10
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - notifyResourceAvailable@resource://devtools/server/actors/watcher.js:263:10
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - onStateChange@resource://devtools/server/actors/resources/parent-process-document-event.js:94:12
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - Rejection date: Fri Jul 23 2021 23:38:52 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 285
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - Stack trace:
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:285
[task 2021-07-23T23:38:53.242Z] 23:38:53     INFO - chrome://mochikit/content/browser-test.js:nextTest:625
[task 2021-07-23T23:38:53.243Z] 23:38:53     INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1545
[task 2021-07-23T23:38:53.243Z] 23:38:53     INFO - chrome://mochikit/content/browser-test.js:run:1460
[task 2021-07-23T23:38:53.243Z] 23:38:53     INFO - GECKO(15398) | MEMORY STAT | vsize 3265MB | residentFast 477MB | heapAllocated 213MB
[task 2021-07-23T23:38:53.243Z] 23:38:53     INFO - TEST-OK | devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | took 2245ms
[task 2021-07-23T23:38:53.243Z] 23:38:53     INFO - checking window state

Comment hidden (Intermittent Failures Robot)

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

4 years ago

status-firefox90: --- → unaffected

status-firefox91: --- → unaffected

status-firefox92: --- → affected

status-firefox-esr78: --- → unaffected

Keywords: regression

Regressed by: 1691588

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 3

•

4 years ago

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

Status: NEW → RESOLVED

Closed: 4 years ago

Resolution: --- → INCOMPLETE

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent devtools/shared/commands/target/tests/browser_target_list_tab_workers_bfcache_navigation.js | A promise chain failed to handle a rejection: Can not send request 'getParentBrowsingContextID' because front 'watcher' is already destroyed. - stac

Categories

(DevTools :: General, defect, P5)

Tracking

(firefox-esr78 unaffected, firefox90 unaffected, firefox91 unaffected, firefox92 affected)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Crash Data

Security

(public)

User Story

Description

Comment 1

Updated

Updated

Comment 2

Comment 3