1775361 - Intermittent TV accessible/tests/browser/e10s/browser_caching_hittest.js | Uncaught exception in test - at chrome://mochitests/content/browser/accessible/tests/browser/shared-head.js:788 - TypeError: can't access property "firstChild", acc is null

Status: RESOLVED FIXED

(Core :: Disability Access APIs, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=382068416&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TMuV8k8uQVy8I9RTRnbVlg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TMuV8k8uQVy8I9RTRnbVlg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2022-06-21T20:48:00.686Z] 20:48:00     INFO - TEST-START | accessible/tests/browser/e10s/browser_caching_hittest.js
[task 2022-06-21T20:48:00.699Z] 20:48:00     INFO - GECKO(3240) | [Child 3253: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 107f65800 == 0 [pid = 3253] [id = 0] [url = http://example.net/document-builder.sjs?html=%3C%21doctype+html%3E%0A++++++++%3Chtml%3E%0A++++++++++%3Chead%3E%0A++++++++++++%3Cmeta+charset%3D%22utf-8%22%2F%3E%0A++++++++++++%3Ctitle%3EAccessibility+Fission+Test%3C%2Ftitle%3E%0A++++++++++%3C%2Fhead%3E%0A++++++++++%3Cbody+id%3D%22default-iframe-body-id%22%3E%0A++%3Cdiv+role%3D%22list%22+id%3D%22list%22%3E%0A++++%3Cdiv+role%3D%22listitem%22+id%3D%22listitem%22%3E%3Cspan+title%3D%22foo%22+id%3D%22inner%22%3Einner%3C%2Fspan%3Eitem%3C%2Fdiv%3E%0A++%3C%2Fdiv%3E%0A%0A++%3Cspan+role%3D%22button%22%3Ebutton1%3C%2Fspan%3E%3Cspan+role%3D%22button%22+id%3D%22btn%22%3Ebutton2%3C%2Fspan%3E%0A%0A++%3Cspan+role%3D%22textbox%22%3Etextbox1%3C%2Fspan%3E%3Cspan+role%3D%22textbox%22+id%3D%22txt%22%3Etextbox2%3C%2Fspan%3E%0A%0A++%3Cdiv+id%3D%22outofflow%22+style%3D%22width%3A+10px%3B+height%3A+10px%3B+position%3A+absolute%3B+left%3A+0px%3B+top%3A+0px%3B+background-color%3A+yellow%3B%22%3E%0A++%3C%2Fdiv%3E%0A++%3Cdiv+id%3D%22area%22+style%3D%22width%3A+100p]
[task 2022-06-21T20:48:00.702Z] 20:48:00     INFO - GECKO(3240) | [Child 3247: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 11981c000 == 10 [pid = 3247] [id = 9]
[task 2022-06-21T20:48:00.703Z] 20:48:00     INFO - GECKO(3240) | [Child 3247: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 25 (119e25600) [pid = 3247] [serial = 25] [outer = 0]
[task 2022-06-21T20:48:00.703Z] 20:48:00     INFO - GECKO(3240) | [Child 3247: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 26 (11981d400) [pid = 3247] [serial = 26] [outer = 119e25600]
[task 2022-06-21T20:48:00.706Z] 20:48:00     INFO - GECKO(3240) | [Child 3243, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-06-21T20:48:00.717Z] 20:48:00     INFO - GECKO(3240) | [Child 3253: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (10569e2b0) [pid = 3253] [serial = 1] [outer = 0] [url = http://example.net/document-builder.sjs?html=%3C%21doctype+html%3E%0A++++++++%3Chtml%3E%0A++++++++++%3Chead%3E%0A++++++++++++%3Cmeta+charset%3D%22utf-8%22%2F%3E%0A++++++++++++%3Ctitle%3EAccessibility+Fission+Test%3C%2Ftitle%3E%0A++++++++++%3C%2Fhead%3E%0A++++++++++%3Cbody+id%3D%22default-iframe-body-id%22%3E%0A++%3Cdiv+role%3D%22list%22+id%3D%22list%22%3E%0A++++%3Cdiv+role%3D%22listitem%22+id%3D%22listitem%22%3E%3Cspan+title%3D%22foo%22+id%3D%22inner%22%3Einner%3C%2Fspan%3Eitem%3C%2Fdiv%3E%0A++%3C%2Fdiv%3E%0A%0A++%3Cspan+role%3D%22button%22%3Ebutton1%3C%2Fspan%3E%3Cspan+role%3D%22button%22+id%3D%22btn%22%3Ebutton2%3C%2Fspan%3E%0A%0A++%3Cspan+role%3D%22textbox%22%3Etextbox1%3C%2Fspan%3E%3Cspan+role%3D%22textbox%22+id%3D%22txt%22%3Etextbox2%3C%2Fspan%3E%0A%0A++%3Cdiv+id%3D%22outofflow%22+style%3D%22width%3A+10px%3B+height%3A+10px%3B+position%3A+absolute%3B+left%3A+0px%3B+top%3A+0px%3B+background-color%3A+yellow%3B%22%3E%0A++%3C%2Fdiv%3E%0A++%3Cdiv+id%3D%22area%22+style%3D%22width%3A+100p]
[task 2022-06-21T20:48:00.718Z] 20:48:00     INFO - GECKO(3240) | [Child 3253: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (107f66800) [pid = 3253] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-06-21T20:48:00.719Z] 20:48:00     INFO - GECKO(3240) | [Child 3253: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (107f6e800) [pid = 3253] [serial = 3] [outer = 0] [url = http://example.net/document-builder.sjs?html=%3C%21doctype+html%3E%0A++++++++%3Chtml%3E%0A++++++++++%3Chead%3E%0A++++++++++++%3Cmeta+charset%3D%22utf-8%22%2F%3E%0A++++++++++++%3Ctitle%3EAccessibility+Fission+Test%3C%2Ftitle%3E%0A++++++++++%3C%2Fhead%3E%0A++++++++++%3Cbody+id%3D%22default-iframe-body-id%22%3E%0A++%3Cdiv+role%3D%22list%22+id%3D%22list%22%3E%0A++++%3Cdiv+role%3D%22listitem%22+id%3D%22listitem%22%3E%3Cspan+title%3D%22foo%22+id%3D%22inner%22%3Einner%3C%2Fspan%3Eitem%3C%2Fdiv%3E%0A++%3C%2Fdiv%3E%0A%0A++%3Cspan+role%3D%22button%22%3Ebutton1%3C%2Fspan%3E%3Cspan+role%3D%22button%22+id%3D%22btn%22%3Ebutton2%3C%2Fspan%3E%0A%0A++%3Cspan+role%3D%22textbox%22%3Etextbox1%3C%2Fspan%3E%3Cspan+role%3D%22textbox%22+id%3D%22txt%22%3Etextbox2%3C%2Fspan%3E%0A%0A++%3Cdiv+id%3D%22outofflow%22+style%3D%22width%3A+10px%3B+height%3A+10px%3B+position%3A+absolute%3B+left%3A+0px%3B+top%3A+0px%3B+background-color%3A+yellow%3B%22%3E%0A++%3C%2Fdiv%3E%0A++%3Cdiv+id%3D%22area%22+style%3D%22width%3A+100p]
[task 2022-06-21T20:48:00.720Z] 20:48:00     INFO - GECKO(3240) | [Child 3253, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-06-21T20:48:00.720Z] 20:48:00     INFO - GECKO(3240) | [Child 3253, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-06-21T20:48:00.724Z] 20:48:00     INFO - GECKO(3240) | [Child 3253, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4569
[task 2022-06-21T20:48:00.748Z] 20:48:00     INFO - GECKO(3240) | [Child 3247: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 27 (1198e2c00) [pid = 3247] [serial = 27] [outer = 119e25600]
[task 2022-06-21T20:48:01.069Z] 20:48:01     INFO - GECKO(3240) | must wait for focus in content
[task 2022-06-21T20:48:01.090Z] 20:48:01     INFO - TEST-INFO | started process screencapture
[task 2022-06-21T20:48:01.209Z] 20:48:01     INFO - TEST-INFO | screencapture: exit 0
[task 2022-06-21T20:48:01.210Z] 20:48:01     INFO - Buffered messages logged at 20:48:00
[task 2022-06-21T20:48:01.210Z] 20:48:01     INFO - Entering test bound 
[task 2022-06-21T20:48:01.211Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | Recieved document load complete event - 
[task 2022-06-21T20:48:01.211Z] 20:48:01     INFO - Buffered messages logged at 20:48:01
[task 2022-06-21T20:48:01.212Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | Actually remote browser - 
[task 2022-06-21T20:48:01.212Z] 20:48:01     INFO - Buffered messages finished
[task 2022-06-21T20:48:01.213Z] 20:48:01     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/e10s/browser_caching_hittest.js | Uncaught exception in test - at chrome://mochitests/content/browser/accessible/tests/browser/shared-head.js:788 - TypeError: can't access property "firstChild", acc is null
[task 2022-06-21T20:48:01.213Z] 20:48:01     INFO - Stack trace:
[task 2022-06-21T20:48:01.213Z] 20:48:01     INFO - waitForImageMap@chrome://mochitests/content/browser/accessible/tests/browser/shared-head.js:788:7
[task 2022-06-21T20:48:01.213Z] 20:48:01     INFO - runTests@chrome://mochitests/content/browser/accessible/tests/browser/e10s/browser_caching_hittest.js:74:9
[task 2022-06-21T20:48:01.213Z] 20:48:01     INFO - accessibleTask/</<@chrome://mochitests/content/browser/accessible/tests/browser/shared-head.js:558:15
[task 2022-06-21T20:48:01.214Z] 20:48:01     INFO - Leaving test bound 
[task 2022-06-21T20:48:01.214Z] 20:48:01     INFO - Entering test bound 
[task 2022-06-21T20:48:01.214Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 115368400 == 5 [pid = 3246] [id = 4]
[task 2022-06-21T20:48:01.215Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (10c09f130) [pid = 3246] [serial = 11] [outer = 0]
[task 2022-06-21T20:48:01.215Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (123c73c00) [pid = 3246] [serial = 12] [outer = 10c09f130]
[task 2022-06-21T20:48:01.216Z] 20:48:01     INFO - GECKO(3240) | [Child 3247, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-06-21T20:48:01.216Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (123c7b000) [pid = 3246] [serial = 13] [outer = 10c09f130]
[task 2022-06-21T20:48:01.217Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 123c7f800 == 6 [pid = 3246] [id = 5]
[task 2022-06-21T20:48:01.217Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (10c09f6a0) [pid = 3246] [serial = 14] [outer = 0]
[task 2022-06-21T20:48:01.218Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (123c80400) [pid = 3246] [serial = 15] [outer = 10c09f6a0]
[task 2022-06-21T20:48:01.218Z] 20:48:01     INFO - GECKO(3240) | [Child 3246: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (10fc05800) [pid = 3246] [serial = 16] [outer = 10c09f6a0]
[task 2022-06-21T20:48:01.219Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | Recieved document load complete event - 
[task 2022-06-21T20:48:01.261Z] 20:48:01     INFO - GECKO(3240) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/rx/57l2fsmj07d7dvyswc7__c5w000014/T/tmpp8enzue3.mozrunner/runtests_leaks_tab_pid3265.log
[task 2022-06-21T20:48:01.262Z] 20:48:01     INFO - GECKO(3240) | [3265, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-06-21T20:48:01.488Z] 20:48:01     INFO - GECKO(3240) | must wait for focus in content
[task 2022-06-21T20:48:01.496Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | Actually remote browser - 
[task 2022-06-21T20:48:01.506Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | IFRAME is in the same process. - 
[task 2022-06-21T20:48:01.524Z] 20:48:01     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_hittest.js | X in CSS pixels is calculated correctly - Got 32 - true == true - 

Comment 1

[BugBot [:suhaib / :marco]]

:morgan, since you are the author of the regressor, bug 1772861, could you take a look?
For more information, please visit auto_nag documentation.

Comment 2

[BugBot [:suhaib / :marco]]

Set release status flags based on info from the regressing bug 1772861

Comment 3

[Morgan Reschenberg [:morgan]]

Hmm, I'm not sure what's happening here -- seems like a timing issue where the acc isn't created when we query the image map? In any case I can add an additional check.

Comment 4

[Morgan Reschenberg [:morgan]]

Attachment: Bug 1775361: Wait for a show event on the imagemap before querying its firstChild r?eeejay

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775361&startday=2022-06-20&endday=2022-06-26&tree=all

Comment 6

[BugBot [:suhaib / :marco]]

Set release status flags based on info from the regressing bug 1772861

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/803910e14417
Wait for a show event on the imagemap before querying its firstChild r=eeejay

Comment 8

[Marian-Vasile Laza]

https://hg.mozilla.org/mozilla-central/rev/803910e14417

Comment 9

[BugBot [:suhaib / :marco]]

The patch landed in nightly and beta is affected.
:morgan, is this bug important enough to require an uplift?

• If yes, please nominate the patch for beta approval.
• If no, please set status-firefox103 to wontfix.

For more information, please visit auto_nag documentation.