Closed Bug 1659101 Opened 4 years ago Closed 3 years ago

Intermittent browser/modules/test/browser/browser_preloading_tab_moving.js | Uncaught exception - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved

Categories

(Firefox :: General, defect, P5)

defect

Tracking

()

RESOLVED FIXED
92 Branch
Tracking Status
firefox-esr91 --- fixed
firefox92 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled][stockwell needswork:owner])

Attachments

(3 files)

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


[task 2020-08-14T12:08:31.973Z] 12:08:31     INFO - TEST-START | browser/modules/test/browser/browser_preloading_tab_moving.js
[task 2020-08-14T12:08:31.988Z] 12:08:31     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 010ADC00 == 2 [pid = 4244] [id = {a765c516-dfbf-44cc-b10b-854ee83106c7}]
[task 2020-08-14T12:08:31.988Z] 12:08:31     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0100B8A0) [pid = 4244] [serial = 67] [outer = 00000000]
[task 2020-08-14T12:08:31.989Z] 12:08:31     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (072C4800) [pid = 4244] [serial = 68] [outer = 0100B8A0]
[task 2020-08-14T12:08:31.989Z] 12:08:31     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1C94AC00 == 11 [pid = 6096] [id = {9029b6ee-9736-4abf-bee9-3fd295d76d4b}]
[task 2020-08-14T12:08:31.989Z] 12:08:31     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 39 (1F3C5790) [pid = 6096] [serial = 203] [outer = 00000000]
[task 2020-08-14T12:08:31.990Z] 12:08:31     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 40 (1C982C00) [pid = 6096] [serial = 204] [outer = 1F3C5790]
[task 2020-08-14T12:08:32.053Z] 12:08:32     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 073D2000 == 1 [pid = 4312] [id = {a0b1244e-f3b1-42e3-a577-f191373d2b4a}] [url = http://example.com/]
[task 2020-08-14T12:08:32.053Z] 12:08:32     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0C08F400 == 0 [pid = 4312] [id = {12b3ae93-6b48-4d4f-8ea5-547022ea2ade}] [url = http://example.com/mypage]
[task 2020-08-14T12:08:32.080Z] 12:08:32     INFO - GECKO(3684) | [Parent 6096, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4248
[task 2020-08-14T12:08:32.087Z] 12:08:32     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (072CC000) [pid = 4244] [serial = 69] [outer = 0100B8A0]
[task 2020-08-14T12:08:32.087Z] 12:08:32     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0130B8A0) [pid = 4312] [serial = 187] [outer = 00000000] [url = http://example.com/]
[task 2020-08-14T12:08:32.294Z] 12:08:32     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 24973400 == 12 [pid = 6096] [id = {d7f2612f-7dbd-4d02-98ea-e614116b7c9a}]
[task 2020-08-14T12:08:32.294Z] 12:08:32     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 41 (1F3C5570) [pid = 6096] [serial = 205] [outer = 00000000]
[task 2020-08-14T12:08:32.354Z] 12:08:32     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 096EB000 == 4 [pid = 6024] [id = {75c55545-6a1f-4e55-a9d6-858ea78b74fc}]
[task 2020-08-14T12:08:32.354Z] 12:08:32     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 24 (0170BF00) [pid = 6024] [serial = 177] [outer = 00000000]
[task 2020-08-14T12:08:32.354Z] 12:08:32     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 25 (096F3000) [pid = 6024] [serial = 178] [outer = 0170BF00]
[task 2020-08-14T12:08:32.434Z] 12:08:32     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 42 (2467D400) [pid = 6096] [serial = 206] [outer = 1F3C5570]
[task 2020-08-14T12:08:32.553Z] 12:08:32     INFO - GECKO(3684) | [Parent 6096, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1598
[task 2020-08-14T12:08:32.572Z] 12:08:32     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 26 (1143DC00) [pid = 6024] [serial = 179] [outer = 0170BF00]
[task 2020-08-14T12:08:33.092Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0D94A800) [pid = 4244] [serial = 66] [outer = 00000000] [url = about:newtab]
[task 2020-08-14T12:08:33.092Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0BD72400) [pid = 4244] [serial = 65] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:33.107Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 072C9800 == 3 [pid = 4244] [id = {dbf9f6fe-3be5-41d0-85ea-66065621f7a7}]
[task 2020-08-14T12:08:33.107Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0100BCE0) [pid = 4244] [serial = 70] [outer = 00000000]
[task 2020-08-14T12:08:33.107Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0BD72400) [pid = 4244] [serial = 71] [outer = 0100BCE0]
[task 2020-08-14T12:08:33.662Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 072C5400 == 4 [pid = 4244] [id = {c136eb24-d7b4-4cb4-b4a7-b38fa9b948d2}]
[task 2020-08-14T12:08:33.662Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0100BF00) [pid = 4244] [serial = 72] [outer = 00000000]
[task 2020-08-14T12:08:33.662Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (072CA400) [pid = 4244] [serial = 73] [outer = 0100BF00]
[task 2020-08-14T12:08:33.737Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0BD71C00) [pid = 4244] [serial = 74] [outer = 0100BF00]
[task 2020-08-14T12:08:33.784Z] 12:08:33     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1FD37800 == 13 [pid = 6096] [id = {dcb0aba1-a374-4a0b-9e56-d688b1d2cff2}]
[task 2020-08-14T12:08:33.784Z] 12:08:33     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 43 (1F3C6010) [pid = 6096] [serial = 207] [outer = 00000000]
[task 2020-08-14T12:08:33.784Z] 12:08:33     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 44 (2209C000) [pid = 6096] [serial = 208] [outer = 1F3C6010]
[task 2020-08-14T12:08:33.844Z] 12:08:33     INFO - GECKO(3684) | [Parent 6096, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4248
[task 2020-08-14T12:08:33.991Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 072CD000 == 5 [pid = 4244] [id = {9130ce40-17c9-4e55-af06-f2ad5f1e7f2d}]
[task 2020-08-14T12:08:33.992Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0100C010) [pid = 4244] [serial = 75] [outer = 00000000]
[task 2020-08-14T12:08:33.992Z] 12:08:33     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (072D0C00) [pid = 4244] [serial = 76] [outer = 0100C010]
[task 2020-08-14T12:08:34.012Z] 12:08:34     INFO - GECKO(3684) | [Child 4244, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp, line 42
[task 2020-08-14T12:08:34.012Z] 12:08:34     INFO - GECKO(3684) | [Child 4244, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp, line 291
[task 2020-08-14T12:08:34.047Z] 12:08:34     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 2572F800 == 14 [pid = 6096] [id = {9df7ff73-a7c1-4478-9dc3-2d0c3c1f7dc1}]
[task 2020-08-14T12:08:34.047Z] 12:08:34     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 45 (1F3C5460) [pid = 6096] [serial = 209] [outer = 00000000]
[task 2020-08-14T12:08:34.066Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 25 (096A2800) [pid = 6024] [serial = 152] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.067Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (096E8400) [pid = 6024] [serial = 161] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.068Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (096EFC00) [pid = 6024] [serial = 165] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.068Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (096A3800) [pid = 6024] [serial = 158] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.068Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (096A0400) [pid = 6024] [serial = 155] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.069Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (0C1AC000) [pid = 6024] [serial = 169] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.069Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 096EB000 == 3 [pid = 6024] [id = {75c55545-6a1f-4e55-a9d6-858ea78b74fc}] [url = about:blank]
[task 2020-08-14T12:08:34.069Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0969C400 == 2 [pid = 6024] [id = {5563afd3-a3fa-476d-95ee-d4b077196b35}] [url = about:blank]
[task 2020-08-14T12:08:34.070Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (096ED000) [pid = 6024] [serial = 159] [outer = 00000000] [url = http://example.com/]
[task 2020-08-14T12:08:34.070Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (096F4000) [pid = 6024] [serial = 162] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.070Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (0969A000) [pid = 6024] [serial = 154] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.071Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0969D000) [pid = 6024] [serial = 157] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.071Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (096F7000) [pid = 6024] [serial = 166] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:34.071Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 096F7400 == 1 [pid = 6024] [id = {49eb1095-b4dd-493a-b761-30c93843903d}] [url = about:blank]
[task 2020-08-14T12:08:34.071Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 076C4800 == 0 [pid = 6024] [id = {19244de9-c0ce-4a84-9d72-d82c769b28af}] [url = about:blank]
[task 2020-08-14T12:08:34.111Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 09699C00 == 1 [pid = 6024] [id = {7402aa9a-68b7-42af-8637-babf2f3a1bd3}]
[task 2020-08-14T12:08:34.111Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (0170C780) [pid = 6024] [serial = 180] [outer = 00000000]
[task 2020-08-14T12:08:34.111Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (0969DC00) [pid = 6024] [serial = 181] [outer = 0170C780]
[task 2020-08-14T12:08:34.131Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0170C340) [pid = 6024] [serial = 160] [outer = 00000000] [url = http://mochi.test:8888/browser/browser/modules/test/browser/?q=example]
[task 2020-08-14T12:08:34.131Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (0170C450) [pid = 6024] [serial = 164] [outer = 00000000] [url = http://example.com/]
[task 2020-08-14T12:08:34.238Z] 12:08:34     INFO - GECKO(3684) | [Parent 6096: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 46 (25731800) [pid = 6096] [serial = 210] [outer = 1F3C5460]
[task 2020-08-14T12:08:34.273Z] 12:08:34     INFO - GECKO(3684) | [Child 4244: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0D94A000) [pid = 4244] [serial = 77] [outer = 0100C010]
[task 2020-08-14T12:08:34.372Z] 12:08:34     INFO - GECKO(3684) | [Parent 6096, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1598
[task 2020-08-14T12:08:34.607Z] 12:08:34     INFO - GECKO(3684) | [Child 6024: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (096A5400) [pid = 6024] [serial = 182] [outer = 0170C780]
[task 2020-08-14T12:08:35.162Z] 12:08:35     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 073D2C00 == 1 [pid = 4312] [id = {87b1f066-90c4-4683-a8c4-013c11d450d2}]
[task 2020-08-14T12:08:35.162Z] 12:08:35     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0130B8A0) [pid = 4312] [serial = 195] [outer = 00000000]
[task 2020-08-14T12:08:35.162Z] 12:08:35     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0C091000) [pid = 4312] [serial = 196] [outer = 0130B8A0]
[task 2020-08-14T12:08:35.937Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0D07B000 == 1 [pid = 5440] [id = {1b47bb80-a852-4389-9acb-5f730364efb1}] [url = about:blank]
[task 2020-08-14T12:08:35.937Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0D073400) [pid = 5440] [serial = 170] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.937Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (0BD95400) [pid = 5440] [serial = 166] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.938Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (0D075000) [pid = 5440] [serial = 167] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.938Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0D33C400) [pid = 5440] [serial = 173] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.973Z] 12:08:35     INFO - GECKO(3684) | [Child 5572: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 06DC4400 == 1 [pid = 5572] [id = {bc14a9f0-c592-4f99-ae48-4d64c6d1676c}]
[task 2020-08-14T12:08:35.973Z] 12:08:35     INFO - GECKO(3684) | [Child 5572: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (00B0B8A0) [pid = 5572] [serial = 37] [outer = 00000000]
[task 2020-08-14T12:08:35.973Z] 12:08:35     INFO - GECKO(3684) | [Child 5572: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (06DCD800) [pid = 5572] [serial = 38] [outer = 00B0B8A0]
[task 2020-08-14T12:08:35.981Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0130C560) [pid = 5440] [serial = 162] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.981Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0130B8A0) [pid = 5440] [serial = 165] [outer = 00000000] [url = http://example.com/]
[task 2020-08-14T12:08:35.981Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0130C230) [pid = 5440] [serial = 172] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.982Z] 12:08:35     INFO - GECKO(3684) | [Child 5440: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0130B9B0) [pid = 5440] [serial = 169] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:35.997Z] 12:08:35     INFO - GECKO(3684) | [Child 5572: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (06DD0000) [pid = 5572] [serial = 39] [outer = 00B0B8A0]
[task 2020-08-14T12:08:36.014Z] 12:08:36     INFO - GECKO(3684) | [Parent 6096, Main Thread] WARNING: 'aOwner->IsDiscarded()', file /builds/worker/workspace/obj-build/dist/include/mozilla/dom/SyncedContextInlines.h, line 36
[task 2020-08-14T12:08:36.014Z] 12:08:36     INFO - GECKO(3684) | [Child 4312, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp, line 42
[task 2020-08-14T12:08:36.014Z] 12:08:36     INFO - TEST-INFO | started process screenshot
[task 2020-08-14T12:08:36.081Z] 12:08:36     INFO - TEST-INFO | screenshot: exit 0
[task 2020-08-14T12:08:36.082Z] 12:08:36     INFO - Buffered messages logged at 12:08:31
[task 2020-08-14T12:08:36.083Z] 12:08:36     INFO - Entering test bound moving_works
[task 2020-08-14T12:08:36.083Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should have preloaded browser - 
[task 2020-08-14T12:08:36.084Z] 12:08:36     INFO - Buffered messages logged at 12:08:33
[task 2020-08-14T12:08:36.084Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Preloaded browser should be gone - 
[task 2020-08-14T12:08:36.085Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should have moved the preload browser - 
[task 2020-08-14T12:08:36.085Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should have the same permanent key - 
[task 2020-08-14T12:08:36.085Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Preloaded browser is usable when opening a new tab. - 
[task 2020-08-14T12:08:36.085Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Successfully loaded the tab. - 
[task 2020-08-14T12:08:36.086Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Managed to open a tab in the original window still. - 
[task 2020-08-14T12:08:36.086Z] 12:08:36     INFO - Leaving test bound moving_works
[task 2020-08-14T12:08:36.087Z] 12:08:36     INFO - Entering test bound moving_shouldnt_move_across_private_state
[task 2020-08-14T12:08:36.087Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should have preloaded browser - 
[task 2020-08-14T12:08:36.088Z] 12:08:36     INFO - Console message: [JavaScript Error: "AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved"]
[task 2020-08-14T12:08:36.088Z] 12:08:36     INFO - Buffered messages logged at 12:08:35
[task 2020-08-14T12:08:36.088Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Preloaded browser in original window should persist - 
[task 2020-08-14T12:08:36.089Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should have created another preload browser - 
[task 2020-08-14T12:08:36.089Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Should not have the same permanent key - 
[task 2020-08-14T12:08:36.090Z] 12:08:36     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Preloaded browser is usable when opening a new tab. - 
[task 2020-08-14T12:08:36.090Z] 12:08:36     INFO - Buffered messages finished
[task 2020-08-14T12:08:36.090Z] 12:08:36     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_preloading_tab_moving.js | Uncaught exception - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2020-08-14T12:08:36.091Z] 12:08:36     INFO - Leaving test bound moving_shouldnt_move_across_private_state
[task 2020-08-14T12:08:36.091Z] 12:08:36     INFO - GECKO(3684) | MEMORY STAT | vsize 1131MB | vsizeMaxContiguous 331MB | residentFast 479MB | heapAllocated 228MB
[task 2020-08-14T12:08:36.091Z] 12:08:36     INFO - TEST-OK | browser/modules/test/browser/browser_preloading_tab_moving.js | took 4072ms
[task 2020-08-14T12:08:36.091Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0C095000 == 2 [pid = 4312] [id = {07b9ae48-4b10-472c-be14-8760d83508bd}]
[task 2020-08-14T12:08:36.092Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0130C120) [pid = 4312] [serial = 197] [outer = 00000000]
[task 2020-08-14T12:08:36.092Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0C098800) [pid = 4312] [serial = 198] [outer = 0130C120]
[task 2020-08-14T12:08:36.092Z] 12:08:36     INFO - checking window state
[task 2020-08-14T12:08:36.093Z] 12:08:36     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T12:08:36.093Z] 12:08:36     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_preloading_tab_moving.js | Found an unexpected browser window at the end of test run - 
[task 2020-08-14T12:08:36.093Z] 12:08:36     INFO - GECKO(3684) | must wait for focus
[task 2020-08-14T12:08:36.094Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0D7C8000) [pid = 4312] [serial = 199] [outer = 0130C120]
[task 2020-08-14T12:08:36.209Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (0D7C0C00) [pid = 4312] [serial = 190] [outer = 00000000] [url = http://example.com/]
[task 2020-08-14T12:08:36.209Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0C08E800) [pid = 4312] [serial = 188] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:36.209Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0C098400) [pid = 4312] [serial = 189] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:36.209Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0C094C00) [pid = 4312] [serial = 192] [outer = 00000000] [url = about:blank]
[task 2020-08-14T12:08:36.210Z] 12:08:36     INFO - GECKO(3684) | [Child 4312: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 073D2C00 == 1 [pid = 4312] [id = {87b1f066-90c4-4683-a8c4-013c11d450d2}] [url = about:blank]
[task 2020-08-14T12:08:36.210Z] 12:08:36     INFO - TEST-START | browser/modules/test/browser/browser_taskbar_preview.js
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

There have been 93 total failures in the last 7 days (recent failure log).
Affected platforms are:

  • linux1804-64 opt & debug, linux1804-64-asan, linux1804-64-ccov, linux1804-64-shippable, linux1804-64-tsan
  • macosx1014-64-qr opt & debug
  • windows10-64 debug & asan, windows10-64-qr debug & opt
  • windows7-32 debug

Most of the failures seem to happen on linux at the moment.
Dave, could you please redirect this to someone who can take a look?

Flags: needinfo?(dtownsend)
Whiteboard: [stockwell needswork:owner]
Assignee: nobody → abutkovits
Pushed by malexandru@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/9edee78b7d6f
disable browser_preloading_tab_moving.js on Linux_64_opt for frequent failures. r=intermittent-reviewers,ahal
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 89 Branch
Status: RESOLVED → REOPENED
Keywords: leave-open
Resolution: FIXED → ---
Target Milestone: 89 Branch → ---

Here's a profile of the passing test on my local Mac build: https://share.firefox.dev/3mhEoiy

I see that there are 2 TestUtils.waitForCondition calls that resolve immediately, and they are before the calls to SpecialPower.Spawn. The failure rate of this bug seems to have increased around the time of when I landed bug 1596165. Before that the waitForCondition calls never resolved in less than 100ms. There's likely a timing issue there.

Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Assignee: abutkovits → nobody
Pushed by nbeleuzu@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/4afbf721f77d
Disable browser_preloading_tab_moving.js on Windows for frequent failures. r=intermittent-reviewers,jmaher

Gijs, looks like you wrote this test, can you take a look or suggest someone else who can?

Flags: needinfo?(dtownsend) → needinfo?(gijskruitbosch+bugs)

This pattern of code which recurs several times in the test:

https://searchfox.org/mozilla-central/rev/2819d981824ad8a707885346d7ddb3bd3e077c9c/browser/modules/test/browser/browser_preloading_tab_moving.js#108-122

  let browser = newWin.gBrowser.preloadedBrowser;
  let tab = BrowserTestUtils.addTab(
    newWin.gBrowser,
    newWin.BROWSER_NEW_TAB_URL
  );
  is(
    tab.linkedBrowser,
    browser,
    "Preloaded browser is usable when opening a new tab."
  );
  await SpecialPowers.spawn(browser, [], function() {
    return ContentTaskUtils.waitForCondition(() => {
      return content.document.readyState == "complete";
    });
  });

is trying to wait for a loaded or loading browser document from a preloaded tab to be swapped into a newly created tab. The test is trying to verify that (a) this happens, and (b) that the new tab ends up with a loaded document. It can't use events for this directly because the browser's content might be loaded prior to being swapped, or it may load afterwards. It looks like the changes in bug 1596918 switched us from ContentTask to SpecialPowers, which already ran into issues which is why 1 spawn call has a blanket Cu.reportError catch - but the others do not. We could obviously add catch clauses to the 2 other spawns, but that's just wallpapering the problem.

Kris/Andrew: Why is the spawned task being destroyed, and how can we reasonably wait for this event if this kind of thing doesn't work? I'm assuming it's something like "the spawn call initially runs against about:blank and then we load the actual requested URI which swaps out content windows and aborts the spawn call" -- but then, why did that start happening 9 months ago, on non-fission tasks, and what is the "right" way to do this?

Flags: needinfo?(kmaglione+bmo)
Flags: needinfo?(gijskruitbosch+bugs)
Flags: needinfo?(continuation)

Anny has looked at fixing tests more recently than me, so maybe she has some ideas.

Flags: needinfo?(continuation) → needinfo?(agakhokidze)

Update:

There have been 33 failures within the last 7 days:

  • 6 failures on Linux 18.04 x64 debug
  • 27 failures on OS X 10.15 WebRender debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=342575447&repo=autoland&lineNumber=15172

[task 2021-06-12T06:46:46.894Z] 06:46:46     INFO - TEST-PASS | browser/modules/test/browser/browser_preloading_tab_moving.js | Preloaded browser is usable when opening a new tab. - 
[task 2021-06-12T06:46:46.895Z] 06:46:46     INFO - Buffered messages finished
[task 2021-06-12T06:46:46.899Z] 06:46:46     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_preloading_tab_moving.js | Uncaught exception - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2021-06-12T06:46:46.900Z] 06:46:46     INFO - Leaving test bound moving_shouldnt_move_across_private_state
[task 2021-06-12T06:46:46.900Z] 06:46:46     INFO - GECKO(3993) | [Child 4129: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 30 (7f90fb495400) [pid = 4129] [serial = 87] [outer = 7f9111b64e40]
[task 2021-06-12T06:46:46.901Z] 06:46:46     INFO - GECKO(3993) | MEMORY STAT | vsize 3924MB | residentFast 698MB | heapAllocated 325MB
[task 2021-06-12T06:46:46.903Z] 06:46:46     INFO - TEST-OK | browser/modules/test/browser/browser_preloading_tab_moving.js | took 5577ms
Flags: needinfo?(agakhokidze)

Anny, did you have a chance to take a look over this?
Thank you.

Flags: needinfo?(agakhokidze)
Whiteboard: [stockwell disabled] → [stockwell disabled][stockwell needswork:owner]

Sorry, I messaged Gijs (while he is away) on matrix, but didn't give a status update here. I've quickly looked into it and tried a few things but couldn't fix it. I'm busy with higher priority Fission work right now, but can probably look again when I have some time.

Flags: needinfo?(agakhokidze)

(In reply to Anny Gakhokidze [:annyG] from comment #55)

Sorry, I messaged Gijs (while he is away) on matrix, but didn't give a status update here. I've quickly looked into it and tried a few things but couldn't fix it. I'm busy with higher priority Fission work right now, but can probably look again when I have some time.

Sorry, I am probably not looking in the right rooms but I can't find your message on matrix (but it's been 2 weeks...). If there's a specific question I can help clarify or if you know the answers to some of the questions at the tail end of comment #45 then perhaps we can find a way of moving forward here :-)

Flags: needinfo?(agakhokidze)

The actor that handles the spawn task is tied to a specific inner window. It needs to finish executing before that window is destroyed. But the test doesn't wait for the correct inner window to be created before calling spawn, so it will sometimes wind up executing in the initial about:blank document, which will never have readyState == "complete"

Flags: needinfo?(kmaglione+bmo)

(In reply to Kris Maglione [:kmag] from comment #58)

The actor that handles the spawn task is tied to a specific inner window. It needs to finish executing before that window is destroyed. But the test doesn't wait for the correct inner window to be created before calling spawn

How should it do that, ie how can you wait for an inner window being created in the child process from the parent, in a test? Are there other tests that do this "right"?

Flags: needinfo?(kmaglione+bmo)

(In reply to :Gijs (he/him) from comment #59)

How should it do that, ie how can you wait for an inner window being created in the child process from the parent, in a test? Are there other tests that do this "right"?

Usually with BrowserTestUtils.browserLoaded or openNewForegroundTab with waitForLoad: true (which is the default). You can also use a WebProgressListener, but that's more work.

Flags: needinfo?(kmaglione+bmo)

(In reply to Kris Maglione [:kmag] from comment #60)

(In reply to :Gijs (he/him) from comment #59)

How should it do that, ie how can you wait for an inner window being created in the child process from the parent, in a test? Are there other tests that do this "right"?

Usually with BrowserTestUtils.browserLoaded or openNewForegroundTab with waitForLoad: true (which is the default). You can also use a WebProgressListener, but that's more work.

This assumes that a load event will always follow; As I said in comment #45, I don't think that's the case, as we're testing that a preloaded browser is getting swapped in, and that browser may already be loaded, so browserLoaded would never resolve. It would race "the other way".

Can we wait for the inner window creation directly?

Flags: needinfo?(kmaglione+bmo)

(In reply to :Gijs (he/him) from comment #61)

This assumes that a load event will always follow; As I said in comment #45, I don't think that's the case, as we're testing that a preloaded browser is getting swapped in, and that browser may already be loaded, so browserLoaded would never resolve. It would race "the other way".

Can we wait for the inner window creation directly?

I don't see how that would make a difference from waiting for the load. If you get an inner window creation event, you'll get a load event.

You could always check the current URI of the WindowGlobalParent and use a WebProgressListener if it isn't what you want.

Flags: needinfo?(kmaglione+bmo)
Flags: needinfo?(gijskruitbosch+bugs)

See discussion in the last few comments on the bug. If we don't wait for the correct URL
to load in the browser, the SpecialPowers.spawn task can get aborted, which causes the
test to fail.

Assignee: nobody → gijskruitbosch+bugs
Flags: needinfo?(gijskruitbosch+bugs)
Flags: needinfo?(agakhokidze)
See Also: → 1720203
Pushed by gijskruitbosch@gmail.com:
https://hg.mozilla.org/integration/autoland/rev/d8ed8ab07a40
fix intermittent failure in browser_preoloading_tab_moving.js, r=jaws
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 92 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: