1901623 - Intermittent [TV] dom/security/test/https-first/test_multiple_redirection.html | Test timed out. -

Status: NEW

(Core :: DOM: Security, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=461714265&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Uh04TCSKQdOd0LSpZugwXQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Uh04TCSKQdOd0LSpZugwXQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2024-06-10T18:56:03.195Z] 18:56:03     INFO - TEST-PASS | dom/security/test/https-first/test_multiple_redirection.html | redirect results in test last redirect other HTTP origin gets upgraded 
[task 2024-06-10T18:56:03.195Z] 18:56:03     INFO - Buffered messages finished
[task 2024-06-10T18:56:03.196Z] 18:56:03     INFO - TEST-UNEXPECTED-FAIL | dom/security/test/https-first/test_multiple_redirection.html | Test timed out. - 
[task 2024-06-10T18:56:03.196Z] 18:56:03     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:03.196Z] 18:56:03     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2024-06-10T18:56:03.196Z] 18:56:03     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:03.197Z] 18:56:03     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2024-06-10T18:56:04.129Z] 18:56:04     INFO - GECKO(4354) | MEMORY STAT | vsize 6704MB | residentFast 82MB | heapAllocated 8MB
[task 2024-06-10T18:56:05.182Z] 18:56:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:05.183Z] 18:56:05     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | /tests/dom/security/test/https-first/test_multiple_redirection.html - finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
[task 2024-06-10T18:56:05.183Z] 18:56:05     INFO - {'loaded_test_url': '/tests/dom/security/test/https-first/test_multiple_redirection.html'}
[task 2024-06-10T18:56:08.537Z] 18:56:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:08.537Z] 18:56:08     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Test timed out. - 
[task 2024-06-10T18:56:08.538Z] 18:56:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:08.538Z] 18:56:08     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2024-06-10T18:56:08.539Z] 18:56:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-06-10T18:56:08.539Z] 18:56:08     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2024-06-10T18:56:08.540Z] 18:56:08     INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2024-06-10T18:56:08.894Z] 18:56:08     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | Finished in 303026ms
[task 2024-06-10T18:56:08.894Z] 18:56:08     INFO - {'runtime': '303026'}
[task 2024-06-10T18:56:08.894Z] 18:56:08     INFO - TEST-INFO 
[task 2024-06-10T18:56:09.173Z] 18:56:09     INFO - TEST-START | Shutdown

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:manuel, since you are the author of the regressor, bug 1747230, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Manuel Bucher [:manuel]]

Trying with requestLongerTimeout: https://treeherder.mozilla.org/#/jobs?repo=try&revision=5abe71b1d131c48ed0110ede162f0e06acdb47f1

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1747230

Comment 4

[Manuel Bucher [:manuel]]

Attachment: mozilla-test-fail-screenshot_8iexmsa4.png

This is hanging on my added test case. Screenshot of failure from CI shows the downgrade url in secure https, which should return

The test case gets initiated with a load to http://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?test5.

• test5 response

# upgraded to https with https-only mode
> HTTP GET https://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?test5
< 302 Found
< Location: https://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?downgrade

• downgrade responses

# explicit downgrade, should
> HTTP GET https://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?downgrade
< 302 Found
< Location: http://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?downgrade

--- or this is getting displayed

# Downgrade detected, added exception, no further upgrades
> HTTP GET http://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?downgrade
< 302 Found
< Location: http://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?verify

• verify response

> HTTP GET http://example.com/tests/dom/security/test/https-first/file_multiple_redirection.sjs?verify
< 200 OK
< [...]

From the log: The last test case before my new one passes, but the 5th test case fails: https://searchfox.org/mozilla-central/rev/4582d908c17fbf7924f5699609fe4a12c28ddc4a/dom/security/test/https-first/test_multiple_redirection.html#43,50

[task 2024-06-10T18:56:03.195Z] 18:56:03     INFO - TEST-PASS | dom/security/test/https-first/test_multiple_redirection.html | redirect results in test last redirect other HTTP origin gets upgraded 
[task 2024-06-10T18:56:03.196Z] 18:56:03     INFO - TEST-UNEXPECTED-FAIL | dom/security/test/https-first/test_multiple_redirection.html | Test timed out. - 

Added try push with http log: ./mach try fuzzy dom/security/test/https-first/test_multiple_redirection.html --env "MOZ_LOG=nsHttp:5,CSMLog:5":

https://treeherder.mozilla.org/jobs?repo=try&revision=2063f56f10f2074c353821e18ed812c9c6d68c19

Comment 5

[Manuel Bucher [:manuel]]

Cool, an intermittent that goes away with logging. Maybe I also just selected the wrong task? Retrying with all macos tasks: https://treeherder.mozilla.org/jobs?repo=try&revision=f7babd05cd7eeabd5ede0fdecc435c76702b8018

Comment 6

[Manuel Bucher [:manuel]]

And one with only MOZ_LOG=CSMLog:5 to drastically reduce the amount of log lines: https://treeherder.mozilla.org/jobs?repo=try&revision=e3652721d299eeef0c76c3eec689bad5c70e8061

Comment 7

[Intermittent Failures Robot]

2 failures in 4625 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• macosx1015-64-qr: 2
  • debug: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1901623&startday=2024-06-10&endday=2024-06-16&tree=all

Comment 8

[Manuel Bucher [:manuel]]

Unassigning myself for now. I'm unlikely to investigate in near future.