1695011 - Intermittent /components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | A promise chain failed to handle a rejection: can't access property "promise", this._batchBlockingDeferred is null - stack: beginBatch/<@/content/browser-places

Status: RESOLVED FIXED

(Firefox :: Bookmarks & History, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-02-25T16:25:55.642Z] 16:25:55     INFO - TEST-OK | browser/components/places/tests/browser/browser_bookmarkProperties_editTagContainer.js | took 1528ms
[task 2021-02-25T16:25:55.650Z] 16:25:55     INFO - GECKO(4192) | [Child 4357: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7ff17e3da400 == 3 [pid = 4357] [id = 5]
[task 2021-02-25T16:25:55.650Z] 16:25:55     INFO - GECKO(4192) | [Child 4357: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7ff17e317200) [pid = 4357] [serial = 12] [outer = (nil)]
[task 2021-02-25T16:25:55.650Z] 16:25:55     INFO - GECKO(4192) | [Child 4357: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7ff17e3dd000) [pid = 4357] [serial = 13] [outer = 0x7ff17e317200]
[task 2021-02-25T16:25:55.689Z] 16:25:55     INFO - checking window state
[task 2021-02-25T16:25:55.689Z] 16:25:55     INFO - GECKO(4192) | must wait for focus
[task 2021-02-25T16:25:55.791Z] 16:25:55     INFO - TEST-START | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js
[task 2021-02-25T16:25:55.819Z] 16:25:55     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fe641cbc800 == 2 [pid = 4257] [id = 2] [url = about:blank]
[task 2021-02-25T16:25:55.820Z] 16:25:55     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fe641cbe000 == 1 [pid = 4257] [id = 4] [url = about:blank]
[task 2021-02-25T16:25:55.821Z] 16:25:55     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fe641cbd000 == 0 [pid = 4257] [id = 3] [url = about:blank]
[task 2021-02-25T16:25:55.858Z] 16:25:55     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f10b09ac400 == 20 [pid = 4192] [id = 23]
[task 2021-02-25T16:25:55.858Z] 16:25:55     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 52 (0x7f10c64f4ac0) [pid = 4192] [serial = 58] [outer = (nil)]
[task 2021-02-25T16:25:55.858Z] 16:25:55     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 53 (0x7f10b0cafc00) [pid = 4192] [serial = 59] [outer = 0x7f10c64f4ac0]
[task 2021-02-25T16:25:55.894Z] 16:25:55     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1327
[task 2021-02-25T16:25:55.894Z] 16:25:55     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4253
[task 2021-02-25T16:25:55.896Z] 16:25:55     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7fe640f15200) [pid = 4257] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2021-02-25T16:25:55.896Z] 16:25:55     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7fe640f15040) [pid = 4257] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2021-02-25T16:25:56.065Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f10b1631800 == 21 [pid = 4192] [id = 24]
[task 2021-02-25T16:25:56.067Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 54 (0x7f10c703cac0) [pid = 4192] [serial = 60] [outer = (nil)]
[task 2021-02-25T16:25:56.112Z] 16:25:56     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe641cbcc00 == 1 [pid = 4257] [id = 5]
[task 2021-02-25T16:25:56.113Z] 16:25:56     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fe640f15040) [pid = 4257] [serial = 13] [outer = (nil)]
[task 2021-02-25T16:25:56.122Z] 16:25:56     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7fe641cc0000) [pid = 4257] [serial = 14] [outer = 0x7fe640f15040]
[task 2021-02-25T16:25:56.164Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4251
[task 2021-02-25T16:25:56.172Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 55 (0x7f10b0afa800) [pid = 4192] [serial = 61] [outer = 0x7f10c703cac0]
[task 2021-02-25T16:25:56.463Z] 16:25:56     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7fe641cc4800) [pid = 4257] [serial = 15] [outer = 0x7fe640f15040]
[task 2021-02-25T16:25:56.565Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f10acef7c00 == 22 [pid = 4192] [id = 25]
[task 2021-02-25T16:25:56.566Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 56 (0x7f10c79ea740) [pid = 4192] [serial = 62] [outer = (nil)]
[task 2021-02-25T16:25:56.570Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 57 (0x7f10b162d800) [pid = 4192] [serial = 63] [outer = 0x7f10c79ea740]
[task 2021-02-25T16:25:56.619Z] 16:25:56     INFO - GECKO(4192) | Waiting for browser load
[task 2021-02-25T16:25:56.620Z] 16:25:56     INFO - GECKO(4192) | Waiting for browser state change
[task 2021-02-25T16:25:56.639Z] 16:25:56     INFO - GECKO(4192) | [Child 4331: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd43f13d000 == 2 [pid = 4331] [id = 2]
[task 2021-02-25T16:25:56.640Z] 16:25:56     INFO - GECKO(4192) | [Child 4331: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fd442436ac0) [pid = 4331] [serial = 7] [outer = (nil)]
[task 2021-02-25T16:25:56.641Z] 16:25:56     INFO - GECKO(4192) | [Child 4331: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fd43f13e000) [pid = 4331] [serial = 8] [outer = 0x7fd442436ac0]
[task 2021-02-25T16:25:56.695Z] 16:25:56     INFO - GECKO(4192) | Saw state 110001 and status 0
[task 2021-02-25T16:25:56.698Z] 16:25:56     INFO - GECKO(4192) | [Parent 4192: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 58 (0x7f10b136f000) [pid = 4192] [serial = 64] [outer = 0x7f10c79ea740]
[task 2021-02-25T16:25:56.707Z] 16:25:56     INFO - GECKO(4192) | [Child 4331: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fd43fc2bc00) [pid = 4331] [serial = 9] [outer = 0x7fd442436ac0]
[task 2021-02-25T16:25:56.789Z] 16:25:56     INFO - GECKO(4192) | Saw state c0010 and status 0
[task 2021-02-25T16:25:56.790Z] 16:25:56     INFO - GECKO(4192) | Browser got expected state change about:robots
[task 2021-02-25T16:25:57.394Z] 16:25:57     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7053
[task 2021-02-25T16:25:57.515Z] 16:25:57     INFO - GECKO(4192) | [Child 4331, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7053
[task 2021-02-25T16:25:57.799Z] 16:25:57     INFO - GECKO(4192) | [Child 4357: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7ff17e3d9800 == 2 [pid = 4357] [id = 3] [url = about:blank]
[task 2021-02-25T16:25:57.800Z] 16:25:57     INFO - GECKO(4192) | [Child 4357: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7ff17e3d9c00 == 1 [pid = 4357] [id = 4] [url = about:blank]
[task 2021-02-25T16:25:57.938Z] 16:25:57     INFO - GECKO(4192) | console.assert: {addObserver:..,root:..,QueryInterface:..,sortingMode:..,suppressNotifications:..,removeObserver:..,onBeginUpdateBatch:..,onEndUpdateBatch:.., } Need to have a result to update
[task 2021-02-25T16:25:57.958Z] 16:25:57     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:57.959Z] 16:25:57     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:57.974Z] 16:25:57     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.023Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.120Z] 16:25:58     INFO - GECKO(4192) | console.assert: {addObserver:..,root:..,QueryInterface:..,sortingMode:..,suppressNotifications:..,removeObserver:..,onBeginUpdateBatch:..,onEndUpdateBatch:.., } Need to have a result to update
[task 2021-02-25T16:25:58.123Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.131Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.319Z] 16:25:58     INFO - GECKO(4192) | console.assert: {addObserver:..,root:..,QueryInterface:..,sortingMode:..,suppressNotifications:..,removeObserver:..,onBeginUpdateBatch:..,onEndUpdateBatch:.., } Need to have a result to update
[task 2021-02-25T16:25:58.335Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.351Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.366Z] 16:25:58     INFO - GECKO(4192) | console.assert: {containerOpen:..,QueryInterface:..,parent:..,parentResult:..,uri:..,type:..,title:..,accessCount:..,time:..,icon:..,indentLevel:..,bookmarkIndex:..,itemId:..,dateAdded:..,lastModified:..,tags:..,pageGuid:..,bookmarkGuid:..,visitId:..,fromVisitId:..,visitType:..,state:..,hasChildren:..,childCount:..,getChild:..,getChildIndex:..,RESULT_TYPE_URI:..,RESULT_TYPE_QUERY:..,RESULT_TYPE_FOLDER:..,RESULT_TYPE_SEPARATOR:..,RESULT_TYPE_FOLDER_SHORTCUT:..,STATE_CLOSED:..,STATE_LOADING:..,STATE_OPENED:..,queryOptions:..,query:..,folderItemId:..,targetFolderGuid:.., } null container
[task 2021-02-25T16:25:58.987Z] 16:25:58     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1349
[task 2021-02-25T16:25:58.997Z] 16:25:58     INFO - GECKO(4192) | [Child 4331, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1349
[task 2021-02-25T16:25:59.033Z] 16:25:59     INFO - GECKO(4192) | [Parent 4192, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-02-25T16:25:59.223Z] 16:25:59     INFO - GECKO(4192) | JavaScript error: chrome://browser/content/browser-places.js, line 382: TypeError: can't access property "promise", this._batchBlockingDeferred is null
[task 2021-02-25T16:25:59.567Z] 16:25:59     INFO - TEST-INFO | started process screentopng
[task 2021-02-25T16:26:00.055Z] 16:26:00     INFO - TEST-INFO | screentopng: exit 0
[task 2021-02-25T16:26:00.055Z] 16:26:00     INFO - Buffered messages logged at 16:25:55
[task 2021-02-25T16:26:00.058Z] 16:26:00     INFO - Entering test bound setup
[task 2021-02-25T16:26:00.059Z] 16:26:00     INFO - Buffered messages logged at 16:25:56
[task 2021-02-25T16:26:00.060Z] 16:26:00     INFO - Leaving test bound setup
[task 2021-02-25T16:26:00.061Z] 16:26:00     INFO - Entering test bound test_selectChoose
[task 2021-02-25T16:26:00.062Z] 16:26:00     INFO - Buffered messages logged at 16:25:57
[task 2021-02-25T16:26:00.062Z] 16:26:00     INFO - TEST-PASS | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | Should have the other bookmarks folder selected by default - "Bookmarks Toolbar" == "Bookmarks Toolbar" - 
<...>
[task 2021-02-25T16:26:00.069Z] 16:26:00     INFO - TEST-PASS | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | The view should have been disconnected - true == true - 
[task 2021-02-25T16:26:00.070Z] 16:26:00     INFO - Leaving test bound test_selectChoose
[task 2021-02-25T16:26:00.071Z] 16:26:00     INFO - Entering test bound test_selectBookmarksMenu
[task 2021-02-25T16:26:00.072Z] 16:26:00     INFO - TEST-PASS | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | Should have updated the menu label - "Bookmarks Menu" == "Bookmarks Menu" - 
[task 2021-02-25T16:26:00.072Z] 16:26:00     INFO - Leaving test bound test_selectBookmarksMenu
[task 2021-02-25T16:26:00.073Z] 16:26:00     INFO - Buffered messages logged at 16:25:59
[task 2021-02-25T16:26:00.074Z] 16:26:00     INFO - Console message: [JavaScript Error: "TypeError: can't access property "promise", this._batchBlockingDeferred is null" {file: "chrome://browser/content/browser-places.js" line: 382}]
[task 2021-02-25T16:26:00.075Z] 16:26:00     INFO - Buffered messages finished
[task 2021-02-25T16:26:00.075Z] 16:26:00     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | A promise chain failed to handle a rejection: can't access property "promise", this._batchBlockingDeferred is null - stack: beginBatch/<@chrome://browser/content/browser-places.js:382:7
[task 2021-02-25T16:26:00.076Z] 16:26:00     INFO - batch/<@resource://gre/modules/PlacesTransactions.jsm:590:20
[task 2021-02-25T16:26:00.077Z] 16:26:00     INFO - enqueue/promise<@resource://gre/modules/PlacesTransactions.jsm:484:21
[task 2021-02-25T16:26:00.078Z] 16:26:00     INFO - Rejection date: Thu Feb 25 2021 16:25:59 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 282
[task 2021-02-25T16:26:00.079Z] 16:26:00     INFO - Stack trace:
[task 2021-02-25T16:26:00.079Z] 16:26:00     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:282
[task 2021-02-25T16:26:00.080Z] 16:26:00     INFO - chrome://mochikit/content/browser-test.js:nextTest:631
[task 2021-02-25T16:26:00.081Z] 16:26:00     INFO - GECKO(4192) | MEMORY STAT | vsize 3316MB | residentFast 454MB | heapAllocated 185MB
[task 2021-02-25T16:26:00.082Z] 16:26:00     INFO - TEST-OK | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | took 3794ms
[task 2021-02-25T16:26:00.083Z] 16:26:00     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe641cc0c00 == 2 [pid = 4257] [id = 6]
[task 2021-02-25T16:26:00.084Z] 16:26:00     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7fe640f15200) [pid = 4257] [serial = 16] [outer = (nil)]
[task 2021-02-25T16:26:00.084Z] 16:26:00     INFO - GECKO(4192) | [Child 4257: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7fe641cc1c00) [pid = 4257] [serial = 17] [outer = 0x7fe640f15200]
[task 2021-02-25T16:26:00.085Z] 16:26:00     INFO - checking window state
[task 2021-02-25T16:26:00.086Z] 16:26:00     INFO - TEST-START | browser/components/places/tests/browser/browser_bookmarkProperties_newFolder.js```

Comment 1

[Intermittent Failures Robot]

1 failures in 4327 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-02-22&endday=2021-02-28&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Florian Quèze [:florian]]

This becomes a high frequency intermittent on "Linux 18.04 x64 tsan opt" if I fix bug 1596165.

Comment 4

[Intermittent Failures Robot]

20 failures in 3967 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 18
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64: 6
  • debug: 6
• linux1804-64-asan: 4
  • opt: 4
• windows10-64-qr: 8
  • debug: 8
• windows10-64: 2
  • asan: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 5

[Florian Quèze [:florian]]

(In reply to Florian Quèze [:florian] from comment #3)

This becomes a high frequency intermittent on "Linux 18.04 x64 tsan opt" if I fix bug 1596165.

I added this workaround to be able to land: https://searchfox.org/mozilla-central/rev/d58860eb739af613774c942c3bb61754123e449b/browser/components/places/tests/browser/head.js#12-37

It's likely that the frequency on other slow builds (asan, debug) increased too, and we'll need to figure out the root cause at some point.

Comment 6

[Intermittent Failures Robot]

46 failures in 3125 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #31 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 40
• try: 2
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64: 6
  • debug: 6
• windows10-64: 13
  • debug: 8
  • asan: 4
  • opt: 1
• windows10-64-qr: 20
  • debug: 20
• linux1804-64-asan: 3
  • opt: 3
• windows7-32: 3
  • debug: 3
• windows10-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-03-29&endday=2021-04-04&tree=all

Comment 7

[Natalia Csoregi [:nataliaCs]]

Update:

There have been 41 failures within the last * 7 days*:

• 9 failures on Windows 10 x64 WebRender debug/opt
• 8 failures on Windows 10 x64 debug/opt
• 8 failures on Linux 18.04 x64 asan opt
• 16 failures on Linux 18.04 x64 debug

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=336089132&repo=autoland

[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - TEST-PASS | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | Should have updated the menu label - "Bookmarks Menu" == "Bookmarks Menu" -
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - Leaving test bound test_selectBookmarksMenu
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - Console message: [JavaScript Error: "TypeError: can't access property "promise", this._batchBlockingDeferred is null" {file: "chrome://browser/content/browser-places.js" line: 376}]
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - Buffered messages finished
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | A promise chain failed to handle a rejection: can't access property "promise", this._batchBlockingDeferred is null - stack: beginBatch/<@chrome://browser/content/browser-places.js:376:7
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - batch/<@resource://gre/modules/PlacesTransactions.jsm:590:20
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - enqueue/promise<@resource://gre/modules/PlacesTransactions.jsm:484:21
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - Rejection date: Sat Apr 10 2021 22:39:40 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 285
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - Stack trace:
[task 2021-04-10T22:39:40.631Z] 22:39:40 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:285
[task 2021-04-10T22:39:40.632Z] 22:39:40 INFO - chrome://mochikit/content/browser-test.js:nextTest:631
[task 2021-04-10T22:39:40.632Z] 22:39:40 INFO - GECKO(8296) | MEMORY STAT | vsize 2104380MB | vsizeMaxContiguous 67848600MB | residentFast 401MB | heapAllocated 172MB
[task 2021-04-10T22:39:40.632Z] 22:39:40 INFO - TEST-OK | browser/components/places/tests/browser/browser_bookmarkProperties_folderSelection.js | took 2944ms

Florian, are you still looking into this?

Comment 8

[Intermittent Failures Robot]

44 failures in 3854 pushes (0.011 failures/push) were associated with this bug in the last 7 days.

This is the #31 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 34
• try: 4
• mozilla-central: 6

Platform and build breakdown:

• linux1804-64-asan: 8
  • opt: 8
• windows10-64: 9
  • debug: 6
  • asan: 3
• linux1804-64: 17
  • debug: 17
• windows10-64-qr: 10
  • debug: 9
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-05&endday=2021-04-11&tree=all

Comment 9

[Intermittent Failures Robot]

16 failures in 649 pushes (0.025 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• try: 2

Platform and build breakdown:

• windows10-64: 1
  • debug: 1
• linux1804-64: 7
  • debug: 7
• windows10-64-qr: 3
  • debug: 3
• linux1804-64-asan: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-13&endday=2021-04-13&tree=all

Comment 10

[Intermittent Failures Robot]

17 failures in 1076 pushes (0.016 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 16
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64: 6
  • debug: 6
• windows10-64-qr: 4
  • debug: 4
• linux1804-64-asan: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-14&endday=2021-04-14&tree=all

Comment 11

[Mark Banner (:standard8)]

I think I might have found a solution for this, but I need to run it through try server.

Comment 12

[Mark Banner (:standard8)]

Attachment: Bug 1695011 - Add a delay in browser_bookmarkProperties_folderSelection.js to reduces instances of an intermittent. r?florian!

Comment 13

[Mark Banner (:standard8)]

The patch here should reduce intermittents to around the previous level. We've still some work to do to figure out what exactly is going on here, I'll hopefully be able to look at that next week.

Note to self: Investigate why StarUI.endBatch gets called before the added transaction/batch call in beginBatch. Also what the side effects are for the other tests.

Comment 14

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/283e7717cf79
Add a delay in browser_bookmarkProperties_folderSelection.js to reduces instances of an intermittent. r=florian

Comment 15

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/283e7717cf79

Comment 16

[Intermittent Failures Robot]

44 failures in 4255 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 40
• try: 2
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-asan: 14
  • opt: 14
• linux1804-64: 17
  • debug: 17
• windows10-64-qr: 10
  • debug: 10
• windows10-64: 1
  • debug: 1
• linux1804-64-qr: 1
  • debug: 1
• linux1804-64-tsan: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-12&endday=2021-04-18&tree=all

Comment 17

[Intermittent Failures Robot]

9 failures in 3224 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• autoland: 8

Platform and build breakdown:

• linux1804-64: 2
  • debug: 2
• linux1804-64-tsan: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-19&endday=2021-04-25&tree=all

Comment 18

[Intermittent Failures Robot]

10 failures in 3706 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10

Platform and build breakdown:

• linux1804-64: 1
  • debug: 1
• linux1804-64-tsan: 9
  • opt: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-04-26&endday=2021-05-02&tree=all

Comment 19

[Intermittent Failures Robot]

3 failures in 3279 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-tsan: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1695011&startday=2021-05-03&endday=2021-05-09&tree=all

Comment 20

[Mark Banner (:standard8)]

Attachment: Bug 1695011 - Wait for star ui initialisation to complete before continuing with tests to avoid intermittents. r?mak!

Comment 21

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/49178b889d90
Wait for star ui initialisation to complete before continuing with tests to avoid intermittents. r=mak

Comment 22

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/49178b889d90

Comment 23

[Cosmin Sabou [:CosminS]]

Mark, seems like the first test browser_bookmarkProperties_folderSelection.js was fixed https://tinyurl.com/ydlaqtre, but the unexpected-fails from https://treeherder.mozilla.org/intermittent-failures/bugdetails?startday=2021-05-05&endday=2021-05-12&tree=trunk&bug=1695011 cropped up and I ended up filling Bug 1710710 until I caught has actually happened.
Any chance you could tackle the other failing tests? Also should we close Bug 1710710 as a duplicate to this one as it's always been around since this was filed? Thank you.

Comment 24

[Mark Banner (:standard8)]

Interesting, I didn't see any of those failures on the try run that I did.

In any case, it looks like we fixed something here, so lets deal with the new ones in bug 1710710 since that's where they'll get duped to.