Closed Bug 1568816 Opened 7 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /fetch/sec-metadata/redirect/redirect-http-upgrade.tentative.sub.html | Http upgrade prefetch => No headers - Test timed out

Categories

(Core :: DOM: Networking, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disabled][necko-triaged])

Attachments

(2 files, 1 obsolete file)

Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=258255878&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Q-LQ-KKnTEuEqYxaGq3YRQ/runs/0/artifacts/public/logs/live_backing.log


[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - TEST-FAIL | /fetch/sec-metadata/redirect/redirect-http-upgrade.tentative.sub.html | Http upgrade object => No headers - assert_equals: site expected "cross-site" but got ""
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - assert_header_equals@http://web-platform.test:8000/fetch/sec-metadata/resources/helper.js:13:3
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - RunCommonRedirectTests/</</e.onload/<@http://web-platform.test:8000/fetch/sec-metadata/resources/redirectTestHelper.sub.js:105:23
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - promise callbackRunCommonRedirectTests/</</e.onload@http://web-platform.test:8000/fetch/sec-metadata/resources/redirectTestHelper.sub.js:105:10
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - EventHandlerNonNull
RunCommonRedirectTests/</<@http://web-platform.test:8000/fetch/sec-metadata/resources/redirectTestHelper.sub.js:102:7
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - RunCommonRedirectTests/<@http://web-platform.test:8000/fetch/sec-metadata/resources/redirectTestHelper.sub.js:98:12
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:1611:25
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:591:36
[task 2019-07-25T08:36:44.128Z] 08:36:44 INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:590:20
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - promise callback*promise_test@http://web-platform.test:8000/resources/testharness.js:589:51
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - RunCommonRedirectTests@http://web-platform.test:8000/fetch/sec-metadata/resources/redirectTestHelper.sub.js:97:3
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - @http://web-platform.test:8000/fetch/sec-metadata/redirect/redirect-http-upgrade.tentative.sub.html:25:3
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - TEST-UNEXPECTED-TIMEOUT | /fetch/sec-metadata/redirect/redirect-http-upgrade.tentative.sub.html | Http upgrade prefetch => No headers - Test timed out
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - TEST-INFO | expected FAIL
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - ......
[task 2019-07-25T08:36:44.129Z] 08:36:44 INFO - TEST-TIMEOUT | /fetch/sec-metadata/redirect/redirect-http-upgrade.tentative.sub.html | took 42295ms
[task 2019-07-25T08:36:44.187Z] 08:36:44 INFO - PID 9832 | 1564043804176 Marionette INFO Stopped listening on port 50737
[task 2019-07-25T08:36:44.713Z] 08:36:44 INFO - PID 9832 | --DOCSHELL 000001F49C62B000 == 3 [pid = 7784] [id = {aa980cfd-0d19-4cd6-b059-cb84b31fda08}] [url = https://web-platform.test:8443/fetch/sec-metadata/resources/record-header.py?file=object-https-redirectcf1be145-2200-44f0-9d5d-6ec9a4608f45&location=https%3A%2F%2Fweb-platform.test%3A8443%2Ffetch%2Fsec-metadata%2Fresources%2Frecord-header.py%3Ffile%3Dobject-https-redirectcf1be145-2200-44f0-9d5d-6ec9a4608f45&count=1]
[task 2019-07-25T08:36:44.713Z] 08:36:44 INFO - PID 9832 | --DOCSHELL 000001F49C61D000 == 2 [pid = 7784] [id = {8a04711a-659c-41a9-8935-40b490ec1523}] [url = https://web-platform.test:8443/fetch/sec-metadata/resources/post-to-owner.py?location=https%3A%2F%2Fweb-platform.test%3A8443%2Ffetch%2Fsec-metadata%2Fresources%2Fpost-to-owner.py&count=1]
[task 2019-07-25T08:36:44.713Z] 08:36:44 INFO - PID 9832 | --DOCSHELL 000001F49C630800 == 1 [pid = 7784] [id = {0901d407-1d5c-4eac-b2e0-e418508b9e28}] [url = https://web-platform.test:8443/fetch/sec-metadata/resources/record-header.py?file=embed-redirect-redirectcf1be145-2200-44f0-9d5d-6ec9a4608f45&location=https%3A%2F%2Fweb-platform.test%3A8443%2Ffetch%2Fsec-metadata%2Fresources%2Frecord-header.py%3Ffile%3Dembed-redirect-redirectcf1be145-2200-44f0-9d5d-6ec9a4608f45&count=1]
[task 2019-07-25T08:36:44.713Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.734Z] 08:36:44 INFO - PID 9832 | [Child 9496, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.734Z] 08:36:44 INFO - PID 9832 | [Child 9496, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.739Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.739Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.739Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.739Z] 08:36:44 INFO - PID 9832 | [Child 7784, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.741Z] 08:36:44 INFO - PID 9832 | [GPU 9748, Chrome_ChildThread] W[Child 7784, ChroARNING: pipe eme_ChildThread] WARNINGrror: 109: file z:: pipe error: 109: file /build/build/src/ipc/z:/chromium/srbuild/build/srcc/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341/chrome/common/ipc_cha
[task 2019-07-25T08:36:44.741Z] 08:36:44 INFO - PID 9832 | nnel_win.cc, line 341
[task 2019-07-25T08:36:44.742Z] 08:36:44 INFO - PID 9832 | [GPU 9748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/ch
[task 2019-07-25T08:36:44.742Z] 08:36:44 INFO - PID 9832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-07-25T08:36:44.742Z] 08:36:44 INFO - PID 9832 | romium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.743Z] 08:36:44 INFO - PID 9832 | [GPU 9748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/c[Child 11204,hromium/src/chro Chrome_ChildThremae/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.743Z] 08:36:44 INFO - PID 9832 | d] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.744Z] 08:36:44 INFO - PID 9832 | [GPU 9748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc[/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.744Z] 08:36:44 INFO - PID 9832 | Child 11204, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.744Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.744Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-07-25T08:36:44.749Z] 08:36:44 INFO - PID 9832 | --DOCSHELL 0000018E77A5F800 == 4 [pid = 7712] [id = {644e8114-49d7-47a4-b014-a26370a28e53}] [url = chrome://extensions/content/dummy.xul]
[task 2019-07-25T08:36:44.754Z] 08:36:44 INFO - PID 9832 | [Parent 7712, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_ch--DOCSHELL 0000018E68271800 == 3 [pid = 7712] [id = {4c4feb03-51d8-418f-8f2e-8a38de34d8fd}] [url = about:blank]
[task 2019-07-25T08:36:44.755Z] 08:36:44 INFO - PID 9832 | annel_win.cc, line 341

Regressed by: 1565002
Keywords: regression
Flags: needinfo?(james)

This is another fetch test; we can mark it as intermittent if necessary, but really someone working on the fetch support should take a look at it first.

Flags: needinfo?(james) → needinfo?(amarchesini)

There are 49 failures associated to this bug in the last 7 days. These are occurring on linux32-shippable opt builds and windows10-64-ccov debug builds.

Flags: needinfo?(nhnguyen)
Whiteboard: [stockwell needswork]

Michal, could you take a look? Thanks!

Flags: needinfo?(nhnguyen) → needinfo?(michal.novotny)

Considering the failures after the latest wpt-sync push, I've updated the expectations accordingly https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-08-08&endday=2019-08-12&tree=trunk&bug=1568816.

(In reply to Cristina Coroiu [:ccoroiu] from comment #1)

Started with this push: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&revision=d46c0e76ce51704baa2f6605bc6d60a5bfeb28da

It's worth to note that it's not a regression. The test was introduced with this push.

Assignee: nobody → michal.novotny
Flags: needinfo?(michal.novotny)
Keywords: regression
Priority: P5 → P3
Attachment #9084597 - Attachment description: Bug 1568816 - update edirect-http-upgrade.tentative.sub.html expectations after wpt-sync. r=jgraham → Bug 1568816 - update redirect-http-upgrade.tentative.sub.html expectations after wpt-sync. r=jgraham
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/2343c63afc6a update redirect-http-upgrade.tentative.sub.html expectations after wpt-sync. r=jgraham
Depends on: 1574881
Assignee: michal.novotny → nobody
Component: DOM: Networking → DOM: Security

We don't yet implement sec-metadata. Any intermittents here are fetch things.

Component: DOM: Security → DOM: Networking

The component has been changed since the backlog priority was decided, so we're resetting it.
For more information, please visit auto_nag documentation.

Priority: P3 → --
Attachment #9103830 - Attachment is obsolete: true
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/602c8d202735 update redirect-http-upgrade.tentative.sub.html expectations due to frequent failures. r=aryx
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/931307506591 update redirect-http-upgrade.tentative.sub.html expectations due to frequent failures. a=test-only

Kershaw, could you

Flags: needinfo?(amarchesini)
Priority: -- → P3
Whiteboard: [stockwell disabled] → [stockwell disabled][necko-triaged]
Has Regression Range: --- → yes
Severity: normal → S3
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: