Closed Bug 1595356 Opened 6 years ago Closed 6 years ago

Intermittent dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: websocket-unexpected-error) - didn't expect -1, but got it

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [domsecurity-intermittent])

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=275518818&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dNplLl2mSAeUoEMRzH8fhg/runs/0/artifacts/public/logs/live_backing.log


[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel https (result: nested-img-ok)
[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - Buffered messages finished
[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - TEST-UNEXPECTED-FAIL | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: websocket-unexpected-error) - didn't expect -1, but got it
[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - SimpleTest.isnot@SimpleTest/SimpleTest.js:334:16
[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - checkResults@dom/security/test/csp/test_upgrade_insecure.html:138:8
[task 2019-11-10T02:47:21.929Z] 02:47:21 INFO - receiveMessage@dom/security/test/csp/test_upgrade_insecure.html:161:15
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - EventListener.handleEvent*@dom/security/test/csp/test_upgrade_insecure.html:159:8
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(aCSP) failed: file /builds/worker/workspace/build/src/dom/security/FramingChecker.cpp, line 176
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(mIsPending) failed: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpChannel.cpp, line 9618
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(aCSP) failed: file /builds/worker/workspace/build/src/dom/security/FramingChecker.cpp, line 176
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | ++DOCSHELL 0x11f95b000 == 11 [pid = 1710] [id = {9ede644d-6729-e54b-9e5f-bb01f950e143}]
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | ++DOMWINDOW == 30 (0x12b3f92e0) [pid = 1710] [serial = 963] [outer = 0x0]
[task 2019-11-10T02:47:21.930Z] 02:47:21 INFO - GECKO(1708) | ++DOMWINDOW == 31 (0x11fad9400) [pid = 1710] [serial = 964] [outer = 0x12b3f92e0]
[task 2019-11-10T02:47:21.931Z] 02:47:21 INFO - GECKO(1708) | [Child 1710, Main Thread] WARNING: Listener is not retargetable: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 3615
[task 2019-11-10T02:47:21.931Z] 02:47:21 INFO - GECKO(1708) | [Child 1710, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/workspace/build/src/parser/html/nsHtml5StreamParser.cpp, line 1142
[task 2019-11-10T02:47:21.970Z] 02:47:21 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(mSuspendCount > 0) failed: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpChannel.cpp, line 9659
[task 2019-11-10T02:47:21.971Z] 02:47:21 INFO - GECKO(1708) | ++DOMWINDOW == 32 (0x1285d4800) [pid = 1710] [serial = 965] [outer = 0x12744ce20]
[task 2019-11-10T02:47:22.136Z] 02:47:22 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(aCSP) failed: file /builds/worker/workspace/build/src/dom/security/FramingChecker.cpp, line 176
[task 2019-11-10T02:47:22.136Z] 02:47:22 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(mIsPending) failed: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpChannel.cpp, line 9618
[task 2019-11-10T02:47:22.136Z] 02:47:22 INFO - GECKO(1708) | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(mSuspendCount > 0) failed: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpChannel.cpp, line 9659
[task 2019-11-10T02:47:22.155Z] 02:47:22 INFO - GECKO(1708) | ++DOMWINDOW == 33 (0x12a0e8c00) [pid = 1710] [serial = 966] [outer = 0x12744c6a0]
[task 2019-11-10T02:47:22.187Z] 02:47:22 INFO - GECKO(1708) | --DOCSHELL 0x10eb44800 == 10 [pid = 1710] [id = {b78a2b0b-4c53-a343-b2fb-d464c3632600}] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?object]
[task 2019-11-10T02:47:22.187Z] 02:47:22 INFO - GECKO(1708) | --DOCSHELL 0x11f95c800 == 9 [pid = 1710] [id = {38f675d9-503e-6c43-ae90-368674a97ff2}] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?form]
[task 2019-11-10T02:47:22.216Z] 02:47:22 INFO - [1705, Main Thread] WARNING: 'result.isErr()', file /builds/worker/workspace/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-11-10T02:47:22.261Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: style-ok)
[task 2019-11-10T02:47:22.261Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: script-ok)
[task 2019-11-10T02:47:22.261Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: img-ok)
[task 2019-11-10T02:47:22.261Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: form-ok)
[task 2019-11-10T02:47:22.261Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: object-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: iframe-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: xhr-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: font-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: media-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: img-redir-ok)
[task 2019-11-10T02:47:22.262Z] 02:47:22 INFO - TEST-PASS | dom/security/test/csp/test_upgrade_insecure.html | upgrade all requests on toplevel http (result: nested-img-ok)
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 32 (0x12b319400) [pid = 1710] [serial = 942] [outer = 0x0] [url = http://example.com/tests/dom/security/test/csp/file_windowwatcher_subframeC.html]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 31 (0x12b683800) [pid = 1710] [serial = 947] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_windowwatcher_win_open.html]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 30 (0x12b754800) [pid = 1710] [serial = 948] [outer = 0x0] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 29 (0x11f93f000) [pid = 1710] [serial = 935] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/security/test/csp/test_uir_windowwatcher.html]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 28 (0x11fadec00) [pid = 1710] [serial = 941] [outer = 0x0] [url = http://example.com/tests/dom/security/test/csp/file_windowwatcher_subframeB.html]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 27 (0x12b757400) [pid = 1710] [serial = 951] [outer = 0x0] [url = about:blank]
[task 2019-11-10T02:47:22.274Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 26 (0x12b4a0000) [pid = 1710] [serial = 943] [outer = 0x0] [url = http://example.com/tests/dom/security/test/csp/file_windowwatcher_subframeD.html]
[task 2019-11-10T02:47:22.275Z] 02:47:22 INFO - GECKO(1708) | --DOCSHELL 0x11f96c000 == 8 [pid = 1710] [id = {0e82575f-999f-5c43-9b13-fe49f320ea59}] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?iframe]
[task 2019-11-10T02:47:22.384Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 25 (0x12b3f9a60) [pid = 1710] [serial = 954] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?form]
[task 2019-11-10T02:47:22.384Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 24 (0x12b3f96a0) [pid = 1710] [serial = 953] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?iframe]
[task 2019-11-10T02:47:22.384Z] 02:47:22 INFO - GECKO(1708) | --DOMWINDOW == 23 (0x12b3f9880) [pid = 1710] [serial = 956] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?object]
[task 2019-11-10T02:47:26.449Z] 02:47:26 INFO - GECKO(1708) | --DOMWINDOW == 22 (0x11fa6f400) [pid = 1710] [serial = 962] [outer = 0x0] [url = about:blank]
[task 2019-11-10T02:47:26.449Z] 02:47:26 INFO - GECKO(1708) | --DOMWINDOW == 21 (0x12b75ec00) [pid = 1710] [serial = 952] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_testserver.sjs?file=tests/dom/security/test/csp/file_upgrade_insecure.html&csp=upgrade-insecure-requests%3Bblock-all-mixed-content%3Bdefault-src%20https%3A%20wss%3A%20%27unsafe-inline%27%3Bform-action%20https%3A%3B]
[task 2019-11-10T02:47:26.449Z] 02:47:26 INFO - GECKO(1708) | --DOMWINDOW == 20 (0x11fad7400) [pid = 1710] [serial = 958] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?iframe]
[task 2019-11-10T02:47:26.449Z] 02:47:26 INFO - GECKO(1708) | --DOMWINDOW == 19 (0x11f9c8800) [pid = 1710] [serial = 957] [outer = 0x0] [url = https://example.com/tests/dom/security/test/csp/file_upgrade_insecure_server.sjs?object]
[task 2019-11-10T02:47:26.449Z] 02:47:26 INFO - GECKO(1708) | --DOMWINDOW == 18 (0x11f937800) [pid = 1710] [serial = 955] [outer = 0x0] [url = about:blank]
[task 2019-11-10T02:47:28.706Z] 02:47:28 INFO - GECKO(1708) | --DOMWINDOW == 1 (0x147b41020) [pid = 1711] [serial = 1] [outer = 0x0] [url = about:blank]
[task 2019-11-10T02:47:32.978Z] 02:47:32 INFO - GECKO(1708) | --DOMWINDOW == 0 (0x147b4a400) [pid = 1711] [serial = 2] [outer = 0x0] [url = about:blank]
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/security/test/csp/test_upgrade_insecure.html | Test timed out.
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.525Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - EventHandlerNonNull
getTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - hookup@SimpleTest/setup.js:253:20
[task 2019-11-10T02:52:20.526Z] 02:52:20 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2F8m%2Fhd429pnx4qvgrgc744rt68n4000017%2FT&cleanupCrashes=true:11:1
[task 2019-11-10T02:52:21.655Z] 02:52:21 INFO - GECKO(1708) | MEMORY STAT | vsize 7382MB | residentFast 180MB | heapAllocated 31MB

Whiteboard: [domsecurity-intermittent]
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.