Closed Bug 1909279 Opened 2 years ago Closed 1 year ago

Intermittent toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | single tracking bug

Categories

(Core :: Privacy: Anti-Tracking, defect, P5)

Product:
Core
Component:
Privacy: Anti-Tracking
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2024-07-22T16:16:14.266Z] 16:16:14     INFO - TEST-PASS | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'fail_actor_destroyed' has correct state. - 
[task 2024-07-22T16:16:14.267Z] 16:16:14     INFO - Opening https://example.com/browser/toolkit/components/cookiebanners/test/browser/file_delayed_banner_load.html
[task 2024-07-22T16:16:14.268Z] 16:16:14     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use <!DOCTYPE html>." {file: "https://example.com/browser/toolkit/components/cookiebanners/test/browser/file_delayed_banner_load.html" line: 0}]
[task 2024-07-22T16:16:14.268Z] 16:16:14     INFO - Buffered messages logged at 16:16:14
[task 2024-07-22T16:16:14.268Z] 16:16:14     INFO - Verify the cookie banner state.
[task 2024-07-22T16:16:14.269Z] 16:16:14     INFO - Buffered messages finished
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | The banner element should be hidden - true == false - got true, expected false (operator ==)
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - Stack trace:
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - is@resource://testing-common/SpecialPowersSandbox.sys.mjs:88:21
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - @chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:133:9
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - execute@resource://testing-common/SpecialPowersSandbox.sys.mjs:139:12
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - _spawnTask@resource://testing-common/SpecialPowersChild.sys.mjs:1617:15
[task 2024-07-22T16:16:14.271Z] 16:16:14     INFO - receiveMessage@resource://testing-common/SpecialPowersChild.sys.mjs:256:21
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - JSActor query*receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1383:14
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - JSActor query*spawn@resource://testing-common/SpecialPowersChild.sys.mjs:1546:17
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - verifyBannerState@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:126:23
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - openPageAndVerify@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:181:9
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - async*test_clicking_with_delayed_banner@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js:25:9
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-07-22T16:16:14.272Z] 16:16:14     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-07-22T16:16:14.273Z] 16:16:14     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-07-22T16:16:14.273Z] 16:16:14     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | The build click state is correct. - "NoClick" == "OptOut" - got "NoClick", expected "OptOut" (operator ==)
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - Stack trace:
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - is@resource://testing-common/SpecialPowersSandbox.sys.mjs:88:21
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - @chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:144:9
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - execute@resource://testing-common/SpecialPowersSandbox.sys.mjs:139:12
[task 2024-07-22T16:16:14.275Z] 16:16:14     INFO - _spawnTask@resource://testing-common/SpecialPowersChild.sys.mjs:1617:15
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - receiveMessage@resource://testing-common/SpecialPowersChild.sys.mjs:256:21
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - JSActor query*receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1383:14
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - JSActor query*spawn@resource://testing-common/SpecialPowersChild.sys.mjs:1546:17
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - verifyBannerState@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:126:23
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - openPageAndVerify@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:181:9
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - async*test_clicking_with_delayed_banner@chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js:25:9
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-07-22T16:16:14.276Z] 16:16:14     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-07-22T16:16:14.277Z] 16:16:14     INFO - GECKO(1032) | console.debug: CookieBannerChild: "Submitted clickResult telemetry" "fail" "banner_not_found" ({success:false, successStage:null, currentStage:"mutation_post_load", failReason:"banner_not_found"})
[task 2024-07-22T16:16:14.277Z] 16:16:14     INFO - GECKO(1032) | console.debug: CookieBannerChild: "Submitted querySelector telemetry" ({isTopLevel:true, querySelectorCount:1, querySelectorTimeUS:37, querySelectorTimeMS:0.03669999999988249})
[task 2024-07-22T16:16:14.278Z] 16:16:14     INFO - GECKO(1032) | console.debug: CookieBannerChild: "Submitted cookieInjectionFail telemetry" ({bannerDetectedAfterCookieInjection:false})
[task 2024-07-22T16:16:14.278Z] 16:16:14     INFO - GECKO(1032) | console.debug: CookieBannerChild: "Submitted detectedCMP telemetry" ({detectedCMP:[]})
[task 2024-07-22T16:16:19.657Z] 16:16:19     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-22T16:16:19.664Z] 16:16:19     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'success' has correct state. - Got null, expected 1
[task 2024-07-22T16:16:19.664Z] 16:16:19     INFO - Stack trace:
[task 2024-07-22T16:16:19.664Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:test_is:1620
[task 2024-07-22T16:16:19.664Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testMetricState:450
[task 2024-07-22T16:16:19.664Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testClickResultTelemetryInternal:472
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testClickResultTelemetry:385
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js:test_clicking_with_delayed_banner:33
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:handleTask:1139
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1211
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1353
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1128
[task 2024-07-22T16:16:19.665Z] 16:16:19     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2024-07-22T16:16:19.666Z] 16:16:19     INFO - TEST-PASS | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'success_cookie_injected' has correct state. - 
[task 2024-07-22T16:16:19.667Z] 16:16:19     INFO - TEST-PASS | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'success_dom_content_loaded' has correct state. - 
[task 2024-07-22T16:16:19.667Z] 16:16:19     INFO - TEST-PASS | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'success_mutation_pre_load' has correct state. - 
[task 2024-07-22T16:16:19.668Z] 16:16:19     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'success_mutation_post_load' has correct state. - Got null, expected 1
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - Stack trace:
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:test_is:1620
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testMetricState:450
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testClickResultTelemetryInternal:472
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testClickResultTelemetry:385
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js:test_clicking_with_delayed_banner:33
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:handleTask:1139
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1211
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1353
[task 2024-07-22T16:16:19.671Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1128
[task 2024-07-22T16:16:19.672Z] 16:16:19     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2024-07-22T16:16:19.672Z] 16:16:19     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-22T16:16:19.674Z] 16:16:19     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/cookiebanners/test/browser/browser_bannerClicking_slowLoad.js | Counter for label 'fail' has correct state. - Got 1, expected null
[task 2024-07-22T16:16:19.674Z] 16:16:19     INFO - Stack trace:
[task 2024-07-22T16:16:19.674Z] 16:16:19     INFO - chrome://mochikit/content/browser-test.js:test_is:1620
[task 2024-07-22T16:16:19.674Z] 16:16:19     INFO - chrome://mochitests/content/browser/toolkit/components/cookiebanners/test/browser/head.js:testMetricState:450
<...>

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

Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.