Open Bug 1640234 Opened 4 years ago Updated 2 years ago

Intermittent devtools/client/application/test/browser/browser_application_panel_target-switching.js | Test timed out -

Categories

(DevTools :: General, defect, P5)

defect

Tracking

(Not tracked)

ASSIGNED

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [retriggered][stockwell-disabled])

Attachments

(1 file, 1 obsolete file)

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


[task 2020-05-22T16:18:36.930Z] 16:18:36     INFO - TEST-OK | devtools/client/application/test/browser/browser_application_panel_sidebar.js | took 13229ms
[task 2020-05-22T16:18:37.015Z] 16:18:37     INFO - GECKO(1242) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpmBuc3U.mozrunner/runtests_leaks_tab_pid1727.log
[task 2020-05-22T16:18:37.015Z] 16:18:37     INFO - GECKO(1242) | [1727, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 224
[task 2020-05-22T16:18:37.042Z] 16:18:37     INFO - checking window state
[task 2020-05-22T16:18:37.083Z] 16:18:37     INFO - TEST-START | devtools/client/application/test/browser/browser_application_panel_target-switching.js
[task 2020-05-22T16:18:37.099Z] 16:18:37     INFO - GECKO(1242) | [Parent 1242, Main Thread] WARNING: NS_ENSURE_TRUE(GetWrapper()) failed: file /builds/worker/checkouts/gecko/dom/ipc/JSActor.cpp, line 90
[task 2020-05-22T16:18:37.147Z] 16:18:37     INFO - GECKO(1242) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-05-22T16:18:37.162Z] 16:18:37     INFO - GECKO(1242) | [Parent 1242: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe4aa688c00 == 13 [pid = 1242] [id = {d59618c1-0474-409c-965d-fda9d92e67ae}]
[task 2020-05-22T16:18:37.163Z] 16:18:37     INFO - GECKO(1242) | [Parent 1242: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 56 (0x7fe4aa6c9600) [pid = 1242] [serial = 82] [outer = (nil)]
[task 2020-05-22T16:18:37.183Z] 16:18:37     INFO - GECKO(1242) | [Parent 1242: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 57 (0x7fe4aa68fc00) [pid = 1242] [serial = 83] [outer = 0x7fe4aa6c9600]
[task 2020-05-22T16:18:37.324Z] 16:18:37     INFO - GECKO(1242) | [Parent 1242: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 58 (0x7fe4ae07c000) [pid = 1242] [serial = 84] [outer = 0x7fe4aa6c9600]
[task 2020-05-22T16:18:37.481Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x7f6561d8fc00) [pid = 1690] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.482Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7f6567d59400) [pid = 1690] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.482Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7f6561d99400) [pid = 1690] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.482Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f6567ddac00 == 0 [pid = 1690] [id = {27c3bd5d-9366-49bf-9b94-ac4cc4152e72}] [url = about:blank]
[task 2020-05-22T16:18:37.741Z] 16:18:37     INFO - GECKO(1242) | [Child 1727, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1638
[task 2020-05-22T16:18:37.757Z] 16:18:37     INFO - GECKO(1242) | [Child 1727: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f18cf497c00 == 1 [pid = 1727] [id = {78fa6d50-d5d1-415d-950e-82cdce694191}]
[task 2020-05-22T16:18:37.805Z] 16:18:37     INFO - GECKO(1242) | [Child 1727: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f18e5193350) [pid = 1727] [serial = 1] [outer = (nil)]
[task 2020-05-22T16:18:37.812Z] 16:18:37     INFO - GECKO(1242) | [Child 1727: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f18cf3b4c00) [pid = 1727] [serial = 2] [outer = 0x7f18e5193350]
[task 2020-05-22T16:18:37.854Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7f657da93520) [pid = 1690] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.854Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f6567ca2260) [pid = 1690] [serial = 9] [outer = (nil)] [url = http://example.com/browser/devtools/client/application/test/browser/resources/manifest/load-ok.html]
[task 2020-05-22T16:18:37.854Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f657da93180) [pid = 1690] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.854Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f657da93350) [pid = 1690] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.854Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f6561d3f400) [pid = 1690] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.855Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f6561d99800) [pid = 1690] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.856Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f6561d9bc00) [pid = 1690] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2020-05-22T16:18:37.857Z] 16:18:37     INFO - GECKO(1242) | [Child 1690: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f6567d5cc00) [pid = 1690] [serial = 11] [outer = (nil)] [url = http://example.com/browser/devtools/client/application/test/browser/resources/manifest/load-ok.html]
[task 2020-05-22T16:18:37.858Z] 16:18:37     INFO - GECKO(1242) | [Child 1690, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp, line 2295
[task 2020-05-22T16:18:37.859Z] 16:18:37     INFO - GECKO(1242) | [Child 1690, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/caps/ContentPrincipal.cpp, line 398
<...>
[task 2020-05-22T16:18:52.916Z] 16:18:52     INFO - GECKO(1242) | [Parent 1242: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7fe4c2292800) [pid = 1242] [serial = 29] [outer = (nil)] [url = about:devtools-toolbox]
[task 2020-05-22T16:19:10.438Z] 16:19:10     INFO - GECKO(1242) | [Parent 1242, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp, line 57
[task 2020-05-22T16:19:10.438Z] 16:19:10     INFO - GECKO(1242) | [Parent 1242, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 446
[task 2020-05-22T16:19:10.438Z] 16:19:10     INFO - GECKO(1242) | [Parent 1242, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2020-05-22T16:20:07.105Z] 16:20:07     INFO - TEST-INFO | started process screentopng
[task 2020-05-22T16:20:07.366Z] 16:20:07     INFO - TEST-INFO | screentopng: exit 0
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Buffered messages logged at 16:18:37
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Entering test bound 
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Open a page that runs in the parent process
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Adding a new tab with URL: about:robots
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Tab added and finished loading
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Buffered messages logged at 16:18:38
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 78}]
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 78}]
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Buffered messages logged at 16:18:39
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Check for non-existing service worker
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Selecting application page: service-workers
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - TEST-PASS | devtools/client/application/test/browser/browser_application_panel_target-switching.js | No Service Worker displayed - 
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Navigate to a page that runs in the child process
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Load document "http://example.com/browser/devtools/client/application/test/browser/resources/service-workers/simple.html"
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Waiting for page to be loaded…
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - → page loaded
[task 2020-05-22T16:20:07.367Z] 16:20:07     INFO - Waiting for target switch…
[task 2020-05-22T16:20:07.370Z] 16:20:07     INFO - Buffered messages logged at 16:18:40
[task 2020-05-22T16:20:07.370Z] 16:20:07     INFO - → switched-target emitted
[task 2020-05-22T16:20:07.370Z] 16:20:07     INFO - Wait until the service worker appears in the application panel
[task 2020-05-22T16:20:07.371Z] 16:20:07     INFO - Buffered messages finished
[task 2020-05-22T16:20:07.371Z] 16:20:07     INFO - TEST-UNEXPECTED-FAIL | devtools/client/application/test/browser/browser_application_panel_target-switching.js | Test timed out - 
[task 2020-05-22T16:20:08.313Z] 16:20:08     INFO - Removing tab.
[task 2020-05-22T16:20:08.314Z] 16:20:08     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-05-22T16:20:08.332Z] 16:20:08     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-05-22T16:20:08.351Z] 16:20:08     INFO - GECKO(1242) | [Parent 1242, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorProtocol.cpp, line 172
[task 2020-05-22T16:20:08.353Z] 16:20:08     INFO - GECKO(1242) | [Parent 1242, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/JSWindowActorProtocol.cpp, line 172
[task 2020-05-22T16:20:08.356Z] 16:20:08     INFO - Tab removed and finished closing
[task 2020-05-22T16:20:08.422Z] 16:20:08     INFO - TEST-PASS | devtools/client/application/test/browser/browser_application_panel_target-switching.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-05-22T16:20:08.458Z] 16:20:08     INFO - GECKO(1242) | MEMORY STAT | vsize 3269MB | residentFast 391MB | heapAllocated 114MB
[task 2020-05-22T16:20:08.458Z] 16:20:08     INFO - TEST-OK | devtools/client/application/test/browser/browser_application_panel_target-switching.js | took 91370ms
Has Regression Range: --- → yes
Whiteboard: [retriggered]

In the past week there were 52 failures on
-linux1804-64 debug
-linux1804-64-shippable opt
-windows7-32-shippable opt.

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=306247768&repo=autoland&lineNumber=2384

[task 2020-06-13T17:43:42.987Z] 17:43:42 INFO - TEST-PASS | devtools/client/application/test/browser/browser_application_panel_target-switching.js | No Service Worker displayed -
[task 2020-06-13T17:43:42.988Z] 17:43:42 INFO - Navigate to a page that runs in the child process
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - Load document "http://example.com/browser/devtools/client/application/test/browser/resources/service-workers/simple.html"
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - Waiting for page to be loaded…
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - → page loaded
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - Waiting for target switch…
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - → switched-target emitted
[task 2020-06-13T17:43:42.989Z] 17:43:42 INFO - Wait until the service worker appears in the application panel
[task 2020-06-13T17:43:42.990Z] 17:43:42 INFO - Buffered messages finished
[task 2020-06-13T17:43:42.990Z] 17:43:42 INFO - TEST-UNEXPECTED-FAIL | devtools/client/application/test/browser/browser_application_panel_target-switching.js | Test timed out -

Flags: needinfo?(odvarko)
Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]
Assignee: nobody → ncsoregi
Status: NEW → ASSIGNED
Flags: needinfo?(hmanilla)
Attachment #9158006 - Attachment is obsolete: true
Keywords: leave-open
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell-disabled]
Pushed by ncsoregi@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/70237b77288a
Disabled browser_application_panel_target-switching.js on Linux and Windows. r=jmaher
Flags: needinfo?(odvarko)

There have only been 3 occurrences in the last 7 days, 2 on OSX on Autoland, and 1 occurrence on Release.
Based on Comment 14, failures on these landings: https://hg.mozilla.org/integration/autoland/rev/4f21c4c72174a2ba1092ae9c1e9b178fbaf38d49 and https://hg.mozilla.org/integration/autoland/rev/8a9e63398ef9fdb6720599c67d38a160cac797d8 are misclassifications.

Attachment #9158006 - Attachment is obsolete: false
Attachment #9158006 - Attachment is obsolete: true
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: