1627419 - Intermittent browser/components/aboutlogins/tests/browser/browser_aaa_eventTelemetry_run_first.js | Uncaught exception - waiting for telemetry event count of: 2 - timed out after 50 tries.

Status: ASSIGNED

(Firefox :: about:logins, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-04T06:05:36.500Z] 06:05:36     INFO - TEST-START | browser/components/aboutlogins/tests/browser/browser_aaa_eventTelemetry_run_first.js
[task 2020-04-04T06:05:38.995Z] 06:05:38     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.097Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.198Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.300Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.403Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.504Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.607Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.708Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.809Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:39.909Z] 06:05:39     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.011Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.113Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.214Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.316Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.418Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.518Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.620Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.722Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.821Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:40.924Z] 06:05:40     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.023Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.128Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.227Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.329Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.430Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.531Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.633Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.733Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.836Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:41.938Z] 06:05:41     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:42.040Z] 06:05:42     INFO - GECKO(5624) | Waiting for 2 events, got 1
[task 2020-04-04T06:05:42.141Z] 06:05:42     INFO - TEST-INFO | started process screenshot
[task 2020-04-04T06:05:42.212Z] 06:05:42     INFO - TEST-INFO | screenshot: exit 0
[task 2020-04-04T06:05:42.213Z] 06:05:42     INFO - Buffered messages logged at 06:05:36
[task 2020-04-04T06:05:42.213Z] 06:05:42     INFO - Entering test bound setup
[task 2020-04-04T06:05:42.213Z] 06:05:42     INFO - Leaving test bound setup
[task 2020-04-04T06:05:42.214Z] 06:05:42     INFO - Entering test bound setup
[task 2020-04-04T06:05:42.214Z] 06:05:42     INFO - Leaving test bound setup
[task 2020-04-04T06:05:42.214Z] 06:05:42     INFO - Entering test bound test_telemetry_events
[task 2020-04-04T06:05:42.214Z] 06:05:42     INFO - Buffered messages finished
[task 2020-04-04T06:05:42.215Z] 06:05:42     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutlogins/tests/browser/browser_aaa_eventTelemetry_run_first.js | Uncaught exception - waiting for telemetry event count of: 2 - timed out after 50 tries.
[task 2020-04-04T06:05:42.215Z] 06:05:42     INFO - Leaving test bound test_telemetry_events
[task 2020-04-04T06:05:42.215Z] 06:05:42     INFO - Console message: SENTINEL
[task 2020-04-04T06:05:42.235Z] 06:05:42     INFO - GECKO(5624) | MEMORY STAT | vsize 2104148MB | vsizeMaxContiguous 65802605MB | residentFast 234MB | heapAllocated 84MB
[task 2020-04-04T06:05:42.235Z] 06:05:42     INFO - TEST-OK | browser/components/aboutlogins/tests/browser/browser_aaa_eventTelemetry_run_first.js | took 5738ms

Comment 1

[Bogdan Tara[:bogdan_tara | bogdant]]

Seems to have started with the changes from bug 1625181.

https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&resultStatus=pending%2Crunning%2Csuccess%2Ctestfailed%2Cbusted%2Cexception&searchStr=windows%2C10%2Cx64%2Cquantumrender%2Cshippable%2Copt%2Cmochitests%2Ctest-windows10-64-shippable-qr%2Fopt-mochitest-browser-chrome-e10s&tochange=7256d96b4de64b5164e7c1829e487224e2c0c375&fromchange=a315e85c53761f48c13f62b92ecb10198d99b4a9

Comment 2

[Andrew Swan [:aswan]]

I can't see a direct connection between bug 1625181 and this bug. I guess the most likely scenario is that 1625181 changed startup timing a bit and some sort of "butterfly flapping its wings in China" effect rippled all the way down to this test. I don't know this code well enough to try to diagnose it, I'll defer to :MattN and :jaws

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64-shippable-qr: 2
• windows7-32: 1
• windows10-64-shippable: 2
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-03-30&endday=2020-04-05&tree=all

Comment 4

[Intermittent Failures Robot]

25 failures in 913 pushes (0.027 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 9
• autoland: 10
• try: 6

Platform breakdown:

• windows7-32: 3
• windows10-64-qr: 3
• windows10-64-shippable: 5
• windows10-64-shippable-qr: 2
• windows7-32-shippable: 8
• windows10-64-devedition: 1
• windows7-32-devedition: 2
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-08&endday=2020-04-08&tree=all

Comment 5

[Intermittent Failures Robot]

25 failures in 1016 pushes (0.025 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 25

Platform breakdown:

• windows7-32-shippable: 15
• windows10-64-shippable: 5
• windows10-64-shippable-qr: 4
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-09&endday=2020-04-09&tree=all

Comment 6

[Intermittent Failures Robot]

24 failures in 571 pushes (0.042 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 10
• mozilla-central: 12
• try: 2

Platform breakdown:

• windows10-64-shippable: 4
• windows7-32-shippable: 8
• macosx1014-64-shippable: 1
• windows7-32: 4
• windows10-64-qr: 1
• windows10-64: 1
• windows10-64-shippable-qr: 4
• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-10&endday=2020-04-10&tree=all

Comment 7

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days there have been 95 occurrences, most on windows10-64-shippable opt (some on windows7-32 and macosx1014-64-shippable opt).

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=297272564&repo=autoland&lineNumber=4078

Comment 8

[Intermittent Failures Robot]

116 failures in 4823 pushes (0.024 failures/push) were associated with this bug in the last 7 days.

This is the #5 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 73
• try: 9
• mozilla-central: 34

Platform breakdown:

• windows10-64-shippable: 26
• windows7-32-shippable: 45
• windows10-64: 4
• windows10-64-qr: 7
• windows7-32: 10
• windows10-64-shippable-qr: 17
• macosx1014-64-shippable: 3
• windows10-64-devedition: 1
• windows7-32-devedition: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 9

[Intermittent Failures Robot]

28 failures in 500 pushes (0.056 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:

• mozilla-central: 8
• autoland: 15
• try: 5

Platform breakdown:

• windows7-32: 4
• windows10-64-shippable-qr: 4
• windows10-64-shippable: 7
• windows7-32-shippable: 9
• windows10-64-devedition: 1
• windows7-32-devedition: 1
• windows10-64-qr: 2

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

Comment 10

[Cosmin Sabou [:CosminS]]

Attachment: Bug 1627419 - Disable browser_aaa_eventTelemetry_run_first on win !debug. r=#intermittent-reviewers

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/61878d92e4ae
Disable browser_aaa_eventTelemetry_run_first on win !debug. r=jmaher

Comment 12

[Cristina Coroiu [:ccoroiu]]

https://hg.mozilla.org/mozilla-central/rev/61878d92e4ae

Comment 13

[Intermittent Failures Robot]

21 failures in 1019 pushes (0.021 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 8
• mozilla-central: 10
• try: 3

Platform breakdown:

• windows10-64-shippable-qr: 5
• windows7-32-shippable: 6
• windows7-32: 3
• windows10-64-qr: 1
• windows10-64-shippable: 4
• windows10-64-devedition: 1
• windows7-32-devedition: 1

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

Comment 14

[Intermittent Failures Robot]

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

This is the #22 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:

• mozilla-central: 18
• autoland: 23
• try: 11

Platform breakdown:

• windows7-32: 8
• windows10-64-shippable-qr: 9
• windows10-64-shippable: 11
• windows7-32-shippable: 15
• windows10-64-devedition: 2
• windows7-32-devedition: 2
• windows10-64-qr: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• macosx1014-64-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• mozilla-central: 2

Platform breakdown:

• macosx1014-64-shippable: 2
• linux1804-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• try: 3
• mozilla-central: 1
• mozilla-beta: 1

Platform breakdown:

• linux1804-64: 5
• linux1804-64-shippable: 1
• linux1804-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• linux1804-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1627419&startday=2020-06-15&endday=2020-06-21&tree=all

Comment 21

[Jared Wein [:jaws] (please needinfo? me)]

Disabling the test in !debug is fine with me. The failure rate is extremely low so I will clear the needinfo.

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

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