1484062 - Intermittent toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico

Reporter

Description

•

6 years ago

treeherder

Filed by: aciure [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=194402870&repo=autoland

https://queue.taskcluster.net/v1/task/Zc9et47oQou_m3zKPWASGg/runs/0/artifacts/public/logs/live_backing.log

13:38:55     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | received onCompleted 
13:38:55     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | resource type is correct - Expected: main_frame, Actual: main_frame 
13:38:55     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | origin is correct - Expected: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html, Actual: http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html 
13:38:55     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | correct requestId - Expected: 47, Actual: 47 
13:38:55     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | correct tabId - Expected: 47, Actual: 47 
13:38:55     INFO - onBeforeRequest 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Buffered messages finished
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - onBeforeSendHeaders 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Not taking screenshot here: see the one that was previously logged
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - onSendHeaders 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Not taking screenshot here: see the one that was previously logged
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - onHeadersReceived 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Not taking screenshot here: see the one that was previously logged
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - onResponseStarted 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Not taking screenshot here: see the one that was previously logged
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - onCompleted 48 http://mochi.test:8888/favicon.ico
13:38:55     INFO - Not taking screenshot here: see the one that was previously logged
13:38:55     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test-oop-extensions/test_ext_webrequest_filter.html | unexpected request favicon.ico 
13:38:55     INFO -     getExpected@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:62:7
13:38:55     INFO -     getListener/<@moz-extension://8836590f-8423-5747-97f6-d71a53048e42/%7B925a4cf3-92ca-d146-b5fe-7b71b6e07f70%7D.js:236:22
13:38:55     INFO -     
13:38:55     INFO - GECKO(1054) | ++DOMWINDOW == 22 (0x11b323c00) [pid = 1055] [serial = 343] [outer = 0x11e3c6200]
13:38:55     INFO - GECKO(1054) | [Child 1055, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 596
13:38:55     INFO - GECKO(1054) | [Child 1055, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1582
13:38:55     INFO - GECKO(1054) | [Child 1065, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 596
13:38:55     INFO - GECKO(1054) | [Parent 1054, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 596
13:38:55     INFO - GECKO(1054) | [Child 1056, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 596

Andrei Ciure[:aciure]

Comment 1

•

6 years ago

https://treeherder.mozilla.org/#/jobs?repo=autoland&filter-searchStr=f6f84f2ceaecd6ebf65353a84721c86c9656e72b&tochange=07ce0e4ae08d0cd49d8be2c613121b5c84364422&fromchange=7df7135ad04c688b90bb8e6e995dde508b7f41ad&group_state=expanded&selectedJob=194730742

This issue seems to have started with:
https://treeherder.mozilla.org/#/jobs?repo=autoland&revision=3567e433384608b2df9c8b0124afa595edd2c3dc&filter-searchStr=f6f84f2ceaecd6ebf65353a84721c86c9656e72b

Andrei Ciure[:aciure]

Updated

•

6 years ago

Whiteboard: [retriggered]

Comment hidden (Intermittent Failures Robot)

42 failures in 2672 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

** 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:
* try: 2
* mozilla-central: 14
* autoland: 14
* mozilla-inbound: 12

Platform breakdown:
* windows10-64-ccov: 5
* linux32: 5
* linux64-qr: 12
* windows10-64: 1
* linux64: 8
* windows10-64-msvc: 1
* osx-10-10: 10

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

Comment hidden (Intermittent Failures Robot)

15 failures in 492 pushes (0.03 failures/push) were associated with this bug yesterday.

Repository breakdown:
* autoland: 5
* mozilla-inbound: 6
* try: 1
* mozilla-central: 3

Platform breakdown:
* linux64-qr: 8
* linux32: 2
* osx-10-10: 4
* windows10-64-ccov: 1

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

Treeherder Bug Filer

Reporter

Updated

•

6 years ago

Comment 5

•

6 years ago

There are 74 occurrence for this failure in the last 7 days. This is happening on the debug builds on all platforms.

:ddurst can you take a look at this?

Flags: needinfo?(ddurst)

Whiteboard: [retriggered] → [retriggered] [stockwell needswork]

Comment hidden (Intermittent Failures Robot)

79 failures in 2568 pushes (0.031 failures/push) were associated with this bug in the last 7 days.

This is the #21 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:
* mozilla-central: 18
* try: 5
* mozilla-inbound: 28
* autoland: 28

Platform breakdown:
* linux64: 10
* linux64-qr: 30
* windows10-64-ccov: 6
* osx-10-10: 22
* linux32: 6
* windows7-32-msvc: 1
* windows10-64: 1
* linux64-ccov: 3

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

Rob Wu [:robwu]

Assignee

Comment 7

•

6 years ago

Shane, could you take a look? (also related: bug 1485467 ).

I think that we have to add an expectation for favicons to test_webRequest_filter_tab, like this:
https://searchfox.org/mozilla-central/rev/55da592d85c2baf8d8818010c41d9738c97013d2/toolkit/components/extensions/test/mochitest/test_ext_webrequest_filter.html#81-88

But the comments around optional_events refer to bug 1472156, which suggests that optional_events are only supposed to happen in non-e10s. That is at odds with this bug, where the test failure clearly occurs with e10s.

Flags: needinfo?(ddurst) → needinfo?(mixedpuppy)

Shane Caraveo (:mixedpuppy)

Comment 8

•

6 years ago

(In reply to Rob Wu [:robwu] from comment #7)
> Shane, could you take a look? (also related: bug 1485467 ).
> 
> I think that we have to add an expectation for favicons to
> test_webRequest_filter_tab, like this:
> https://searchfox.org/mozilla-central/rev/
> 55da592d85c2baf8d8818010c41d9738c97013d2/toolkit/components/extensions/test/
> mochitest/test_ext_webrequest_filter.html#81-88

yeah.

> But the comments around optional_events refer to bug 1472156, which suggests
> that optional_events are only supposed to happen in non-e10s. That is at
> odds with this bug, where the test failure clearly occurs with e10s.

Used to be the case, but at some point that changed.

Flags: needinfo?(mixedpuppy)

Rob Wu [:robwu]

Assignee

Comment 9

•

6 years ago

I can consistently reproduce this bug by adding an artificial wait before resetting the window location to about:blank.

I've looked at the list of failures, and it seems that the requests either end up being aborted (onErrorOccurred) or they complete (onCompleted). This seems different from test_webRequest_filter_window, where the final requests are somehow not observed. That does probably still require an investigation (bug 1472156).

Assignee: nobody → rob

Status: NEW → ASSIGNED

Rob Wu [:robwu]

Assignee

Comment 10

•

6 years ago

Attached file Bug 1484062 - Add optional favicon.ico expectation to test_ext_webrequest_filter.html — Details

Shane Caraveo (:mixedpuppy)

Comment 12

•

6 years ago

Comment on attachment 9005139 [details]
Bug 1484062 - Add optional favicon.ico expectation to test_ext_webrequest_filter.html

Shane Caraveo (:mixedpuppy) has approved the revision.

Attachment #9005139 - Flags: review+

Comment hidden (Intermittent Failures Robot)

16 failures in 538 pushes (0.03 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:
* autoland: 3
* mozilla-inbound: 6
* mozilla-central: 6
* try: 1

Platform breakdown:
* linux64-qr: 5
* osx-10-10: 5
* linux32: 5
* linux64-ccov: 1

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

Comment hidden (Intermittent Failures Robot)

17 failures in 480 pushes (0.035 failures/push) were associated with this bug yesterday.

Repository breakdown:
* autoland: 3
* try: 1
* mozilla-beta: 1
* mozilla-inbound: 8
* mozilla-central: 4

Platform breakdown:
* linux64-qr: 7
* windows10-64-ccov: 1
* linux32: 2
* osx-10-10: 5
* linux64-ccov: 2

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

Pulsebot

Comment 15

•

6 years ago

Pushed by rob@robwu.nl:
https://hg.mozilla.org/integration/autoland/rev/cd5cb85fa128
Add optional favicon.ico expectation to test_ext_webrequest_filter.html r=mixedpuppy

Narcis Beleuzu [:NarcisB]

Comment 16

•

6 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/cd5cb85fa128

Status: ASSIGNED → RESOLVED

Closed: 6 years ago

status-firefox63: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla63

Comment hidden (Intermittent Failures Robot)

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

This is the #21 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:
* try: 5
* mozilla-central: 11
* mozilla-inbound: 26
* autoland: 19
* mozilla-beta: 1

Platform breakdown:
* osx-10-10: 20
* windows10-64-ccov: 2
* linux32: 10
* linux64-ccov: 3
* linux64: 4
* linux64-qr: 23

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484062&startday=2018-08-27&endday=2018-09-02&tree=all

CosminB

Updated

•

6 years ago

Flags: qe-verify-

Luca Greco [:rpl] [:luca] [:lgreco]

Updated

•

5 years ago

Whiteboard: [retriggered] [stockwell disable-recommended] → [retriggered] [stockwell fixed]