Closed Bug 1494419 Opened 7 years ago Closed 7 years ago

Intermittent devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out -

Categories

(DevTools :: about:debugging, defect, P3)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: cbrindusan [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=201705713&repo=autoland https://queue.taskcluster.net/v1/task/QlF3aN4FRdeTyP0HrFyaQA/runs/0/artifacts/public/logs/live_backing.log [task 2018-09-26T16:07:13.490Z] 16:07:13 INFO - TEST-START | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js [task 2018-09-26T16:07:13.559Z] 16:07:13 INFO - GECKO(2722) | ++DOCSHELL 0x7f5ae607b800 == 10 [pid = 2722] [id = {fe303567-e2c1-43b6-a0e7-efc86b1b5f35}] [task 2018-09-26T16:07:13.560Z] 16:07:13 INFO - GECKO(2722) | ++DOMWINDOW == 26 (0x7f5aeb0daa00) [pid = 2722] [serial = 26] [outer = (nil)] [task 2018-09-26T16:07:13.561Z] 16:07:13 INFO - GECKO(2722) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties [task 2018-09-26T16:07:13.619Z] 16:07:13 INFO - GECKO(2722) | ++DOMWINDOW == 27 (0x7f5ae6ba2000) [pid = 2722] [serial = 27] [outer = 0x7f5aeb0daa00] [task 2018-09-26T16:07:13.819Z] 16:07:13 INFO - GECKO(2722) | ++DOCSHELL 0x7f12c83c8800 == 1 [pid = 2885] [id = {4418e783-5acd-4ef1-8f1c-16934c43ee1a}] [task 2018-09-26T16:07:13.887Z] 16:07:13 INFO - GECKO(2722) | ++DOMWINDOW == 28 (0x7f5ae99c2c00) [pid = 2722] [serial = 28] [outer = 0x7f5aeb0daa00] [task 2018-09-26T16:07:13.916Z] 16:07:13 INFO - GECKO(2722) | ++DOMWINDOW == 1 (0x7f12df357e00) [pid = 2885] [serial = 1] [outer = (nil)] [task 2018-09-26T16:07:14.058Z] 16:07:14 INFO - GECKO(2722) | ++DOMWINDOW == 2 (0x7f12c9135800) [pid = 2885] [serial = 2] [outer = 0x7f12df357e00] [task 2018-09-26T16:07:14.660Z] 16:07:14 INFO - GECKO(2722) | console.error: "ADB extension is not installed" [task 2018-09-26T16:07:15.373Z] 16:07:15 INFO - GECKO(2722) | [Parent 2722, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/protocol/res/SubstitutingProtocolHandler.cpp, line 342 [task 2018-09-26T16:07:15.414Z] 16:07:15 INFO - GECKO(2722) | [Parent 2722, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1640 [task 2018-09-26T16:07:15.617Z] 16:07:15 INFO - GECKO(2722) | ++DOCSHELL 0x7f5ae994d800 == 11 [pid = 2722] [id = {adbaa903-49e3-4735-a27a-6b5bfc4646fe}] [task 2018-09-26T16:07:15.620Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 29 (0x7f5ae6b55a00) [pid = 2722] [serial = 29] [outer = (nil)] [task 2018-09-26T16:07:15.622Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 30 (0x7f5aecbc6400) [pid = 2722] [serial = 30] [outer = 0x7f5ae6b55a00] [task 2018-09-26T16:07:15.644Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 31 (0x7f5aecbc7000) [pid = 2722] [serial = 31] [outer = 0x7f5ae6b55a00] [task 2018-09-26T16:07:15.766Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 32 (0x7f5aec7de000) [pid = 2722] [serial = 32] [outer = 0x7f5ae6b55a00] [task 2018-09-26T16:07:15.845Z] 16:07:15 INFO - GECKO(2722) | [Parent 2722, Main Thread] WARNING: Need TabChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1187 [task 2018-09-26T16:07:15.845Z] 16:07:15 INFO - GECKO(2722) | ++DOCSHELL 0x7fb048b1b000 == 4 [pid = 2773] [id = {cb3a81ea-67d6-4469-b5ae-368559267336}] [task 2018-09-26T16:07:15.845Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 10 (0x7fb048b81a00) [pid = 2773] [serial = 10] [outer = (nil)] [task 2018-09-26T16:07:15.846Z] 16:07:15 INFO - GECKO(2722) | [Child 2773, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/TabChild.cpp, line 2858 [task 2018-09-26T16:07:15.866Z] 16:07:15 INFO - GECKO(2722) | --DOMWINDOW == 6 (0x7fafcc321400) [pid = 2798] [serial = 2] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:15.866Z] 16:07:15 INFO - GECKO(2722) | --DOMWINDOW == 5 (0x7fafcc6cf800) [pid = 2798] [serial = 6] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:15.907Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 11 (0x7fb04834d000) [pid = 2773] [serial = 11] [outer = 0x7fb048b81a00] [task 2018-09-26T16:07:15.949Z] 16:07:15 INFO - GECKO(2722) | ++DOMWINDOW == 12 (0x7fb04924b400) [pid = 2773] [serial = 12] [outer = 0x7fb048b81a00] [task 2018-09-26T16:07:16.106Z] 16:07:16 INFO - GECKO(2722) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpiqwL9E.mozrunner/runtests_leaks_tab_pid2904.log [task 2018-09-26T16:07:16.228Z] 16:07:16 INFO - GECKO(2722) | [Parent 2722, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1640 [task 2018-09-26T16:07:16.350Z] 16:07:16 INFO - GECKO(2722) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties [task 2018-09-26T16:07:16.821Z] 16:07:16 INFO - GECKO(2722) | --DOMWINDOW == 11 (0x7fb0493df000) [pid = 2773] [serial = 2] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:16.821Z] 16:07:16 INFO - GECKO(2722) | --DOMWINDOW == 10 (0x7fb04924c800) [pid = 2773] [serial = 8] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:16.821Z] 16:07:16 INFO - GECKO(2722) | --DOMWINDOW == 9 (0x7fb049247400) [pid = 2773] [serial = 5] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:16.862Z] 16:07:16 INFO - GECKO(2722) | [Parent 2722, QuotaManager IO] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7594 [task 2018-09-26T16:07:16.864Z] 16:07:16 INFO - GECKO(2722) | [Parent 2722, QuotaManager IO] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7594 [task 2018-09-26T16:07:16.865Z] 16:07:16 INFO - GECKO(2722) | [Parent 2722, QuotaManager IO] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7594 [task 2018-09-26T16:07:16.866Z] 16:07:16 INFO - GECKO(2722) | [Parent 2722, QuotaManager IO] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7594 [task 2018-09-26T16:07:18.177Z] 16:07:18 INFO - GECKO(2722) | --DOCSHELL 0x7f7b19abb000 == 0 [pid = 2837] [id = {018f1eda-747e-4d44-92c8-fc59c7e1222c}] [task 2018-09-26T16:07:18.875Z] 16:07:18 INFO - GECKO(2722) | --DOMWINDOW == 4 (0x7fafe3355400) [pid = 2798] [serial = 1] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:19.085Z] 16:07:19 INFO - GECKO(2722) | --DOCSHELL 0x7f5ae9bd6000 == 10 [pid = 2722] [id = {60588ae3-ed04-49fb-95fd-40031a93d7f4}] [task 2018-09-26T16:07:19.086Z] 16:07:19 INFO - GECKO(2722) | --DOCSHELL 0x7f5af0642800 == 9 [pid = 2722] [id = {b5507192-7e71-4cb7-99c5-df524bca7f73}] [task 2018-09-26T16:07:19.088Z] 16:07:19 INFO - GECKO(2722) | --DOCSHELL 0x7f5ae994d800 == 8 [pid = 2722] [id = {adbaa903-49e3-4735-a27a-6b5bfc4646fe}] [task 2018-09-26T16:07:19.096Z] 16:07:19 INFO - GECKO(2722) | --DOMWINDOW == 31 (0x7f5af0628000) [pid = 2722] [serial = 10] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:19.098Z] 16:07:19 INFO - GECKO(2722) | --DOCSHELL 0x7f5ae9bc0800 == 7 [pid = 2722] [id = {8e4da7b9-c9b4-43bd-b79b-d0cef05e1cfd}] [task 2018-09-26T16:07:19.100Z] 16:07:19 INFO - GECKO(2722) | --DOMWINDOW == 30 (0x7f5aeb0d9000) [pid = 2722] [serial = 22] [outer = (nil)] [url = chrome://extensions/content/dummy.xul] [task 2018-09-26T16:07:19.101Z] 16:07:19 INFO - GECKO(2722) | --DOMWINDOW == 29 (0x7f5ae6b55a00) [pid = 2722] [serial = 29] [outer = (nil)] [url = chrome://extensions/content/dummy.xul] [task 2018-09-26T16:07:19.103Z] 16:07:19 INFO - GECKO(2722) | --DOMWINDOW == 28 (0x7f5aeae42a00) [pid = 2722] [serial = 19] [outer = (nil)] [url = about:debugging] [task 2018-09-26T16:07:21.304Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 8 (0x7fb049231200) [pid = 2773] [serial = 7] [outer = (nil)] [url = moz-extension://f16a0b7f-1edf-493a-9f59-8234befc7ed1/_generated_background_page.html] [task 2018-09-26T16:07:21.306Z] 16:07:21 INFO - GECKO(2722) | --DOCSHELL 0x7fb048b1b000 == 3 [pid = 2773] [id = {cb3a81ea-67d6-4469-b5ae-368559267336}] [task 2018-09-26T16:07:21.307Z] 16:07:21 INFO - GECKO(2722) | --DOCSHELL 0x7fb049293000 == 2 [pid = 2773] [id = {46550b26-953a-44fc-b62c-9aa0741dc426}] [task 2018-09-26T16:07:21.369Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 27 (0x7f5af000e000) [pid = 2722] [serial = 14] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.372Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 26 (0x7f5af000f000) [pid = 2722] [serial = 15] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.375Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 25 (0x7f5af081ec00) [pid = 2722] [serial = 11] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.379Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 24 (0x7f5aff122400) [pid = 2722] [serial = 8] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.380Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 23 (0x7f5aecbc7000) [pid = 2722] [serial = 31] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.382Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 22 (0x7f5aecbc6400) [pid = 2722] [serial = 30] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.384Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 21 (0x7f5af9acc800) [pid = 2722] [serial = 20] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.385Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 20 (0x7f5aec7de000) [pid = 2722] [serial = 32] [outer = (nil)] [url = chrome://extensions/content/dummy.xul] [task 2018-09-26T16:07:21.386Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 19 (0x7f5ae6b9a000) [pid = 2722] [serial = 23] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.387Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 18 (0x7f5ae6b9ac00) [pid = 2722] [serial = 24] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.389Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 17 (0x7f5af8e99c00) [pid = 2722] [serial = 2] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.390Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 16 (0x7f5ae6ba2000) [pid = 2722] [serial = 27] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:21.391Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 15 (0x7f5ae99bbc00) [pid = 2722] [serial = 21] [outer = (nil)] [url = about:debugging] [task 2018-09-26T16:07:21.392Z] 16:07:21 INFO - GECKO(2722) | --DOMWINDOW == 14 (0x7f5ae6b9e000) [pid = 2722] [serial = 25] [outer = (nil)] [url = chrome://extensions/content/dummy.xul] [task 2018-09-26T16:07:22.023Z] 16:07:22 INFO - GECKO(2722) | --DOMWINDOW == 7 (0x7fb04924d000) [pid = 2773] [serial = 9] [outer = (nil)] [url = moz-extension://f16a0b7f-1edf-493a-9f59-8234befc7ed1/_generated_background_page.html] [task 2018-09-26T16:07:22.024Z] 16:07:22 INFO - GECKO(2722) | --DOMWINDOW == 6 (0x7fb04834d000) [pid = 2773] [serial = 11] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:22.869Z] 16:07:22 INFO - GECKO(2722) | --DOMWINDOW == 3 (0x7fafcc31fc00) [pid = 2798] [serial = 3] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:22.870Z] 16:07:22 INFO - GECKO(2722) | --DOMWINDOW == 2 (0x7fafcc329800) [pid = 2798] [serial = 4] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:24.041Z] 16:07:24 INFO - GECKO(2722) | [Parent 2722, StreamTrans #22] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 417 [task 2018-09-26T16:07:24.050Z] 16:07:24 INFO - GECKO(2722) | [Parent 2722, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1125 [task 2018-09-26T16:07:27.975Z] 16:07:27 INFO - GECKO(2722) | --DOMWINDOW == 1 (0x7f7b30a56600) [pid = 2837] [serial = 1] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:32.017Z] 16:07:32 INFO - GECKO(2722) | --DOMWINDOW == 0 (0x7f7b1a17c000) [pid = 2837] [serial = 2] [outer = (nil)] [url = about:blank] [task 2018-09-26T16:07:32.860Z] 16:07:32 INFO - GECKO(2722) | --DOMWINDOW == 5 (0x7fb048b81a00) [pid = 2773] [serial = 10] [outer = (nil)] [url = moz-extension://3bd5a9f2-220a-4e9c-a302-6c50ae7e0f2b/_generated_background_page.html] [task 2018-09-26T16:07:36.901Z] 16:07:36 INFO - GECKO(2722) | --DOMWINDOW == 4 (0x7fb04924b400) [pid = 2773] [serial = 12] [outer = (nil)] [url = moz-extension://3bd5a9f2-220a-4e9c-a302-6c50ae7e0f2b/_generated_background_page.html] [task 2018-09-26T16:08:43.532Z] 16:08:43 INFO - TEST-INFO | started process screentopng [task 2018-09-26T16:08:43.959Z] 16:08:43 INFO - TEST-INFO | screentopng: exit 0 [task 2018-09-26T16:08:43.961Z] 16:08:43 INFO - Buffered messages logged at 16:07:13 [task 2018-09-26T16:08:43.963Z] 16:08:43 INFO - Entering test bound [task 2018-09-26T16:08:43.966Z] 16:08:43 INFO - opening about:debugging [task 2018-09-26T16:08:43.968Z] 16:08:43 INFO - Adding a new tab with URL: about:debugging [task 2018-09-26T16:08:43.970Z] 16:08:43 INFO - Buffered messages logged at 16:07:14 [task 2018-09-26T16:08:43.973Z] 16:08:43 INFO - Tab added and finished loading [task 2018-09-26T16:08:43.977Z] 16:08:43 INFO - Wait until the main about debugging container is available [task 2018-09-26T16:08:43.978Z] 16:08:43 INFO - Wait until the client connection was established [task 2018-09-26T16:08:43.982Z] 16:08:43 INFO - Check that the temporary extensions pane is empty [task 2018-09-26T16:08:43.983Z] 16:08:43 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Temporary Extensions pane contains no debug target - [task 2018-09-26T16:08:43.985Z] 16:08:43 INFO - Check an empty target pane message is displayed [task 2018-09-26T16:08:43.986Z] 16:08:43 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | An empty target list message is displayed - [task 2018-09-26T16:08:43.987Z] 16:08:43 INFO - Install a temporary extension [task 2018-09-26T16:08:43.989Z] 16:08:43 INFO - Wait for addon to be installed [task 2018-09-26T16:08:43.989Z] 16:08:43 INFO - Buffered messages logged at 16:07:15 [task 2018-09-26T16:08:43.991Z] 16:08:43 INFO - Wait until a debug target item appears [task 2018-09-26T16:08:43.992Z] 16:08:43 INFO - Buffered messages logged at 16:07:16 [task 2018-09-26T16:08:43.993Z] 16:08:43 INFO - Check the empty target pane message is no longer displayed [task 2018-09-26T16:08:43.994Z] 16:08:43 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | The empty target list message is no longer displayed - [task 2018-09-26T16:08:43.996Z] 16:08:43 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Temporary Extensions pane now shows debug target - [task 2018-09-26T16:08:43.996Z] 16:08:43 INFO - Remove the temporary extension [task 2018-09-26T16:08:43.997Z] 16:08:43 INFO - Wait until the debug target item disappears [task 2018-09-26T16:08:43.998Z] 16:08:43 INFO - Buffered messages finished [task 2018-09-26T16:08:43.999Z] 16:08:43 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out - [task 2018-09-26T16:08:43.999Z] 16:08:43 INFO - Removing tab. [task 2018-09-26T16:08:44.000Z] 16:08:43 INFO - Waiting for event: 'TabClose' on [object XULElement]. [task 2018-09-26T16:08:44.000Z] 16:08:43 INFO - Got event: 'TabClose' on [object XULElement]. [task 2018-09-26T16:08:44.001Z] 16:08:44 INFO - Tab removed and finished closing [task 2018-09-26T16:08:44.219Z] 16:08:44 INFO - Check the empty target pane message is displayed again [task 2018-09-26T16:08:44.221Z] 16:08:44 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | An empty target list message is displayed again - [task 2018-09-26T16:08:44.222Z] 16:08:44 INFO - Removing tab. [task 2018-09-26T16:08:44.223Z] 16:08:44 INFO - Waiting for event: 'TabClose' on [object XULElement]. [task 2018-09-26T16:08:44.322Z] 16:08:44 INFO - GECKO(2722) | MEMORY STAT | vsize 1905MB | residentFast 295MB | heapAllocated 78MB
Summary: Intermittent devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out - → Intermittent [TV] devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out -
Summary: Intermittent [TV] devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out - → Intermittent devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_debug-target-pane_empty.js | Test timed out -
Priority: P5 → P3
Looks like the test hangs when trying to remove the temporary extension. It clicks on the "remove" button but as the failure screenshots show, the item is still visible.
Most of the failures are on debug, although it's not a simple time out issue. When the test is successful, it takes less than 10seconds to complete on debug platforms. In case we don't have a fix for it, we may skip it on debug only.
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.