Closed Bug 1580606 Opened 5 years ago Closed 5 years ago

Perma tier 2 toolkit/components/antitracking/test/browser/browser_blockingCookies.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(Core :: Privacy: Anti-Tracking, defect, P5)

Product:
Core
Component:
Privacy: Anti-Tracking
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla71
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox69 --- unaffected
firefox70 --- unaffected
firefox71 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: ehsan.akhgari)

References

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1580606 - Increase the timeout in all anti-tracking tests by a factor of two when running ccov tests;
47 bytes, text/x-phabricator-request
Details | Review

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

[task 2019-09-11T17:50:27.105Z] 17:50:27 INFO - TEST-START | toolkit/components/antitracking/test/browser/browser_blockingCookies.js
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO - GECKO(6730) | [Parent 6730, Main Thread] ###!!! ASSERTION: OnDataAvailable implementation consumed no data: 'Error', file /builds/worker/workspace/build/src/netwerk/base/nsInputStreamPump.cpp, line 577
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO - GECKO(6730) | #01: nsInputStreamPump::OnStateTransfer() [netwerk/base/nsInputStreamPump.cpp:577]
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO - GECKO(6730) | #02: nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) [netwerk/base/nsInputStreamPump.cpp:399]
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO - GECKO(6730) | #03: nsInputStreamReadyEvent::Run() [xpcom/io/nsStreamUtils.cpp:91]
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO - GECKO(6730) | #04: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1229]
[task 2019-09-11T17:50:27.458Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO - GECKO(6730) | #05: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:486]
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO - GECKO(6730) | #06: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:88]
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO - GECKO(6730) | #07: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:315]
[task 2019-09-11T17:50:27.459Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.460Z] 17:50:27 INFO - GECKO(6730) | #08: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:289]
[task 2019-09-11T17:50:27.460Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.464Z] 17:50:27 INFO - GECKO(6730) | #09: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2019-09-11T17:50:27.465Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.467Z] 17:50:27 INFO - GECKO(6730) | #10: nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:276]
[task 2019-09-11T17:50:27.467Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.468Z] 17:50:27 INFO - GECKO(6730) | #11: XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:4586]
[task 2019-09-11T17:50:27.469Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.469Z] 17:50:27 INFO - GECKO(6730) | #12: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4720]
[task 2019-09-11T17:50:27.470Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.471Z] 17:50:27 INFO - GECKO(6730) | #13: XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:4801]
[task 2019-09-11T17:50:27.471Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.471Z] 17:50:27 INFO - GECKO(6730) | #14: mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/Bootstrap.cpp:45][task 2019-09-11T17:50:27.472Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.475Z] 17:50:27 INFO - GECKO(6730) | #15: _fini
[task 2019-09-11T17:50:27.475Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO - GECKO(6730) | #16: _fini
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO - GECKO(6730) | #17: libc.so.6 + 0x20830
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO - GECKO(6730) | #18: _fini
[task 2019-09-11T17:50:27.476Z] 17:50:27 INFO -
[task 2019-09-11T17:50:27.512Z] 17:50:27 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a4c000 == 4 [pid = 6886] [id = {a046db7b-0600-444c-be7e-282085c8a4dd}]
[task 2019-09-11T17:50:27.513Z] 17:50:27 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954f336a0) [pid = 6886] [serial = 11] [outer = (nil)]
[task 2019-09-11T17:50:27.513Z] 17:50:27 INFO - GECKO(6730) | ++DOMWINDOW == 10 (0x7fe954fa2c00) [pid = 6886] [serial = 12] [outer = 0x7fe954f336a0]
[task 2019-09-11T17:50:27.832Z] 17:50:27 INFO - GECKO(6730) | ++DOMWINDOW == 11 (0x7fe95515ac00) [pid = 6886] [serial = 13] [outer = 0x7fe954f336a0]
[task 2019-09-11T17:50:28.030Z] 17:50:28 INFO - GECKO(6730) | ++DOCSHELL 0x7fe95513a000 == 5 [pid = 6886] [id = {5e81549d-fb48-4ece-9838-4168a1bc0f9b}]
[task 2019-09-11T17:50:28.031Z] 17:50:28 INFO - GECKO(6730) | ++DOMWINDOW == 12 (0x7fe954f332e0) [pid = 6886] [serial = 14] [outer = (nil)]
[task 2019-09-11T17:50:28.032Z] 17:50:28 INFO - GECKO(6730) | ++DOMWINDOW == 13 (0x7fe95515f000) [pid = 6886] [serial = 15] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:28.276Z] 17:50:28 INFO - GECKO(6730) | ++DOMWINDOW == 14 (0x7fe955162c00) [pid = 6886] [serial = 16] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:28.458Z] 17:50:28 INFO - GECKO(6730) | --DOCSHELL 0x7fe97e98b000 == 4 [pid = 6886] [id = {03469e67-fb49-4d6a-b4f7-4a6bdfccc035}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:28.499Z] 17:50:28 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a46000 == 3 [pid = 6886] [id = {02693e1d-c1c0-47ae-b8fa-ad80d57663d8}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:28.500Z] 17:50:28 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a49000 == 2 [pid = 6886] [id = {28f26cbd-2a66-486c-a6f3-ccb4d62f3c42}] [url = about:blank]
[task 2019-09-11T17:50:29.107Z] 17:50:29 INFO - GECKO(6730) | [Parent 6730, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-11T17:50:29.272Z] 17:50:29 INFO - GECKO(6730) | [Parent 6730, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f496d18c710 (http://mzl.la/1FuID0j).: file /builds/worker/workspace/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
[task 2019-09-11T17:50:31.152Z] 17:50:31 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7f17ce06a800) [pid = 6859] [serial = 52] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:31.153Z] 17:50:31 INFO - GECKO(6730) | --DOCSHELL 0x7f17ce051000 == 1 [pid = 6859] [id = {95978ce8-37c2-44ff-a917-3b3f24fba82a}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:31.154Z] 17:50:31 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7f17ce067c00) [pid = 6859] [serial = 49] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:31.155Z] 17:50:31 INFO - GECKO(6730) | --DOMWINDOW == 6 (0x7f17ce071800) [pid = 6859] [serial = 47] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:31.257Z] 17:50:31 INFO - GECKO(6730) | --DOMWINDOW == 5 (0x7f17ce4c44c0) [pid = 6859] [serial = 48] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:31.258Z] 17:50:31 INFO - GECKO(6730) | --DOMWINDOW == 4 (0x7f17ce4c3f20) [pid = 6859] [serial = 51] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdPartySVG.html]
[task 2019-09-11T17:50:31.481Z] 17:50:31 INFO - GECKO(6730) | --DOCSHELL 0x7fd2c8385000 == 0 [pid = 6963] [id = {c81258a9-21fe-4dd3-86bf-5a6d3e89aa28}] [url = about:blank]
[task 2019-09-11T17:50:32.672Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 13 (0x7fe954f98400) [pid = 6886] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:32.673Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 12 (0x7fe9550ad800) [pid = 6886] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:32.675Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 11 (0x7fe954fa2c00) [pid = 6886] [serial = 12] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:32.675Z] 17:50:32 INFO - GECKO(6730) | --DOCSHELL 0x7fe95513a000 == 1 [pid = 6886] [id = {5e81549d-fb48-4ece-9838-4168a1bc0f9b}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:32.676Z] 17:50:32 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a4c000 == 0 [pid = 6886] [id = {a046db7b-0600-444c-be7e-282085c8a4dd}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:32.858Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 10 (0x7fe954f332e0) [pid = 6886] [serial = 14] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:32.859Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 9 (0x7fe954f32f20) [pid = 6886] [serial = 6] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:32.861Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe954f33100) [pid = 6886] [serial = 3] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:32.861Z] 17:50:32 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7fe954f334c0) [pid = 6886] [serial = 9] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:34.047Z] 17:50:34 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a47800 == 1 [pid = 6886] [id = {e32280cb-5608-425c-acd1-3435a5ddd992}]
[task 2019-09-11T17:50:34.049Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 8 (0x7fe954f33100) [pid = 6886] [serial = 17] [outer = (nil)]
[task 2019-09-11T17:50:34.050Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954f31800) [pid = 6886] [serial = 18] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:34.429Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 10 (0x7fe9550bac00) [pid = 6886] [serial = 19] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:34.587Z] 17:50:34 INFO - GECKO(6730) | ++DOCSHELL 0x7fe955133000 == 2 [pid = 6886] [id = {3781b399-8572-44a8-ac24-070d2458ab3d}]
[task 2019-09-11T17:50:34.587Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 11 (0x7fe954f32f20) [pid = 6886] [serial = 20] [outer = (nil)]
[task 2019-09-11T17:50:34.587Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 12 (0x7fe95515d000) [pid = 6886] [serial = 21] [outer = 0x7fe954f32f20]
[task 2019-09-11T17:50:34.813Z] 17:50:34 INFO - GECKO(6730) | ++DOMWINDOW == 13 (0x7fe956360c00) [pid = 6886] [serial = 22] [outer = 0x7fe954f32f20][task 2019-09-11T17:50:35.137Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 12 (0x7fe955162c00) [pid = 6886] [serial = 16] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:35.138Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 11 (0x7fe95515f000) [pid = 6886] [serial = 15] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:35.138Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 10 (0x7fe956368800) [pid = 6886] [serial = 8] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:35.138Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 9 (0x7fe9550b6400) [pid = 6886] [serial = 5] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:35.138Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe954fa1800) [pid = 6886] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:35.255Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7fe954f336a0) [pid = 6886] [serial = 11] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:35.271Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 3 (0x7f17cf977400) [pid = 6859] [serial = 50] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:35.272Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 2 (0x7f17ce072000) [pid = 6859] [serial = 53] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdPartySVG.html]
[task 2019-09-11T17:50:35.618Z] 17:50:35 INFO - GECKO(6730) | --DOMWINDOW == 1 (0x7fd2f1db5f20) [pid = 6963] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:35.675Z] 17:50:35 INFO - GECKO(6730) | [Parent 6730, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-11T17:50:35.893Z] 17:50:35 INFO - GECKO(6730) | [Parent 6730, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f496d18c710 (http://mzl.la/1FuID0j).: file /builds/worker/workspace/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
[task 2019-09-11T17:50:39.249Z] 17:50:39 INFO - GECKO(6730) | --DOCSHELL 0x7fe955133000 == 1 [pid = 6886] [id = {3781b399-8572-44a8-ac24-070d2458ab3d}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:39.249Z] 17:50:39 INFO - GECKO(6730) | --DOMWINDOW == 6 (0x7fe95515ac00) [pid = 6886] [serial = 13] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:39.249Z] 17:50:39 INFO - GECKO(6730) | --DOMWINDOW == 5 (0x7fe95515d000) [pid = 6886] [serial = 21] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:39.250Z] 17:50:39 INFO - GECKO(6730) | --DOMWINDOW == 4 (0x7fe954f31800) [pid = 6886] [serial = 18] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:39.266Z] 17:50:39 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a47800 == 0 [pid = 6886] [id = {e32280cb-5608-425c-acd1-3435a5ddd992}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:39.343Z] 17:50:39 INFO - GECKO(6730) | --DOMWINDOW == 3 (0x7fe954f32f20) [pid = 6886] [serial = 20] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:39.646Z] 17:50:39 INFO - GECKO(6730) | --DOMWINDOW == 0 (0x7fd2c8453800) [pid = 6963] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:40.684Z] 17:50:40 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a48800 == 1 [pid = 6886] [id = {ffa3f3b3-7ce7-450e-b6be-078737e488be}]
[task 2019-09-11T17:50:40.684Z] 17:50:40 INFO - GECKO(6730) | ++DOMWINDOW == 4 (0x7fe954f332e0) [pid = 6886] [serial = 23] [outer = (nil)]
[task 2019-09-11T17:50:40.685Z] 17:50:40 INFO - GECKO(6730) | ++DOMWINDOW == 5 (0x7fe952104400) [pid = 6886] [serial = 24] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:41.009Z] 17:50:41 INFO - GECKO(6730) | ++DOMWINDOW == 6 (0x7fe95210b400) [pid = 6886] [serial = 25] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:41.437Z] 17:50:41 INFO - GECKO(6730) | ++DOMWINDOW == 7 (0x7fe952110000) [pid = 6886] [serial = 26] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:41.563Z] 17:50:41 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a4b800 == 2 [pid = 6886] [id = {acdaa6da-8151-49e8-8199-0dc1cc702c39}]
[task 2019-09-11T17:50:41.563Z] 17:50:41 INFO - GECKO(6730) | ++DOMWINDOW == 8 (0x7fe954f32f20) [pid = 6886] [serial = 27] [outer = (nil)]
[task 2019-09-11T17:50:41.566Z] 17:50:41 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954fa1000) [pid = 6886] [serial = 28] [outer = 0x7fe954f32f20]
[task 2019-09-11T17:50:41.804Z] 17:50:41 INFO - GECKO(6730) | ++DOMWINDOW == 10 (0x7fe95210f400) [pid = 6886] [serial = 29] [outer = 0x7fe954f32f20]
[task 2019-09-11T17:50:41.849Z] 17:50:41 INFO - GECKO(6730) | --DOMWINDOW == 9 (0x7fe956360c00) [pid = 6886] [serial = 22] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:42.228Z] 17:50:42 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe954f33100) [pid = 6886] [serial = 17] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:42.670Z] 17:50:42 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954b59800) [pid = 6886] [serial = 30] [outer = 0x7fe954f332e0]
[task 2019-09-11T17:50:42.893Z] 17:50:42 INFO - GECKO(6730) | [Parent 6730, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-11T17:50:43.103Z] 17:50:43 INFO - GECKO(6730) | [Parent 6730, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f496d18c710 (http://mzl.la/1FuID0j).: file /builds/worker/workspace/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
[task 2019-09-11T17:50:43.855Z] 17:50:43 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a4b800 == 1 [pid = 6886] [id = {acdaa6da-8151-49e8-8199-0dc1cc702c39}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:43.855Z] 17:50:43 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe95210b400) [pid = 6886] [serial = 25] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:43.855Z] 17:50:43 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7fe952104400) [pid = 6886] [serial = 24] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:43.855Z] 17:50:43 INFO - GECKO(6730) | --DOMWINDOW == 6 (0x7fe9550bac00) [pid = 6886] [serial = 19] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:43.855Z] 17:50:43 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a48800 == 0 [pid = 6886] [id = {ffa3f3b3-7ce7-450e-b6be-078737e488be}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:47.636Z] 17:50:47 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a4a000 == 1 [pid = 6886] [id = {bfcef51b-0c3c-419c-87ce-aa2bbf855684}]
[task 2019-09-11T17:50:47.636Z] 17:50:47 INFO - GECKO(6730) | ++DOMWINDOW == 7 (0x7fe954f334c0) [pid = 6886] [serial = 31] [outer = (nil)]
[task 2019-09-11T17:50:47.636Z] 17:50:47 INFO - GECKO(6730) | ++DOMWINDOW == 8 (0x7fe952102c00) [pid = 6886] [serial = 32] [outer = 0x7fe954f334c0]
[task 2019-09-11T17:50:47.976Z] 17:50:47 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe95210c400) [pid = 6886] [serial = 33] [outer = 0x7fe954f334c0]
[task 2019-09-11T17:50:48.214Z] 17:50:48 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a49800 == 2 [pid = 6886] [id = {039c4400-2571-4347-bdd7-33bed81bef11}]
[task 2019-09-11T17:50:48.214Z] 17:50:48 INFO - GECKO(6730) | ++DOMWINDOW == 10 (0x7fe954f33100) [pid = 6886] [serial = 34] [outer = (nil)]
[task 2019-09-11T17:50:48.230Z] 17:50:48 INFO - GECKO(6730) | ++DOMWINDOW == 11 (0x7fe9550b2c00) [pid = 6886] [serial = 35] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:48.432Z] 17:50:48 INFO - GECKO(6730) | --DOMWINDOW == 10 (0x7fe954fa1000) [pid = 6886] [serial = 28] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:48.535Z] 17:50:48 INFO - GECKO(6730) | ++DOMWINDOW == 11 (0x7fe954fa2c00) [pid = 6886] [serial = 36] [outer = 0x7fe954f33100][task 2019-09-11T17:50:48.785Z] 17:50:48 INFO - GECKO(6730) | --DOMWINDOW == 10 (0x7fe954f332e0) [pid = 6886] [serial = 23] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:48.786Z] 17:50:48 INFO - GECKO(6730) | --DOMWINDOW == 9 (0x7fe954f32f20) [pid = 6886] [serial = 27] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:49.340Z] 17:50:49 INFO - GECKO(6730) | [Parent 6730, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-11T17:50:49.537Z] 17:50:49 INFO - GECKO(6730) | [Parent 6730, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f496d18c710 (http://mzl.la/1FuID0j).: file /builds/worker/workspace/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
[task 2019-09-11T17:50:52.581Z] 17:50:52 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a49800 == 1 [pid = 6886] [id = {039c4400-2571-4347-bdd7-33bed81bef11}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:52.582Z] 17:50:52 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe95210f400) [pid = 6886] [serial = 29] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:52.582Z] 17:50:52 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7fe954b59800) [pid = 6886] [serial = 30] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:52.584Z] 17:50:52 INFO - GECKO(6730) | --DOMWINDOW == 6 (0x7fe952110000) [pid = 6886] [serial = 26] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:52.585Z] 17:50:52 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a4a000 == 0 [pid = 6886] [id = {bfcef51b-0c3c-419c-87ce-aa2bbf855684}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:52.646Z] 17:50:52 INFO - GECKO(6730) | --DOMWINDOW == 5 (0x7fe954f33100) [pid = 6886] [serial = 34] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:54.126Z] 17:50:54 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a47800 == 1 [pid = 6886] [id = {8bbf89d1-7b0b-46ce-aa01-d24baaef6500}]
[task 2019-09-11T17:50:54.127Z] 17:50:54 INFO - GECKO(6730) | ++DOMWINDOW == 6 (0x7fe954f33100) [pid = 6886] [serial = 37] [outer = (nil)]
[task 2019-09-11T17:50:54.128Z] 17:50:54 INFO - GECKO(6730) | ++DOMWINDOW == 7 (0x7fe952104000) [pid = 6886] [serial = 38] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:54.436Z] 17:50:54 INFO - GECKO(6730) | ++DOMWINDOW == 8 (0x7fe95210ec00) [pid = 6886] [serial = 39] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:54.876Z] 17:50:54 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954fa1400) [pid = 6886] [serial = 40] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:55.017Z] 17:50:55 INFO - GECKO(6730) | ++DOCSHELL 0x7fe955136800 == 2 [pid = 6886] [id = {7f59c7eb-214d-4075-8b61-0c3dcce0d5ca}]
[task 2019-09-11T17:50:55.018Z] 17:50:55 INFO - GECKO(6730) | ++DOMWINDOW == 10 (0x7fe954f32f20) [pid = 6886] [serial = 41] [outer = (nil)]
[task 2019-09-11T17:50:55.019Z] 17:50:55 INFO - GECKO(6730) | ++DOMWINDOW == 11 (0x7fe9550af000) [pid = 6886] [serial = 42] [outer = 0x7fe954f32f20]
[task 2019-09-11T17:50:55.241Z] 17:50:55 INFO - GECKO(6730) | ++DOMWINDOW == 12 (0x7fe9550bbc00) [pid = 6886] [serial = 43] [outer = 0x7fe954f32f20]
[task 2019-09-11T17:50:55.595Z] 17:50:55 INFO - GECKO(6730) | --DOMWINDOW == 11 (0x7fe952102c00) [pid = 6886] [serial = 32] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:55.595Z] 17:50:55 INFO - GECKO(6730) | --DOMWINDOW == 10 (0x7fe9550b2c00) [pid = 6886] [serial = 35] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:55.595Z] 17:50:55 INFO - GECKO(6730) | --DOMWINDOW == 9 (0x7fe954fa2c00) [pid = 6886] [serial = 36] [outer = (nil)] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:55.740Z] 17:50:55 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe954f334c0) [pid = 6886] [serial = 31] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:56.059Z] 17:50:56 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe95210c000) [pid = 6886] [serial = 44] [outer = 0x7fe954f33100]
[task 2019-09-11T17:50:56.277Z] 17:50:56 INFO - GECKO(6730) | [Parent 6730, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-11T17:50:56.495Z] 17:50:56 INFO - GECKO(6730) | [Parent 6730, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f496d18c710 (http://mzl.la/1FuID0j).: file /builds/worker/workspace/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
[task 2019-09-11T17:50:58.862Z] 17:50:58 INFO - GECKO(6730) | --DOCSHELL 0x7fe955136800 == 1 [pid = 6886] [id = {7f59c7eb-214d-4075-8b61-0c3dcce0d5ca}] [url = https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:50:59.040Z] 17:50:59 INFO - GECKO(6730) | --DOMWINDOW == 8 (0x7fe95210c400) [pid = 6886] [serial = 33] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:59.041Z] 17:50:59 INFO - GECKO(6730) | --DOMWINDOW == 7 (0x7fe952104000) [pid = 6886] [serial = 38] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:59.041Z] 17:50:59 INFO - GECKO(6730) | --DOMWINDOW == 6 (0x7fe95210ec00) [pid = 6886] [serial = 39] [outer = (nil)] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:50:59.042Z] 17:50:59 INFO - GECKO(6730) | --DOMWINDOW == 5 (0x7fe9550af000) [pid = 6886] [serial = 42] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:50:59.043Z] 17:50:59 INFO - GECKO(6730) | --DOCSHELL 0x7fe954a47800 == 0 [pid = 6886] [id = {8bbf89d1-7b0b-46ce-aa01-d24baaef6500}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:51:01.109Z] 17:51:01 INFO - GECKO(6730) | ++DOCSHELL 0x7fe954a4a000 == 1 [pid = 6886] [id = {53bdeab2-d225-4f0e-ab36-53cc88e554dc}]
[task 2019-09-11T17:51:01.109Z] 17:51:01 INFO - GECKO(6730) | ++DOMWINDOW == 6 (0x7fe954f334c0) [pid = 6886] [serial = 45] [outer = (nil)]
[task 2019-09-11T17:51:01.110Z] 17:51:01 INFO - GECKO(6730) | ++DOMWINDOW == 7 (0x7fe952102c00) [pid = 6886] [serial = 46] [outer = 0x7fe954f334c0]
[task 2019-09-11T17:51:01.422Z] 17:51:01 INFO - GECKO(6730) | ++DOMWINDOW == 8 (0x7fe95210ac00) [pid = 6886] [serial = 47] [outer = 0x7fe954f334c0]
[task 2019-09-11T17:51:01.894Z] 17:51:01 INFO - GECKO(6730) | ++DOMWINDOW == 9 (0x7fe954f9e000) [pid = 6886] [serial = 48] [outer = 0x7fe954f334c0]
[task 2019-09-11T17:51:01.999Z] 17:51:01 INFO - GECKO(6730) | ++DOCSHELL 0x7fe955137000 == 2 [pid = 6886] [id = {c4286951-1ee3-4a65-8878-1e5c31314c2c}]
...
[task 2019-09-11T17:56:34.109Z] 17:56:34 INFO - GECKO(6730) | --DOCSHELL 0x7f4976d3a000 == 7 [pid = 6730] [id = {d2aea21b-6899-4d11-883f-f4dc8fb03e46}] [url = about:blank]
[task 2019-09-11T17:56:35.701Z] 17:56:35 INFO - GECKO(6730) | --DOCSHELL 0x7f89a0c9f800 == 1 [pid = 6926] [id = {21b8ede9-ea4d-4d8e-8152-a9aff65fe6e4}] [url = https://another-tracking.example.net/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:56:35.701Z] 17:56:35 INFO - GECKO(6730) | --DOMWINDOW == 5 (0x7f89a0c77800) [pid = 6926] [serial = 170] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:35.701Z] 17:56:35 INFO - GECKO(6730) | --DOCSHELL 0x7f89a0c9d800 == 0 [pid = 6926] [id = {c24f1c51-ba1b-4a1b-bbff-470787b7c6cf}] [url = http://example.net/browser/toolkit/components/antitracking/test/browser/page.html]
[task 2019-09-11T17:56:35.762Z] 17:56:35 INFO - GECKO(6730) | --DOMWINDOW == 4 (0x7f89a24fd4c0) [pid = 6926] [serial = 172] [outer = (nil)] [url = https://another-tracking.example.net/browser/toolkit/components/antitracking/test/browser/3rdParty.html]
[task 2019-09-11T17:56:35.810Z] 17:56:35 INFO - GECKO(6730) | --DOCSHELL 0x7f4965b87000 == 6 [pid = 6730] [id = {01b189d8-4a35-4cd4-a8c9-1a0b481834c3}] [url = chrome://browser/content/browser.xhtml]
[task 2019-09-11T17:56:35.907Z] 17:56:35 INFO - GECKO(6730) | --DOMWINDOW == 2 (0x7fe952103800) [pid = 6886] [serial = 235] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.075Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 17 (0x7f496b97d400) [pid = 6730] [serial = 148] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.083Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 16 (0x7f496bf2e3e0) [pid = 6730] [serial = 150] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.083Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 15 (0x7f496bf2e200) [pid = 6730] [serial = 149] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.083Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 14 (0x7f496cda3000) [pid = 6730] [serial = 152] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.084Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 13 (0x7f496bb07400) [pid = 6730] [serial = 151] [outer = (nil)] [url = about:blank]
[task 2019-09-11T17:56:37.084Z] 17:56:37 INFO - GECKO(6730) | --DOMWINDOW == 12 (0x7f496bf2e020) [pid = 6730] [serial = 147] [outer = (nil)] [url = chrome://browser/content/browser.xhtml]
[task 2019-09-11T17:56:38.694Z] 17:56:38 INFO - GECKO(6730) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-09-11T17:56:38.775Z] 17:56:38 INFO - TEST-INFO | started process screentopng
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - TEST-INFO | screentopng: exit 0
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - <snipped 1413 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Buffered messages logged at 17:56:10
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/server.sjs” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - checking with arg=?redirect
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Buffered messages logged at 17:56:11
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/server.sjs” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/server.sjs” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.222Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 24 column: 4 source: "https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html line 37 > eval"}]
[task 2019-09-11T17:56:39.223Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Still no cookies for me - true == true - [task 2019-09-11T17:56:39.223Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Should have storage access now - true == true -
[task 2019-09-11T17:56:39.223Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 29 column: 4 source: "https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html line 37 > eval"}]
[task 2019-09-11T17:56:39.224Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No cookies for me - true == true -
[task 2019-09-11T17:56:39.224Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 30 column: 4 source: "https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html line 37 > eval"}]
[task 2019-09-11T17:56:39.225Z] 17:56:39 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 40 column: 6 source: "https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/3rdParty.html line 37 > eval"}]
[task 2019-09-11T17:56:39.226Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No cookies for me - true == true -
[task 2019-09-11T17:56:39.228Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some console message should be generated -
[task 2019-09-11T17:56:39.232Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.233Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.234Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.236Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.237Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.238Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.239Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.241Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.244Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.245Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.247Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Message should be of expected category -
[task 2019-09-11T17:56:39.248Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Checking cookie blocking notifications -
[task 2019-09-11T17:56:39.249Z] 17:56:39 INFO - Removing the tab
[task 2019-09-11T17:56:39.250Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.251Z] 17:56:39 INFO - Entering test bound
[task 2019-09-11T17:56:39.252Z] 17:56:39 INFO - Cleaning up.
[task 2019-09-11T17:56:39.254Z] 17:56:39 INFO - Buffered messages logged at 17:56:17
[task 2019-09-11T17:56:39.255Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.256Z] 17:56:39 INFO - Entering test bound
[task 2019-09-11T17:56:39.257Z] 17:56:39 INFO - Starting blocking cookieBehavior (4) with allow list test Cookies and Storage Access API running in a private window with iframe sandbox set to null and access removal set to null
[task 2019-09-11T17:56:39.258Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | callbackAfterRemoval must be passed when accessRemoval is non-null -
[task 2019-09-11T17:56:39.258Z] 17:56:39 INFO - Buffered messages logged at 17:56:19
[task 2019-09-11T17:56:39.259Z] 17:56:39 INFO - Creating a new tab
[task 2019-09-11T17:56:39.260Z] 17:56:39 INFO - Buffered messages logged at 17:56:20
[task 2019-09-11T17:56:39.262Z] 17:56:39 INFO - Disabling content blocking for this page
[task 2019-09-11T17:56:39.263Z] 17:56:39 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.263Z] 17:56:39 INFO - Buffered messages logged at 17:56:21
[task 2019-09-11T17:56:39.264Z] 17:56:39 INFO - Creating a 3rd party content
[task 2019-09-11T17:56:39.265Z] 17:56:39 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.267Z] 17:56:39 INFO - Sending code to the 3rd party content
[task 2019-09-11T17:56:39.268Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Has storage access - true == true -
[task 2019-09-11T17:56:39.269Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No cookies for me - true == true -
[task 2019-09-11T17:56:39.270Z] 17:56:39 INFO - checking with arg=?checkonly
[task 2019-09-11T17:56:39.271Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.272Z] 17:56:39 INFO - checking with arg=?redirect
[task 2019-09-11T17:56:39.272Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.273Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.273Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.274Z] 17:56:39 INFO - checking with arg=
[task 2019-09-11T17:56:39.275Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should have cookies - true == true -
[task 2019-09-11T17:56:39.276Z] 17:56:39 INFO - checking with arg=?redirect
[task 2019-09-11T17:56:39.276Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should have cookies - true == true -
[task 2019-09-11T17:56:39.277Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some Cookies for me - true == true -
[task 2019-09-11T17:56:39.277Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Should have storage access now - true == true - [task 2019-09-11T17:56:39.278Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Still some Cookies for me - true == true -
[task 2019-09-11T17:56:39.280Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.280Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.281Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No console messages should be generated -
[task 2019-09-11T17:56:39.281Z] 17:56:39 INFO - Enabling content blocking for this page
[task 2019-09-11T17:56:39.282Z] 17:56:39 INFO - Buffered messages logged at 17:56:22
[task 2019-09-11T17:56:39.282Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Checking cookie blocking notifications -
[task 2019-09-11T17:56:39.283Z] 17:56:39 INFO - Removing the tab
[task 2019-09-11T17:56:39.283Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.284Z] 17:56:39 INFO - Entering test bound
[task 2019-09-11T17:56:39.284Z] 17:56:39 INFO - Cleaning up.
[task 2019-09-11T17:56:39.286Z] 17:56:39 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.286Z] 17:56:39 INFO - Buffered messages logged at 17:56:28
[task 2019-09-11T17:56:39.287Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.288Z] 17:56:39 INFO - Entering test bound
[task 2019-09-11T17:56:39.288Z] 17:56:39 INFO - Starting blocking cookieBehavior (4) without allow list test Cookies and Storage Access API running in a private window with iframe sandbox set to null and access removal set to null and third party page set to https://another-tracking.example.net/browser/toolkit/components/antitracking/test/browser/3rdParty.html
[task 2019-09-11T17:56:39.289Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | callbackAfterRemoval must be passed when accessRemoval is non-null -
[task 2019-09-11T17:56:39.289Z] 17:56:39 INFO - Buffered messages logged at 17:56:30
[task 2019-09-11T17:56:39.290Z] 17:56:39 INFO - Creating a new tab
[task 2019-09-11T17:56:39.290Z] 17:56:39 INFO - Buffered messages logged at 17:56:31
[task 2019-09-11T17:56:39.292Z] 17:56:39 INFO - Creating a 3rd party content
[task 2019-09-11T17:56:39.293Z] 17:56:39 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-09-11T17:56:39.295Z] 17:56:39 INFO - Sending code to the 3rd party content
[task 2019-09-11T17:56:39.295Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Doesn't yet have storage access - true == true -
[task 2019-09-11T17:56:39.297Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No cookies for me - true == true -
[task 2019-09-11T17:56:39.298Z] 17:56:39 INFO - checking with arg=?checkonly
[task 2019-09-11T17:56:39.299Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.300Z] 17:56:39 INFO - checking with arg=?redirect
[task 2019-09-11T17:56:39.301Z] 17:56:39 INFO - Buffered messages logged at 17:56:32
[task 2019-09-11T17:56:39.302Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should not have cookies - true == true -
[task 2019-09-11T17:56:39.303Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.303Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.303Z] 17:56:39 INFO - checking with arg=
[task 2019-09-11T17:56:39.304Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should have cookies - true == true -
[task 2019-09-11T17:56:39.304Z] 17:56:39 INFO - checking with arg=?redirect
[task 2019-09-11T17:56:39.305Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | We should have cookies - true == true -
[task 2019-09-11T17:56:39.306Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some Cookies for me - true == true -
[task 2019-09-11T17:56:39.307Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Should have storage access now - true == true -
[task 2019-09-11T17:56:39.309Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Still some Cookies for me - true == true -
[task 2019-09-11T17:56:39.310Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.312Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Some cookies for me - true == true -
[task 2019-09-11T17:56:39.315Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | No console messages should be generated -
[task 2019-09-11T17:56:39.315Z] 17:56:39 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | Checking cookie blocking notifications -
[task 2019-09-11T17:56:39.316Z] 17:56:39 INFO - Removing the tab[task 2019-09-11T17:56:39.316Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.317Z] 17:56:39 INFO - Entering test bound
[task 2019-09-11T17:56:39.317Z] 17:56:39 INFO - Cleaning up.
[task 2019-09-11T17:56:39.318Z] 17:56:39 INFO - Buffered messages logged at 17:56:38
[task 2019-09-11T17:56:39.318Z] 17:56:39 INFO - Leaving test bound
[task 2019-09-11T17:56:39.318Z] 17:56:39 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-09-11T17:56:39.319Z] 17:56:39 INFO - Buffered messages finished
[task 2019-09-11T17:56:39.319Z] 17:56:39 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/antitracking/test/browser/browser_blockingCookies.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Summary: Intermittent toolkit/components/antitracking/test/browser/browser_blockingCookies.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - → Perma tier 2 toolkit/components/antitracking/test/browser/browser_blockingCookies.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Marco, could you please take a look at this? It looks like it's still failing even after Bug 1577105 landed: https://hg.mozilla.org/mozilla-central/rev/0e0e9c22321e

Flags: needinfo?(mcastelluccio)
See Also: → 1577105

The fix for bug 1577105 wasn't done in a general way and the patch wasn't reviewed by a module peer, so tests such as browser_blockingCookies.js which themselves override the timeout were left unfixed...

Flags: needinfo?(mcastelluccio)
Pushed by eakhgari@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/fd4cd8f47843 Increase the timeout in all anti-tracking tests by a factor of two when running ccov tests; r=marco
Flags: needinfo?(ehsan)
Pushed by eakhgari@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/551628b1c3b8 Increase the timeout in all anti-tracking tests by a factor of two when running ccov tests; r=marco

https://hg.mozilla.org/mozilla-central/rev/551628b1c3b8

Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox71: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla71
Assignee: nobody → ehsan
See Also: → 1582241
See Also: → 1582970
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: