1585533 - Intermittent fission dom/security/test/csp/test_upgrade_insecure_reporting.html | Test timed out.

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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

---

[task 2019-10-02T04:03:41.398Z] 04:03:41 INFO - TEST-START | dom/security/test/csp/test_upgrade_insecure_reporting.html
[task 2019-10-02T04:03:42.144Z] 04:03:42 INFO - GECKO(1832) | JavaScript error: resource://gre/modules/LoginManagerContent.jsm, line 874: SecurityError: Permission denied to access property "document" on cross-origin object
[task 2019-10-02T04:08:58.605Z] 04:08:58 INFO - TEST-INFO | started process screentopng
[task 2019-10-02T04:08:58.842Z] 04:08:58 INFO - TEST-INFO | screentopng: exit 0
[task 2019-10-02T04:08:58.843Z] 04:08:58 INFO - Buffered messages logged at 04:03:42
[task 2019-10-02T04:08:58.843Z] 04:08:58 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure_reporting.html | upgraded insecure image load from http -> https
[task 2019-10-02T04:08:58.844Z] 04:08:58 INFO - Buffered messages finished
[task 2019-10-02T04:08:58.844Z] 04:08:58 INFO - TEST-UNEXPECTED-FAIL | dom/security/test/csp/test_upgrade_insecure_reporting.html | Test timed out.
[task 2019-10-02T04:08:58.844Z] 04:08:58 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.845Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.846Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.846Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.846Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.846Z] 04:08:58 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-10-02T04:08:58.847Z] 04:08:58 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-10-02T04:08:58.847Z] 04:08:58 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-10-02T04:08:58.847Z] 04:08:58 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-10-02T04:08:58.848Z] 04:08:58 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-10-02T04:08:58.848Z] 04:08:58 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-10-02T04:08:58.849Z] 04:08:58 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-10-02T04:08:58.849Z] 04:08:58 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-10-02T04:08:58.849Z] 04:08:58 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-10-02T04:08:58.849Z] 04:08:58 INFO - hookup@SimpleTest/setup.js:253:20
[task 2019-10-02T04:08:58.849Z] 04:08:58 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-10-02T04:08:59.612Z] 04:08:59 INFO - GECKO(1832) | MEMORY STAT | vsize 2552MB | residentFast 130MB | heapAllocated 13MB
[task 2019-10-02T04:08:59.613Z] 04:08:59 INFO - TEST-OK | dom/security/test/csp/test_upgrade_insecure_reporting.html | took 318205ms

Comment 1

[Geoff Brown [:gbrown]]

So far this appears to be a low-frequency fission-only intermittent test failure. This test was recently un-skipped on fission by bug 1584992.

Comment 2

[Intermittent Failures Robot]

4 failures in 4292 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4

Platform breakdown:

• linux64-qr: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• mozilla-central: 1

Platform breakdown:

• linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-11-04&endday=2019-11-10&tree=all

Comment 5

[Chris Peterson [:cpeterson]]

Tentatively moving all bugs whose summaries mention "Fission" (or other Fission-related keywords) but are not assigned to a Fission Milestone to the "?" triage milestone.

This will generate a lot of bugmail, so you can filter your bugmail for the following UUID and delete them en masse:

0ee3c76a-bc79-4eb2-8d12-05dc0b68e732

Comment 6

[Intermittent Failures Robot]

3 failures in 4207 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2
• try: 1

Platform breakdown:

• linux64-qr: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-11-11&endday=2019-11-17&tree=all

Comment 7

[Bogdan Tara[:bogdan_tara | bogdant]]

Seeing this on tier 1: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=278239683&repo=autoland&lineNumber=12495

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform breakdown:

• linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64-qr: 1

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

Comment 10

[Christoph Kerschbaumer [:ckerschb]]

I'll take a look at this one.

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 12

[Christoph Kerschbaumer [:ckerschb]]

A few observations when trying to debug the problem here:

• 1 failures in 4582; indicates that the problem is almost negligible at this point.
• Manual inspection of the test does not reveal anything that might obviously be broken because of fission.
• The logs indicate that the image loading succeeds (grep for "upgraded insecure image load from http -> https" in the logs) which suggests that something around the CSP reporting in the test is broken. My hunch is that the stalled XHR request on the server gets lost before we can report back that the server received the CSP report. I will upload a patch which adds some logging around that. We can leave the bug open for now and next time around the robot posts something on the bug we can investigate those logs.

Comment 13

[Christoph Kerschbaumer [:ckerschb]]

Attachment: Bug 1585533: Add debug statements to fix intermittent test failure on fission for test_upgrade_insecure_reporting.html. r=jkt

Comment 14

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/5cf830ef2611
Add debug statements to fix intermittent test failure on fission for test_upgrade_insecure_reporting.html. r=jkt

Comment 15

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/5cf830ef2611

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2020-01-06&endday=2020-01-12&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-qr: 1

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

Comment 18

[Chris Peterson [:cpeterson]]

Christoph, this rare intermittent Fission test failure hasn't been seen for two months. Can we close this bug as WORKSFORME?

Comment 19

[Christoph Kerschbaumer [:ckerschb]]

Attachment: Bug 1585533: Remove debug statements for test test_upgrade_insecure_reporting.html. r=sstreich

Comment 20

[Christoph Kerschbaumer [:ckerschb]]

(In reply to Chris Peterson [:cpeterson] from comment #18)

Christoph, this rare intermittent Fission test failure hasn't been seen for two months. Can we close this bug as WORKSFORME?

Yes, I just wrote a patch to remove the debug statements. Removing leave-open keyword and this bug will be marked as resolved once the patch for removing the debug statements has landed.

Comment 21

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/c13a7a1fa129
Remove debug statements for test test_upgrade_insecure_reporting.html. r=sstreich

Comment 22

[Cristina Coroiu [:ccoroiu]]

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

Comment 23

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585533&startday=2020-03-30&endday=2020-04-05&tree=all