1607941 - Intermittent ccov toolkit/components/normandy/test/browser/browser_RecipeRunner.js | run() does not run again from timer - Got 3, expected 2

Status: RESOLVED INCOMPLETE

(Firefox :: Normandy Client, defect, P5)

Description

[Treeherder Bug Filer]

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

---

Comment 1

[Alexandru Michis [:malexandru]]

Started appearing on non-TV jobs as well:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=284146953&repo=mozilla-central&lineNumber=26623

Log:
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - TEST-PASS | toolkit/components/normandy/test/browser/browser_RecipeRunner.js | run() is no-op if already running -
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Leaving test bound testRunCanRunOnlyOnce
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Entering test bound testSyncDelaysTimer
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:registerTimer - timerID: recipe-client-addon-run interval: 1 skipFirst: false
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:registerTimer - Ignoring second registration for recipe-client-addon-run
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:unregisterTimer - id: recipe-client-addon-run
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:registerTimer - timerID: recipe-client-addon-run interval: 1 skipFirst: false
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - fire timerID: recipe-client-addon-run intended time: 1 (1970-01-01T00:00:01.000Z)
[task 2020-01-09T06:20:08.667Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - fire timerID: xpi-signature-verification intended time: 86400 (1970-01-02T00:00:00.000Z)
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - notified timerID: recipe-client-addon-run
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - fire timerID: recipe-client-addon-run intended time: 1578550804 (2020-01-09T06:20:04.000Z)
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - notified timerID: xpi-signature-verification
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Buffered messages logged at 06:20:04
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Console message: UTM:SVC TimerManager:notify - notified timerID: recipe-client-addon-run
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Buffered messages finished
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/normandy/test/browser/browser_RecipeRunner.js | run() does not run again from timer after sync - Got 3, expected 2
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - Stack trace:
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-01-09T06:20:08.668Z] 06:20:08 INFO - chrome://mochitests/content/browser/toolkit/components/normandy/test/browser/browser_RecipeRunner.js:testSyncDelaysTimer:878
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochitests/content/browser/toolkit/components/normandy/test/browser/head.js:wrappedTestFunction:304
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochitests/content/browser/toolkit/components/normandy/test/browser/head.js:wrappedTestFunction:304
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochitests/content/browser/toolkit/components/normandy/test/browser/head.js:wrappedTestFunction:304
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochitests/content/browser/toolkit/components/normandy/test/browser/head.js:inner:233
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:808
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - Leaving test bound testSyncDelaysTimer
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - Entering test bound testAutomaticCapabilities
[task 2020-01-09T06:20:08.669Z] 06:20:08 INFO - TEST-PASS | toolkit/components/normandy/test/browser/browser_RecipeRunner.js | context variables from Normandy's client context should be included -

Comment 2

[Intermittent Failures Robot]

11 failures in 4472 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• mozilla-central: 6

Platform breakdown:

• windows10-64: 2
• macosx1014-64-shippable: 3
• windows7-32: 1
• windows10-64-ccov: 3
• windows10-64-shippable: 1
• macosx1014-64: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-shippable: 1

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64-ccov: 1

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

Comment 5

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

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