Closed Bug 1530603 Opened 6 years ago Closed 6 years ago

Intermittent toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Uncaught exception - Waiting for the search string to filter logins - threw exception: TypeError: window.document.getElementById(...) is null

Categories

(Toolkit :: Password Manager, defect, P3)

Product:
Toolkit
Component:
Password Manager
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla68
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox66 --- unaffected
firefox67 --- fixed
firefox68 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])

#[markdown(off)]
Filed by: cbrindusan [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=230470160&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/Ql3KmQpkQh-CMl6YGegEPA/runs/0/artifacts/public/logs/live_backing.log

[task 2019-02-26T05:54:06.870Z] 05:54:06 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js
[task 2019-02-26T05:54:07.076Z] 05:54:07 INFO - GECKO(20372) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpk1amZy.mozrunner/runtests_leaks_tab_pid20566.log
[task 2019-02-26T05:54:07.262Z] 05:54:07 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: '!mSelection', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 4933
[task 2019-02-26T05:54:07.262Z] 05:54:07 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: '!editActionData.CanHandle()', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 1291
[task 2019-02-26T05:54:07.379Z] 05:54:07 INFO - GECKO(20372) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-02-26T05:54:07.379Z] 05:54:07 INFO - GECKO(20372) | [Child 20566, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/base/nsIOService.cpp, line 942
[task 2019-02-26T05:54:07.387Z] 05:54:07 INFO - GECKO(20372) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-02-26T05:54:07.389Z] 05:54:07 INFO - GECKO(20372) | [Child 20555, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/base/nsIOService.cpp, line 942
[task 2019-02-26T05:54:07.512Z] 05:54:07 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 970
[task 2019-02-26T05:54:07.513Z] 05:54:07 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 970
[task 2019-02-26T05:54:07.676Z] 05:54:07 INFO - GECKO(20372) | ++DOCSHELL 0x7f6616da0800 == 1 [pid = 20555] [id = {d721ef87-0b0a-4a44-8513-930bc6ba2305}]
[task 2019-02-26T05:54:07.773Z] 05:54:07 INFO - GECKO(20372) | ++DOCSHELL 0x7fa01139e800 == 1 [pid = 20566] [id = {a245f023-c730-4cf0-839f-b6005198ac62}]
[task 2019-02-26T05:54:07.809Z] 05:54:07 INFO - GECKO(20372) | ++DOMWINDOW == 1 (0x7f6616d12000) [pid = 20555] [serial = 1] [outer = (nil)]
[task 2019-02-26T05:54:07.858Z] 05:54:07 INFO - GECKO(20372) | ++DOMWINDOW == 1 (0x7fa011312000) [pid = 20566] [serial = 1] [outer = (nil)]
[task 2019-02-26T05:54:07.989Z] 05:54:07 INFO - GECKO(20372) | ++DOMWINDOW == 2 (0x7f6616e6d800) [pid = 20555] [serial = 2] [outer = 0x7f6616d12000]
[task 2019-02-26T05:54:08.086Z] 05:54:08 INFO - GECKO(20372) | ++DOMWINDOW == 2 (0x7fa011597800) [pid = 20566] [serial = 2] [outer = 0x7fa011312000]
[task 2019-02-26T05:54:08.114Z] 05:54:08 INFO - GECKO(20372) | ++DOMWINDOW == 3 (0x7f6617b7e400) [pid = 20555] [serial = 3] [outer = 0x7f6616d12000]
[task 2019-02-26T05:54:08.317Z] 05:54:08 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 122
[task 2019-02-26T05:54:08.536Z] 05:54:08 INFO - GECKO(20372) | ++DOMWINDOW == 3 (0x7fa010f89800) [pid = 20566] [serial = 3] [outer = 0x7fa011312000]
[task 2019-02-26T05:54:09.690Z] 05:54:09 INFO - GECKO(20372) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpk1amZy.mozrunner/runtests_leaks_tab_pid20607.log
[task 2019-02-26T05:54:09.936Z] 05:54:09 INFO - GECKO(20372) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-02-26T05:54:09.937Z] 05:54:09 INFO - GECKO(20372) | [Child 20607, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/base/nsIOService.cpp, line 942
[task 2019-02-26T05:54:10.061Z] 05:54:10 INFO - GECKO(20372) | [Parent 20372, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 970
[task 2019-02-26T05:54:10.293Z] 05:54:10 INFO - GECKO(20372) | ++DOCSHELL 0x7f0156415000 == 6 [pid = 20372] [id = {1e11f01f-a6ff-480c-afef-a4ddf47b2b02}]
[task 2019-02-26T05:54:10.293Z] 05:54:10 INFO - GECKO(20372) | ++DOMWINDOW == 13 (0x7f015305b000) [pid = 20372] [serial = 13] [outer = (nil)]
[task 2019-02-26T05:54:10.293Z] 05:54:10 INFO - GECKO(20372) | ++DOMWINDOW == 14 (0x7f015305b800) [pid = 20372] [serial = 14] [outer = 0x7f015305b000]
[task 2019-02-26T05:54:10.556Z] 05:54:10 INFO - GECKO(20372) | [Child 20555, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 959
[task 2019-02-26T05:54:10.557Z] 05:54:10 INFO - GECKO(20372) | [Child 20555, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 809
[task 2019-02-26T05:54:10.565Z] 05:54:10 INFO - GECKO(20372) | [Child 20555, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 959
[task 2019-02-26T05:54:10.566Z] 05:54:10 INFO - GECKO(20372) | [Child 20555, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805303F4: file /builds/worker/workspace/build/src/dom/security/nsContentSecurityManager.cpp, line 809
[task 2019-02-26T05:54:10.689Z] 05:54:10 INFO - TEST-INFO | started process screentopng
[task 2019-02-26T05:54:11.166Z] 05:54:11 INFO - TEST-INFO | screentopng: exit 0
[task 2019-02-26T05:54:11.168Z] 05:54:11 INFO - Buffered messages logged at 05:54:06
[task 2019-02-26T05:54:11.169Z] 05:54:11 INFO - Entering test bound common_initialize
[task 2019-02-26T05:54:11.171Z] 05:54:11 INFO - Leaving test bound common_initialize
[task 2019-02-26T05:54:11.172Z] 05:54:11 INFO - Entering test bound test_initialize
[task 2019-02-26T05:54:11.174Z] 05:54:11 INFO - Leaving test bound test_initialize
[task 2019-02-26T05:54:11.175Z] 05:54:11 INFO - Entering test bound test_autocomplete_footer
[task 2019-02-26T05:54:11.176Z] 05:54:11 INFO - Buffered messages logged at 05:54:08
[task 2019-02-26T05:54:11.178Z] 05:54:11 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Got popup -
[task 2019-02-26T05:54:11.182Z] 05:54:11 INFO - Buffered messages logged at 05:54:09
[task 2019-02-26T05:54:11.184Z] 05:54:11 INFO - content window focused
[task 2019-02-26T05:54:11.185Z] 05:54:11 INFO - Buffered messages logged at 05:54:10
[task 2019-02-26T05:54:11.187Z] 05:54:11 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | autocomplete shown -
[task 2019-02-26T05:54:11.189Z] 05:54:11 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Got footer richlistitem -
[task 2019-02-26T05:54:11.193Z] 05:54:11 INFO - Login dialog was opened
[task 2019-02-26T05:54:11.195Z] 05:54:11 INFO - Buffered messages finished
[task 2019-02-26T05:54:11.197Z] 05:54:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Uncaught exception - Waiting for the search string to filter logins - threw exception: TypeError: window.document.getElementById(...) is null

Summary: Intermittent [TV] toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Uncaught exception - Waiting for the search string to filter logins - threw exception: TypeError: window.document.getElementById(...) is null → Intermittent toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Uncaught exception - Waiting for the search string to filter logins - threw exception: TypeError: window.document.getElementById(...) is null

This seems to be from Bug 1189618, first occurrence was when this merge landed https://hg.mozilla.org/integration/mozilla-inbound/pushloghtml?changeset=c4ce50209f1966dfd7b10c6060b5a625395e0dca

There are 29 total failures in the last 7 days on linux64, linux64-ccov and windows10-64-ccov all debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=235715066&repo=mozilla-central&lineNumber=16121

[task 2019-03-24T11:10:17.419Z] 11:10:17 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js
[task 2019-03-24T11:10:17.565Z] 11:10:17 INFO - GECKO(6800) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-03-24T11:10:17.566Z] 11:10:17 INFO - GECKO(6800) | [Child 7012, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/base/nsIOService.cpp, line 941
[task 2019-03-24T11:10:17.566Z] 11:10:17 INFO - GECKO(6800) | [CodeCoverage] Setting handlers for process 7012.
[task 2019-03-24T11:10:17.724Z] 11:10:17 INFO - GECKO(6800) | ++DOCSHELL 0x7fb4683ad000 == 1 [pid = 7012] [id = {e91d08e4-1136-4add-8dd8-f0b0d3ca5230}]
[task 2019-03-24T11:10:17.772Z] 11:10:17 INFO - GECKO(6800) | ++DOMWINDOW == 1 (0x7fb4695e8f20) [pid = 7012] [serial = 1] [outer = (nil)]
[task 2019-03-24T11:10:17.950Z] 11:10:17 INFO - GECKO(6800) | ++DOMWINDOW == 2 (0x7fb46951e400) [pid = 7012] [serial = 2] [outer = 0x7fb4695e8f20]
[task 2019-03-24T11:10:18.047Z] 11:10:18 INFO - GECKO(6800) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp0wP6q4.mozrunner/runtests_leaks_tab_pid7034.log
[task 2019-03-24T11:10:18.116Z] 11:10:18 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: '!mSelection', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 4928
[task 2019-03-24T11:10:18.116Z] 11:10:18 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: '!editActionData.CanHandle()', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 1292
[task 2019-03-24T11:10:18.164Z] 11:10:18 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 955
[task 2019-03-24T11:10:18.241Z] 11:10:18 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 955
[task 2019-03-24T11:10:18.338Z] 11:10:18 INFO - GECKO(6800) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-03-24T11:10:18.338Z] 11:10:18 INFO - GECKO(6800) | [Child 7034, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/base/nsIOService.cpp, line 941
[task 2019-03-24T11:10:18.338Z] 11:10:18 INFO - GECKO(6800) | [CodeCoverage] Setting handlers for process 7034.
[task 2019-03-24T11:10:18.500Z] 11:10:18 INFO - GECKO(6800) | ++DOCSHELL 0x7f558e5ac800 == 1 [pid = 7034] [id = {ab5bca61-c0b4-4b73-b22d-244e321676e6}]
[task 2019-03-24T11:10:18.548Z] 11:10:18 INFO - GECKO(6800) | ++DOMWINDOW == 1 (0x7f558f7add40) [pid = 7034] [serial = 1] [outer = (nil)]
[task 2019-03-24T11:10:18.730Z] 11:10:18 INFO - GECKO(6800) | ++DOMWINDOW == 2 (0x7f558f8c9000) [pid = 7034] [serial = 2] [outer = 0x7f558f7add40]
[task 2019-03-24T11:10:19.074Z] 11:10:19 INFO - GECKO(6800) | ++DOMWINDOW == 3 (0x7f558edbf800) [pid = 7034] [serial = 3] [outer = 0x7f558f7add40]
[task 2019-03-24T11:10:20.201Z] 11:10:20 INFO - GECKO(6800) | ++DOCSHELL 0x7f676f0e8800 == 6 [pid = 6800] [id = {59a718de-150f-49c5-912c-39ec48321fa3}]
[task 2019-03-24T11:10:20.201Z] 11:10:20 INFO - GECKO(6800) | ++DOMWINDOW == 13 (0x7f67681c8020) [pid = 6800] [serial = 13] [outer = (nil)]
[task 2019-03-24T11:10:20.201Z] 11:10:20 INFO - GECKO(6800) | ++DOMWINDOW == 14 (0x7f676dd90000) [pid = 6800] [serial = 14] [outer = 0x7f67681c8020]
[task 2019-03-24T11:10:20.363Z] 11:10:20 INFO - GECKO(6800) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp0wP6q4.mozrunner/runtests_leaks_tab_pid7063.log
[task 2019-03-24T11:10:20.378Z] 11:10:20 INFO - GECKO(6800) | [Parent 6800, StreamTrans #3] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 371
[task 2019-03-24T11:10:20.439Z] 11:10:20 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 994
[task 2019-03-24T11:10:20.569Z] 11:10:20 INFO - GECKO(6800) | [Parent 6800, Main Thread] WARNING: '!parent', file /builds/worker/workspace/build/src/netwerk/ipc/NeckoParent.cpp, line 955
[task 2019-03-24T11:10:20.569Z] 11:10:20 INFO - TEST-INFO | started process screentopng
[task 2019-03-24T11:10:20.936Z] 11:10:20 INFO - TEST-INFO | screentopng: exit 0
[task 2019-03-24T11:10:20.936Z] 11:10:20 INFO - Buffered messages logged at 11:10:17
[task 2019-03-24T11:10:20.936Z] 11:10:20 INFO - Entering test bound common_initialize
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - Leaving test bound common_initialize
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - Entering test bound test_initialize
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - Leaving test bound test_initialize
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - Entering test bound test_autocomplete_footer_onclick
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - Buffered messages logged at 11:10:19
[task 2019-03-24T11:10:20.937Z] 11:10:20 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Got popup -
[task 2019-03-24T11:10:20.939Z] 11:10:20 INFO - content window focused
[task 2019-03-24T11:10:20.939Z] 11:10:20 INFO - Buffered messages logged at 11:10:20
[task 2019-03-24T11:10:20.939Z] 11:10:20 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | autocomplete popup shown -
[task 2019-03-24T11:10:20.940Z] 11:10:20 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Got footer richlistitem -
[task 2019-03-24T11:10:20.941Z] 11:10:20 INFO - Login dialog was opened
[task 2019-03-24T11:10:20.942Z] 11:10:20 INFO - Buffered messages finished
[task 2019-03-24T11:10:20.943Z] 11:10:20 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autocomplete_footer.js | Uncaught exception - Waiting for the search string to filter logins - threw exception: TypeError: window.document.getElementById(...) is null
[task 2019-03-24T11:10:20.944Z] 11:10:20 INFO - Leaving test bound test_autocomplete_footer_onclick
[task 2019-03-24T11:10:20.944Z] 11:10:20 INFO - Entering test bound test_autocomplete_footer_keydown

prathiksha can you please take a look?

Flags: needinfo?(prathikshaprasadsuman)
Whiteboard: [stockwell needswork:owner]
Pushed by mozilla@noorenberghe.ca: https://hg.mozilla.org/integration/mozilla-inbound/rev/648419554d95 Wait for #filter in browser_autocomplete_footer.js. r=intermittent

I was hitting this failure locally in a debug build so decided to fix it.

Assignee: nobody → MattN+bmo
Status: NEW → ASSIGNED
Flags: needinfo?(prathikshaprasadsuman)
Priority: P5 → P3

https://hg.mozilla.org/mozilla-central/rev/648419554d95

Status: ASSIGNED → RESOLVED
Closed: 6 years ago
status-firefox68: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
You need to log in before you can comment on or make changes to this bug.