1883554 - Intermittent TV toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | single tracking bug

Status: NEW

(Toolkit :: Password Manager, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: pstanciu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=449516250&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KVgYzq1aQL6NCaKzcEYd5Q/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/KVgYzq1aQL6NCaKzcEYd5Q/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2024-03-05T01:58:30.424Z] 01:58:30     INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js
[task 2024-03-05T01:58:33.180Z] 01:58:33     INFO - GECKO(13284) | Waiting for 1 events, got 1
[task 2024-03-05T01:59:51.663Z] 01:59:51     INFO - TEST-INFO | started process screentopng
[task 2024-03-05T01:59:52.037Z] 01:59:52     INFO - TEST-INFO | screentopng: exit 0
[task 2024-03-05T01:59:52.038Z] 01:59:52     INFO - Buffered messages logged at 01:58:30
[task 2024-03-05T01:59:52.039Z] 01:59:52     INFO - Entering setup bound common_initialize
[task 2024-03-05T01:59:52.039Z] 01:59:52     INFO - Leaving setup bound common_initialize
[task 2024-03-05T01:59:52.040Z] 01:59:52     INFO - Entering setup bound 
[task 2024-03-05T01:59:52.041Z] 01:59:52     INFO - Leaving setup bound 
[task 2024-03-05T01:59:52.042Z] 01:59:52     INFO - Entering test bound mainMenu_entryPoint
[task 2024-03-05T01:59:52.042Z] 01:59:52     INFO - mainMenu_entryPoint, got focus
[task 2024-03-05T01:59:52.043Z] 01:59:52     INFO - mainMenu_entryPoint, Main menu button is visible
[task 2024-03-05T01:59:52.044Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | Menu panel (appMenu-popup) is initally closed. - "closed" == "closed" - 
[task 2024-03-05T01:59:52.045Z] 01:59:52     INFO - mainMenu_entryPoint, clicking target and waiting for popup
[task 2024-03-05T01:59:52.046Z] 01:59:52     INFO - mainMenu_entryPoint, main menu popup is shown
[task 2024-03-05T01:59:52.047Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | Menu panel (appMenu-popup) is open. - "open" == "open" - 
[task 2024-03-05T01:59:52.048Z] 01:59:52     INFO - mainMenu_entryPoint, clicking on Logins and passwords button
[task 2024-03-05T01:59:52.049Z] 01:59:52     INFO - waiting for new tab to open
[task 2024-03-05T01:59:52.049Z] 01:59:52     INFO - Buffered messages logged at 01:58:33
[task 2024-03-05T01:59:52.050Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | got password management tab - "[object XULElement]" == true - 
[task 2024-03-05T01:59:52.051Z] 01:59:52     INFO - mainMenu_entryPoint, password manager dialog shown
[task 2024-03-05T01:59:52.051Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | waiting for telemetry event count - 1 == 1 - 
[task 2024-03-05T01:59:52.052Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | content must be in snapshot. Has [content,parent]. - true == true - 
[task 2024-03-05T01:59:52.052Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | After filtering we must have the expected number of events. Filtered events: [["pwmgr","open_management","mainmenu",null,null]] - 1 == 1 - 
[task 2024-03-05T01:59:52.053Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | category in event pwmgr#open_management#mainmenu must match. - "pwmgr" matches "pwmgr" - 
[task 2024-03-05T01:59:52.056Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | method in event pwmgr#open_management#mainmenu must match. - "open_management" matches "open_management" - 
[task 2024-03-05T01:59:52.057Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | object in event pwmgr#open_management#mainmenu must match. - "mainmenu" matches "mainmenu" - 
[task 2024-03-05T01:59:52.058Z] 01:59:52     INFO - mainMenu_entryPoint, close dialog and main menu
[task 2024-03-05T01:59:52.059Z] 01:59:52     INFO - Leaving test bound mainMenu_entryPoint
[task 2024-03-05T01:59:52.061Z] 01:59:52     INFO - Entering test bound pageInfo_entryPoint
[task 2024-03-05T01:59:52.062Z] 01:59:52     INFO - Buffered messages logged at 01:58:55
[task 2024-03-05T01:59:52.063Z] 01:59:52     INFO - pageInfo_entryPoint, opening pageinfo
[task 2024-03-05T01:59:52.064Z] 01:59:52     INFO - Buffered messages logged at 01:59:43
[task 2024-03-05T01:59:52.065Z] 01:59:52     INFO - pageInfo_entryPoint, got pageinfo, wait until password button is visible
[task 2024-03-05T01:59:52.066Z] 01:59:52     INFO - pageInfo_entryPoint, clicking the show passwords button...
[task 2024-03-05T01:59:52.067Z] 01:59:52     INFO - pageInfo_entryPoint, waiting for the passwords manager dialog
[task 2024-03-05T01:59:52.068Z] 01:59:52     INFO - waiting for new tab to open
[task 2024-03-05T01:59:52.069Z] 01:59:52     INFO - Buffered messages logged at 01:59:50
[task 2024-03-05T01:59:52.070Z] 01:59:52     INFO - Console message: [JavaScript Warning: "Loading failed for the module with source “chrome://browser/content/aboutlogins/components/login-timeline.mjs”." {file: "about:logins?filter=example.com&entryPoint=pageinfo" line: 264}]
[task 2024-03-05T01:59:52.071Z] 01:59:52     INFO - Console message: [JavaScript Warning: "Loading failed for the module with source “chrome://browser/content/aboutlogins/components/login-command-button.mjs”." {file: "about:logins?filter=example.com&entryPoint=pageinfo" line: 265}]
[task 2024-03-05T01:59:52.073Z] 01:59:52     INFO - Console message: [JavaScript Warning: "Loading failed for the module with source “chrome://browser/content/aboutlogins/components/login-alert.mjs”." {file: "about:logins?filter=example.com&entryPoint=pageinfo" line: 266}]
[task 2024-03-05T01:59:52.074Z] 01:59:52     INFO - Console message: [JavaScript Warning: "Loading failed for the module with source “chrome://browser/content/aboutlogins/components/login-message-popup.mjs”." {file: "about:logins?filter=example.com&entryPoint=pageinfo" line: 267}]
[task 2024-03-05T01:59:52.075Z] 01:59:52     INFO - Buffered messages logged at 01:59:51
[task 2024-03-05T01:59:52.076Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | got password management tab - "[object XULElement]" == true - 
[task 2024-03-05T01:59:52.077Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | content must be in snapshot. Has [parent,content]. - true == true - 
[task 2024-03-05T01:59:52.078Z] 01:59:52     INFO - Buffered messages finished
[task 2024-03-05T01:59:52.086Z] 01:59:52     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | After filtering we must have the expected number of events. Filtered events: [["pwmgr","open_management","pageinfo",null,null],["pwmgr","open_management","direct",null,null]] - 2 == 1 - {"filename":"resource://testing-common/TelemetryTestUtils.sys.mjs","name":"assertEvents","sourceId":609,"lineNumber":237,"columnNumber":12,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js","name":"pageInfo_entryPoint/<","sourceId":657,"lineNumber":92,"columnNumber":26,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"resource://testing-common/BrowserTestUtils.sys.mjs","name":"withNewTab","sourceId":594,"lineNumber":146,"columnNumber":22,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js","name":"pageInfo_entryPoint","sourceId":657,"lineNumber":64,"columnNumber":26,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"handleTask","sourceId":560,"lineNumber":1139,"columnNumber":26,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"_runTaskBasedTest","sourceId":560,"lineNumber":1211,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":560,"lineNumber":1353,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":560,"lineNumber":1122,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":588,"lineNumber":1058,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"handleTask@chrome://mochikit/content/browser-test.js:1139:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"async*pageInfo_entryPoint@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:64:26\nhandleTask@chrome://mochikit/content/browser-test.js:1139:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:146:22\nasync*pageInfo_entryPoint@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:64:26\nhandleTask@chrome://mochikit/content/browser-test.js:1139:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"pageInfo_entryPoint/<@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:92:26\nasync*withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:146:22\nasync*pageInfo_entryPoint@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:64:26\nhandleTask@chrome://mochikit/content/browser-test.js:1139:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertEvents@resource://testing-common/TelemetryTestUtils.sys.mjs:237:12\npageInfo_entryPoint/<@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:92:26\nasync*withNewTab@resource://testing-common/BrowserTestUtils.sys.mjs:146:22\nasync*pageInfo_entryPoint@chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:64:26\nhandleTask@chrome://mochikit/content/browser-test.js:1139:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1122:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}}
[task 2024-03-05T01:59:52.088Z] 01:59:52     INFO - Stack trace:
[task 2024-03-05T01:59:52.088Z] 01:59:52     INFO - resource://testing-common/TelemetryTestUtils.sys.mjs:assertEvents:237
[task 2024-03-05T01:59:52.089Z] 01:59:52     INFO - chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:pageInfo_entryPoint/<:92
[task 2024-03-05T01:59:52.089Z] 01:59:52     INFO - resource://testing-common/BrowserTestUtils.sys.mjs:withNewTab:146
[task 2024-03-05T01:59:52.090Z] 01:59:52     INFO - chrome://mochitests/content/browser/toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js:pageInfo_entryPoint:64
[task 2024-03-05T01:59:52.091Z] 01:59:52     INFO - chrome://mochikit/content/browser-test.js:handleTask:1139
[task 2024-03-05T01:59:52.091Z] 01:59:52     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1211
[task 2024-03-05T01:59:52.092Z] 01:59:52     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1353
[task 2024-03-05T01:59:52.092Z] 01:59:52     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1122
[task 2024-03-05T01:59:52.093Z] 01:59:52     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2024-03-05T01:59:52.094Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | category in event pwmgr#open_management#pageinfo must match. - "pwmgr" matches "pwmgr" - 
[task 2024-03-05T01:59:52.095Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | method in event pwmgr#open_management#pageinfo must match. - "open_management" matches "open_management" - 
[task 2024-03-05T01:59:52.097Z] 01:59:52     INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | object in event pwmgr#open_management#pageinfo must match. - "pageinfo" matches "pageinfo" - 
[task 2024-03-05T01:59:52.098Z] 01:59:52     INFO - pageInfo_entryPoint, close dialog and pageInfo
[task 2024-03-05T01:59:52.794Z] 01:59:52     INFO - Leaving test bound pageInfo_entryPoint
[task 2024-03-05T01:59:54.603Z] 01:59:54     INFO - GECKO(13284) | MEMORY STAT | vsize 130552150MB | residentFast 1241MB
[task 2024-03-05T01:59:54.607Z] 01:59:54     INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_entry_point_telemetry.js | took 84188ms
[task 2024-03-05T01:59:54.749Z] 01:59:54     INFO - checking window state
[task 2024-03-05T01:59:57.968Z] 01:59:57     INFO - GECKO(13284) | Completed ShutdownLeaks collections in process 13420
[task 2024-03-05T01:59:58.103Z] 01:59:58     INFO - GECKO(13284) | Completed ShutdownLeaks collections in process 13380
[task 2024-03-05T01:59:58.407Z] 01:59:58     INFO - GECKO(13284) | Completed ShutdownLeaks collections in process 13348
[task 2024-03-05T01:59:58.419Z] 01:59:58     INFO - GECKO(13284) | Completed ShutdownLeaks collections in process 13499
[task 2024-03-05T01:59:59.454Z] 01:59:59     INFO - GECKO(13284) | Completed ShutdownLeaks collections in process 13284
[task 2024-03-05T01:59:59.456Z] 01:59:59     INFO - TEST-START | Shutdown

Comment 1

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

:mossop, since you are the author of the regressor, bug 1864896, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Dave Townsend [:mossop]]

I ran this test in test-verify mode on a changeset prior to any patches from bug 1864696 landing and it failed there too so I don't think I regressed this.

https://treeherder.mozilla.org/jobs?repo=try&revision=1f999d188ccb155adaa6246ef32c41e7ac230b58

Comment 3

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

This bug has been marked as a regression. Setting status flag for Nightly to affected.

Comment 4

[Intermittent Failures Robot]

1 failures in 4198 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-tsan-qr: 1
  • opt: 1

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

Comment 5

[Intermittent Failures Robot]

3 failures in 3235 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-qr: 2
  • debug: 1
  • opt: 1
• macosx1015-64-qr: 1
  • debug: 1

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

Comment 6

[Intermittent Failures Robot]

Summary

3 failures in 3682 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-qr: 2
  • opt: 1
  • debug: 1
• linux1804-64-tsan-qr: 1
  • opt: 1

Table

	no_variant
linux1804-64-qr/opt	1
linux1804-64-qr/debug	1
linux1804-64-tsan-qr/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1883554&startday=2025-01-06&endday=2025-01-12&tree=all