Open Bug 1833020 Opened 2 years ago Updated 1 years ago

Frequent TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*static_cast<MockWifiScanner*>(mWifiMonitor->mWifiScanner.get()), GetAccessPointsFromWLAN)...

Categories

(Core :: Networking, defect, P3)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Assigned: handyman)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [retriggered][necko-triaged])

Attachments

(2 files)

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


INFO -  TEST-START | TestWifiMonitor.WifiScanPolling
[task 2023-05-14T10:15:25.208Z] 10:15:25  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*static_cast<MockWifiScanner*>(mWifiMonitor->mWifiScanner.get()), GetAccessPointsFromWLAN)...
[task 2023-05-14T10:15:25.209Z] 10:15:25     INFO -           Expected: to be called at least twice
[task 2023-05-14T10:15:25.209Z] 10:15:25     INFO -             Actual: called once - unsatisfied and active @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:191
[task 2023-05-14T10:15:25.209Z] 10:15:25  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | test completed (time: 371ms)
[task 2023-05-14T10:15:25.210Z] 10:15:25     INFO -  TEST-START | TestBase64Stream.Run

First occurrence in this range of retriggers and backfills

Could be caused by Bug 1810421 in any way?

Flags: needinfo?(davidp99)
Whiteboard: [retriggered]
Assignee: nobody → davidp99
Flags: needinfo?(davidp99)
Duplicate of this bug: 1836663
Duplicate of this bug: 1835584

gMock objects cannot be reused after they receive calls to the mocked functions. This patch changes the test to establish all of the mock expectations upfront for each network-change notification before running it. It uses gMock Sequence objects and a checkpoint to maintain (partial) temporal order.

The patch fixes a few invalid things like illegal gmock use and a SpinEventLoopUntil timeout detection timer that didn't work, but the intermittent failure, seemingly caused by something to do with the AppShell (Windows-only) waiting for messages when it should be working is still an issue. So lets keep this open for now.

Keywords: leave-open
Pushed by daparks@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/a0d15f956590 Fix TestWifiMonitor async mock objects r=necko-reviewers,valentin
Duplicate of this bug: 1840539

David, this seems to still be failing.
Do you have an idea why?

Flags: needinfo?(davidp99)
Priority: P5 → P3
Whiteboard: [retriggered] → [retriggered][necko-triaged]

Update:
There have been 50 failures within the last 7 days:
• 16 failures on OS X 10.15 WebRender opt
• 26 failures on OS X 10.15 WebRender debug
• 4 failures on OS X 10.15 WebRender Shippable opt
• 4 failures on windows10-64-2009-qr debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=434375487&repo=autoland&lineNumber=11982

[task 2023-10-31T03:23:00.419Z] 03:23:00     INFO -  TEST-START | TestWifiMonitor.WifiScanPolling
[task 2023-10-31T03:23:06.323Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling |
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -  Unexpected mock function call - returning directly.
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -      Function call: Call(2)
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -  Google Mock tried the following 3 expectations, but none matched:
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #0: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -           Expected: the expectation is active
[task 2023-10-31T03:23:06.323Z] 03:23:06     INFO -             Actual: it is retired
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -             Actual: called once - saturated and retired
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #1: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -           Expected: all pre-requisites are satisfied
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -             Actual: the following immediate pre-requisites are not satisfied:
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:256: pre-requisite #0
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -                     (end of pre-requisites)
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.324Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #2: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -    Expected arg #0: is equal to 3
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -             Actual: 2
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.325Z] 03:23:06     INFO -   @ (null):-1
[task 2023-10-31T03:23:06.462Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling |
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -  Unexpected mock function call - returning directly.
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -      Function call: Call(3)
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -  Google Mock tried the following 3 expectations, but none matched:
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #0: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -           Expected: the expectation is active
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -             Actual: it is retired
[task 2023-10-31T03:23:06.462Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -             Actual: called once - saturated and retired
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #1: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -    Expected arg #0: is equal to 2
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -             Actual: 3
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #2: EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.463Z] 03:23:06     INFO -           Expected: all pre-requisites are satisfied
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -             Actual: the following immediate pre-requisites are not satisfied:
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: pre-requisite #0
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -                     (end of pre-requisites)
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.464Z] 03:23:06     INFO -   @ (null):-1
[task 2023-10-31T03:23:06.684Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*mWifiListener, OnChange)...
[task 2023-10-31T03:23:06.684Z] 03:23:06     INFO -           Expected: to be called at least twice
[task 2023-10-31T03:23:06.685Z] 03:23:06     INFO -             Actual: called once - unsatisfied and active
[task 2023-10-31T03:23:06.685Z] 03:23:06     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:256
[task 2023-10-31T03:23:06.685Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.685Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.685Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.685Z] 03:23:06     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297
[task 2023-10-31T03:23:06.686Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(mFn, Call(aId))...
[task 2023-10-31T03:23:06.686Z] 03:23:06     INFO -           Expected: to be called once
[task 2023-10-31T03:23:06.686Z] 03:23:06     INFO -             Actual: never called - unsatisfied and active
[task 2023-10-31T03:23:06.686Z] 03:23:06     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297
[task 2023-10-31T03:23:06.686Z] 03:23:06  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | test completed (time: 6265ms)
[task 2023-10-31T03:23:06.686Z] 03:23:06     INFO -  TEST-START | TestBase64Stream.Run
Flags: needinfo?(davidp99)

Hi David! Can you please take another look at this?
Thank you!

Flags: needinfo?(davidp99)
Summary: Intermittent TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*static_cast<MockWifiScanner*>(mWifiMonitor->mWifiScanner.get()), GetAccessPointsFromWLAN)... → Frequent TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*static_cast<MockWifiScanner*>(mWifiMonitor->mWifiScanner.get()), GetAccessPointsFromWLAN)...

Randell, anything that can be done here to get rid or reduce the failure rate?
Recent log: https://treeherder.mozilla.org/logviewer?job_id=435610728&repo=autoland

Flags: needinfo?(rjesup)

I am looking into this again but I've got nothing so far.

Flags: needinfo?(davidp99)

Thank you, David.

Flags: needinfo?(rjesup)

Update

There have been 78 failures within the last 7 days:

  • 63 failures on OS X 10.15 WebRender debug/ opt
  • 10 failures on OS X 10.15 WebRender Shippable opt
  • 3 failures on windows10-64-2009-qr opt
  • 2 failures on windows10-64-2009-shippable-qr opt

Recent log: https://treeherder.mozilla.org/logviewer?job_id=437626691&repo=autoland&lineNumber=12027

Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended] → [retriggered][necko-triaged][stockwell disable-recommended][stockwell needswork:owner]

Update

There have been 66 failures within the last 7 days:

  • 57 failures on OS X 10.15 WebRender debug/ opt
  • 7 failures on OS X 10.15 WebRender Shippable opt
  • 2 failures on windows10-64-2009-qr debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=439766677&repo=autoland&lineNumber=11695

[task 2023-12-12T09:09:10.510Z] 09:09:10     INFO -  TEST-START | TestWifiMonitor.WifiScanPolling
[task 2023-12-12T09:09:14.827Z] 09:09:14  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling |
[task 2023-12-12T09:09:14.827Z] 09:09:14     INFO -  Unexpected mock function call - returning directly.
[task 2023-12-12T09:09:14.827Z] 09:09:14     INFO -      Function call: Call(2)
[task 2023-12-12T09:09:14.827Z] 09:09:14     INFO -  Google Mock tried the following 3 expectations, but none matched:
[task 2023-12-12T09:09:14.828Z] 09:09:14     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #0: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-12T09:09:14.828Z] 09:09:14     INFO -           Expected: the expectation is active
[task 2023-12-12T09:09:14.828Z] 09:09:14     INFO -             Actual: it is retired
[task 2023-12-12T09:09:14.828Z] 09:09:14     INFO -           Expected: to be called once
[task 2023-12-12T09:09:14.829Z] 09:09:14     INFO -             Actual: called once - saturated and retired
[task 2023-12-12T09:09:14.829Z] 09:09:14     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #1: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-12T09:09:14.829Z] 09:09:14     INFO -           Expected: all pre-requisites are satisfied
[task 2023-12-12T09:09:14.829Z] 09:09:14     INFO -             Actual: the following immediate pre-requisites are not satisfied:
[task 2023-12-12T09:09:14.829Z] 09:09:14     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:256: pre-requisite #0
[task 2023-12-12T09:09:14.830Z] 09:09:14     INFO -                     (end of pre-requisites)
[task 2023-12-12T09:09:14.830Z] 09:09:14     INFO -           Expected: to be called once
[task 2023-12-12T09:09:14.830Z] 09:09:14     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-12T09:09:14.830Z] 09:09:14     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #2: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-12T09:09:14.830Z] 09:09:14     INFO -    Expected arg #0: is equal to 3
[task 2023-12-12T09:09:14.831Z] 09:09:14     INFO -             Actual: 2
[task 2023-12-12T09:09:14.831Z] 09:09:14     INFO -           Expected: to be called once
[task 2023-12-12T09:09:14.831Z] 09:09:14     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-12T09:09:14.831Z] 09:09:14     INFO -   @ (null):-1

David, are there any updates?
Thank you!

Flags: needinfo?(davidp99)
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended][stockwell needswork:owner] → [retriggered][necko-triaged][stockwell needswork:owner]

In the past week we had 68 failures:

[task 2023-12-26T21:31:10.834Z] 21:31:10     INFO -  TEST-START | TestWifiMonitor.WifiScanPolling
[task 2023-12-26T21:31:15.449Z] 21:31:15  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling |
[task 2023-12-26T21:31:15.450Z] 21:31:15     INFO -  Unexpected mock function call - returning directly.
[task 2023-12-26T21:31:15.450Z] 21:31:15     INFO -      Function call: Call(2)
[task 2023-12-26T21:31:15.450Z] 21:31:15     INFO -  Google Mock tried the following 3 expectations, but none matched:
[task 2023-12-26T21:31:15.450Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #0: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.451Z] 21:31:15     INFO -           Expected: the expectation is active
[task 2023-12-26T21:31:15.451Z] 21:31:15     INFO -             Actual: it is retired
[task 2023-12-26T21:31:15.451Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.451Z] 21:31:15     INFO -             Actual: called once - saturated and retired
[task 2023-12-26T21:31:15.452Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #1: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.452Z] 21:31:15     INFO -           Expected: all pre-requisites are satisfied
[task 2023-12-26T21:31:15.452Z] 21:31:15     INFO -             Actual: the following immediate pre-requisites are not satisfied:
[task 2023-12-26T21:31:15.452Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:256: pre-requisite #0
[task 2023-12-26T21:31:15.452Z] 21:31:15     INFO -                     (end of pre-requisites)
[task 2023-12-26T21:31:15.453Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.453Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.453Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #2: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.453Z] 21:31:15     INFO -    Expected arg #0: is equal to 3
[task 2023-12-26T21:31:15.454Z] 21:31:15     INFO -             Actual: 2
[task 2023-12-26T21:31:15.454Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.454Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.454Z] 21:31:15     INFO -   @ (null):-1
[task 2023-12-26T21:31:15.697Z] 21:31:15  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling |
[task 2023-12-26T21:31:15.697Z] 21:31:15     INFO -  Unexpected mock function call - returning directly.
[task 2023-12-26T21:31:15.697Z] 21:31:15     INFO -      Function call: Call(3)
[task 2023-12-26T21:31:15.697Z] 21:31:15     INFO -  Google Mock tried the following 3 expectations, but none matched:
[task 2023-12-26T21:31:15.697Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #0: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.698Z] 21:31:15     INFO -           Expected: the expectation is active
[task 2023-12-26T21:31:15.698Z] 21:31:15     INFO -             Actual: it is retired
[task 2023-12-26T21:31:15.698Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.698Z] 21:31:15     INFO -             Actual: called once - saturated and retired
[task 2023-12-26T21:31:15.699Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #1: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.699Z] 21:31:15     INFO -    Expected arg #0: is equal to 2
[task 2023-12-26T21:31:15.699Z] 21:31:15     INFO -             Actual: 3
[task 2023-12-26T21:31:15.699Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.699Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.700Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: tried expectation #2: EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.700Z] 21:31:15     INFO -           Expected: all pre-requisites are satisfied
[task 2023-12-26T21:31:15.700Z] 21:31:15     INFO -             Actual: the following immediate pre-requisites are not satisfied:
[task 2023-12-26T21:31:15.700Z] 21:31:15     INFO -  /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297: pre-requisite #0
[task 2023-12-26T21:31:15.701Z] 21:31:15     INFO -                     (end of pre-requisites)
[task 2023-12-26T21:31:15.701Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.701Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.701Z] 21:31:15     INFO -   @ (null):-1
[task 2023-12-26T21:31:15.866Z] 21:31:15  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*mWifiListener, OnChange)...
[task 2023-12-26T21:31:15.867Z] 21:31:15     INFO -           Expected: to be called at least twice
[task 2023-12-26T21:31:15.867Z] 21:31:15     INFO -             Actual: called once - unsatisfied and active
[task 2023-12-26T21:31:15.867Z] 21:31:15     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:256
[task 2023-12-26T21:31:15.867Z] 21:31:15  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.868Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.868Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.868Z] 21:31:15     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297
[task 2023-12-26T21:31:15.868Z] 21:31:15  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(mFn, Call(aId))...
[task 2023-12-26T21:31:15.868Z] 21:31:15     INFO -           Expected: to be called once
[task 2023-12-26T21:31:15.869Z] 21:31:15     INFO -             Actual: never called - unsatisfied and active
[task 2023-12-26T21:31:15.869Z] 21:31:15     INFO -   @ /builds/worker/checkouts/gecko/netwerk/wifi/gtest/TestWifiMonitor.cpp:297
[task 2023-12-26T21:31:17.285Z] 21:31:17  WARNING -  TEST-UNEXPECTED-FAIL | TestWifiMonitor.WifiScanPolling | test completed (time: 6452ms)
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended] → [retriggered][necko-triaged][stockwell needswork:owner]
Flags: needinfo?(davidp99)

Update

There have been 81 failures within the last 7 days:

  • 6 failures on windows10-64-2009-qr debug
  • 8 failures on OS X 10.15 WebRender Shippable opt
  • 67 failures on OS X 10.15 WebRender opt/debug

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=442584018&repo=autoland&lineNumber=11638

Flags: needinfo?(davidp99)

This has reached our disable-recommended queue.
Ed, can you help us assign this to someone, in case David doesn't have time to look into the issue?
Thank you.

Flags: needinfo?(edgul)
Flags: needinfo?(edgul)
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended] → [retriggered][necko-triaged][stockwell disable-recommended][necko-priority-queue]

Apologies for how long this has taken. It is certainly reasonable to disable the test at this point. LMK if I need to draft a patch for this.

The bug keeps morphing and none of the issues that are presented are, at their core, about the wifi mechanism itself. The latest failures are almost entirely MacOS. When the bug was reported, it was all Windows, as the comments above show. There are still a small handful of Windows failures. The MacOS failures seem to be about a thread (not yet sure if main or background or both) not waking in a reasonable amount of time. This is supported by the fact that increasing the timeout periods doesn't work -- the task just consumes more time. I've reproduced this in a run of a much simpler test. This is an ugly issue that will take even more time to track down.

Flags: needinfo?(davidp99)

(In reply to David Parks [:handyman] from comment #95)

Apologies for how long this has taken. It is certainly reasonable to disable the test at this point. LMK if I need to draft a patch for this.

If you could, that would be great!

Flags: needinfo?(davidp99)
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended][necko-priority-queue] → [retriggered][necko-triaged][stockwell disable-recommended][necko-priority-review]

There are 80 failures in the last 7 days.

  • 75 on macosx1015-64
  • 5 on windows10

David, can you disable the test until there will be a fix ?

(In reply to Narcis Beleuzu [:NarcisB] from comment #106)

David, can you disable the test until there will be a fix ?

Sure. Apologies again for the delay.

Flags: needinfo?(davidp99)
Pushed by daparks@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/fbadb86f7e9b Disable TestWifiMonitor for intermittent failures r=gstoll,necko-reviewers,kershaw
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended][necko-priority-review] → [retriggered][necko-triaged][stockwell disable-recommended]
Whiteboard: [retriggered][necko-triaged][stockwell disable-recommended] → [retriggered][necko-triaged]
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: