Frequent TestWifiMonitor.WifiScanPolling | Actual function call count doesn't match EXPECT_CALL(*static_cast<MockWifiScanner*>(mWifiMonitor->mWifiScanner.get()), GetAccessPointsFromWLAN)...
Categories
(Core :: Networking, defect, P3)
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
Comment hidden (Intermittent Failures Robot) |
Comment 2•2 years ago
|
||
First occurrence in this range of retriggers and backfills
Could be caused by Bug 1810421 in any way?
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•2 years ago
|
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 8•2 years ago
|
||
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.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 12•2 years ago
|
||
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.
Comment 13•2 years ago
|
||
Comment 14•2 years ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 23•2 years ago
|
||
David, this seems to still be failing.
Do you have an idea why?
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 37•2 years ago
•
|
||
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
Updated•2 years ago
|
Comment 38•2 years ago
|
||
Hi David! Can you please take another look at this?
Thank you!
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Updated•2 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 43•2 years ago
|
||
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
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 46•2 years ago
|
||
I am looking into this again but I've got nothing so far.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 53•2 years ago
|
||
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
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 70•2 years ago
|
||
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!
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 79•2 years ago
|
||
In the past week we had 68 failures:
- 2 on windows10-64-2009-qr debug
- 8 on macosx1015-64-shippable-qr opt
- 58 on macosx1015-64-qr opt and debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=441434525&repo=autoland&lineNumber=11647
[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)
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Updated•2 years ago
|
Comment 86•2 years ago
|
||
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
Comment 87•2 years ago
|
||
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.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 95•2 years ago
|
||
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.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 98•2 years ago
|
||
(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!
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 106•2 years ago
|
||
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 ?
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 108•2 years ago
|
||
Assignee | ||
Comment 109•2 years ago
•
|
||
(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.
Comment 110•2 years ago
|
||
Comment 111•2 years ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 115•2 years ago
|
||
uplift |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Updated•2 years ago
|
Updated•1 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Description
•