1765230 - Intermittent TestDeviceInputTrack.ErrorCallback | test completed (time: 20ms)

Status: RESOLVED FIXED

(Core :: Audio/Video, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-04-19T01:31:17.450Z] 01:31:17     INFO -  TEST-START | TestDeviceInputTrack.ErrorCallback
[task 2022-04-19T01:31:17.470Z] 01:31:17  WARNING -  TEST-UNEXPECTED-FAIL | TestDeviceInputTrack.ErrorCallback |
[task 2022-04-19T01:31:17.470Z] 01:31:17     INFO -  Unexpected mock function call - returning directly.
[task 2022-04-19T01:31:17.471Z] 01:31:17     INFO -      Function call: AudioStateCallback(1, 4-byte object <01-00 00-00>)
[task 2022-04-19T01:31:17.472Z] 01:31:17     INFO -  Google Mock tried the following 2 expectations, but none matched:
[task 2022-04-19T01:31:17.472Z] 01:31:17     INFO -  /builds/worker/checkouts/gecko/dom/media/gtest/TestDeviceInputTrack.cpp:519: tried expectation #0: EXPECT_CALL(*listener, AudioStateCallback( sourceId, AudioInputSource::EventListener::State::Started))...
[task 2022-04-19T01:31:17.472Z] 01:31:17     INFO -    Expected arg #1: is equal to 4-byte object <00-00 00-00>
[task 2022-04-19T01:31:17.473Z] 01:31:17     INFO -             Actual: 4-byte object <01-00 00-00>
[task 2022-04-19T01:31:17.473Z] 01:31:17     INFO -           Expected: to be called once
[task 2022-04-19T01:31:17.474Z] 01:31:17     INFO -             Actual: called once - saturated and active
[task 2022-04-19T01:31:17.474Z] 01:31:17     INFO -  /builds/worker/checkouts/gecko/dom/media/gtest/TestDeviceInputTrack.cpp:522: tried expectation #1: EXPECT_CALL(*listener, AudioStateCallback( sourceId, AudioInputSource::EventListener::State::Error))...
[task 2022-04-19T01:31:17.474Z] 01:31:17     INFO -    Expected arg #1: is equal to 4-byte object <03-00 00-00>
[task 2022-04-19T01:31:17.475Z] 01:31:17     INFO -             Actual: 4-byte object <01-00 00-00>
[task 2022-04-19T01:31:17.475Z] 01:31:17     INFO -           Expected: to be called once
[task 2022-04-19T01:31:17.476Z] 01:31:17     INFO -             Actual: called once - saturated and active @ (null):-1
[task 2022-04-19T01:31:17.476Z] 01:31:17  WARNING -  TEST-UNEXPECTED-FAIL | TestDeviceInputTrack.ErrorCallback | test completed (time: 20ms)
[task 2022-04-19T01:31:17.476Z] 01:31:17     INFO -  TEST-START | DriftCompensatorTest.Initialized

Comment 1

[Natalia Csoregi [:nataliaCs]]

First occurrence after retriggers and backfills: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=Linux%2C18.04%2Cx64%2CWebRender%2Copt%2Ctest-linux1804-64-qr%2Fopt-gtest-1proc%2CGTest-1proc&tochange=424e46671d4f42460c4b29cd5b2a06db9c27f584&fromchange=e664bbae493554a5c8a888f42f6b6f7d9271d007&selectedTaskRun=CJfsTswDTnSYoKrpvHDrbg.0

Comment 2

[Intermittent Failures Robot]

21 failures in 3159 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• mozilla-central: 9

Platform and build breakdown:

• linux1804-64-qr: 5
  • opt: 3
  • debug: 2
• linux1804-64-ccov-qr: 6
  • opt: 6
• android-em-7-0-x86_64-lite-qr: 3
  • debug: 3
• android-em-7-0-x86_64-qr: 6
  • opt: 3
  • debug: 2
  • debug-isolated-process: 1
• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765230&startday=2022-04-18&endday=2022-04-24&tree=all

Comment 3

[Intermittent Failures Robot]

15 failures in 3289 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-ccov-qr: 5
  • opt: 5
• linux1804-64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • opt: 2
• windows10-64-2004-qr: 2
  • opt: 2
• android-em-7-0-x86_64-qr: 2
  • debug-isolated-process: 2
• windows10-64-2004-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765230&startday=2022-04-25&endday=2022-05-01&tree=all

Comment 4

[Intermittent Failures Robot]

20 failures in 3540 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 7
• try: 1
• mozilla-beta: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 4
  • opt: 3
  • debug-isolated-process: 1
• linux1804-64-ccov-qr: 5
  • opt: 5
• android-em-7-0-x86_64-lite-qr: 4
  • opt: 2
  • debug: 2
• linux1804-64-qr: 4
  • opt: 3
  • debug: 1
• linux1804-64-shippable-qr: 2
  • opt: 2
• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765230&startday=2022-05-02&endday=2022-05-08&tree=all

Comment 5

[Intermittent Failures Robot]

21 failures in 3317 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• mozilla-central: 6
• try: 2
• mozilla-beta: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 6
  • opt: 2
  • debug-isolated-process: 2
  • debug: 2
• windows10-64-2004-qr: 1
  • debug: 1
• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 6
  • opt: 3
  • debug: 3
• linux1804-64-ccov-qr: 2
  • opt: 2
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 1
  • opt: 1
• windows10-32-2004-shippable-qr: 1
  • opt: 1
• windows10-64-2004-shippable-qr: 1
  • opt: 1

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

Comment 6

[C.M.Chang[:chunmin]]

This seems the same racing issue as the bug 1765312's one.

Comment 7

[C.M.Chang[:chunmin]]

Attachment: Bug 1765230 - Wait for error stop before `StopAudio()`

TestDeviceInputTrack.ErrorCallback has the same racing issue as the
bug 1765312's one (See D144637). We should wait for the
cubeb-stream-stop called by the forced-error's background thread before
calling NonNativeInputTrack::StopAudio() to avoid getting the stopped
state callback before the error callback.

Comment 8

[C.M.Chang[:chunmin]]

Attachment: Bug 1765230 - Rename test name and correct comments

Depends on D146475

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/491c635cc3be
Wait for error stop before `StopAudio()` r=padenot
https://hg.mozilla.org/integration/autoland/rev/8e5847d574bf
Rename test name and correct comments r=padenot

Comment 10

[Atila Butkovits]

https://hg.mozilla.org/mozilla-central/rev/491c635cc3be
https://hg.mozilla.org/mozilla-central/rev/8e5847d574bf

Comment 11

[Intermittent Failures Robot]

8 failures in 2694 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2
• autoland: 6

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 2
  • debug-isolated-process: 1
  • debug: 1
• linux1804-64-ccov-qr: 1
  • opt: 1
• windows10-64-2004-qr: 2
  • debug: 2
• android-em-7-0-x86_64-lite-qr: 2
  • opt: 2
• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1765230&startday=2022-05-16&endday=2022-05-22&tree=all