Closed Bug 1525994 Opened 6 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | Same-origin service worker allowed by script-src 'self'. - Test timed out

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

#[markdown(off)]
Filed by: ncsoregi [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=226849362&repo=autoland

https://queue.taskcluster.net/v1/task/a4YlXbIGQMGi8453iVwYFg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-02-07T15:04:06.173Z] 15:04:06 INFO - TEST-START | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html
[task 2019-02-07T15:04:06.180Z] 15:04:06 INFO - Closing window 42949672961
[task 2019-02-07T15:04:16.572Z] 15:04:16 INFO -
[task 2019-02-07T15:04:16.572Z] 15:04:16 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | Same-origin service worker allowed by script-src 'self'. - Test timed out
[task 2019-02-07T15:04:16.573Z] 15:04:16 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | expected OK
[task 2019-02-07T15:04:16.573Z] 15:04:16 INFO - TEST-INFO took 10400ms
[task 2019-02-07T15:04:16.594Z] 15:04:16 INFO - PID 4962 | 1549551856588 Marionette INFO Stopped listening on port 2828
[task 2019-02-07T15:04:16.713Z] 15:04:16 INFO - PID 4962 | [Parent 4962, Gecko_IOThread] WARNING: pipe error (88): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 357
[task 2019-02-07T15:04:16.714Z] 15:04:16 INFO - PID 4962 | [Parent 4962, Gecko_IOThread] WARNING: pipe error (103): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 357
[task 2019-02-07T15:04:16.714Z] 15:04:16 INFO - PID 4962 | [Parent 4962, Gecko_IOThread] WARNING: pipe error (139): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 357
[task 2019-02-07T15:04:17.088Z] 15:04:17 INFO - Browser exited with return code 0

Whiteboard: [domsecurity-intermittent]
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=281053431&repo=try&lineNumber=8093

task 2019-12-13T12:39:03.975Z] 12:39:03 INFO - TEST-START | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html
[task 2019-12-13T12:39:03.975Z] 12:39:03 INFO - Closing window 58
[task 2019-12-13T12:39:04.091Z] 12:39:04 INFO - PID 1721 | [Child 1748, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2019-12-13T12:39:04.091Z] 12:39:04 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:04.162Z] 12:39:04 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-13T12:39:04.234Z] 12:39:04 INFO - PID 1721 | [Child 1748, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | nsStringStats
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | => mAllocCount: 8775
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | => mReallocCount: 0
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | => mFreeCount: 8775
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | => mShareCount: 10146
[task 2019-12-13T12:39:04.247Z] 12:39:04 INFO - PID 1721 | => mAdoptCount: 470
[task 2019-12-13T12:39:04.248Z] 12:39:04 INFO - PID 1721 | => mAdoptFreeCount: 486
[task 2019-12-13T12:39:04.248Z] 12:39:04 INFO - PID 1721 | => Process ID: 1748, Thread ID: 4675573184
[task 2019-12-13T12:39:04.678Z] 12:39:04 INFO - PID 1721 | [1750, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-13T12:39:04.678Z] 12:39:04 INFO - PID 1721 | [1750, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-13T12:39:04.682Z] 12:39:04 INFO - PID 1721 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gh/dw53c20x50s11wtlv65fqj14000017/T/tmpHwwv1E.mozrunner/runtests_leaks_1524_tab_pid1750.log
[task 2019-12-13T12:39:04.682Z] 12:39:04 INFO - PID 1721 | [1750, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-13T12:39:04.682Z] 12:39:04 INFO - PID 1721 | [1750, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-13T12:39:04.779Z] 12:39:04 INFO - PID 1721 | [Child 1750, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 727
[task 2019-12-13T12:39:04.910Z] 12:39:04 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: Workers don't support the 'mem.mem.' preference!: file /builds/worker/workspace/build/src/dom/workers/RuntimeService.cpp, line 540
[task 2019-12-13T12:39:04.910Z] 12:39:04 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 573
[task 2019-12-13T12:39:04.950Z] 12:39:04 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: 'mIsWorkerScript || (mState != ServiceWorkerState::Parsed && mState != ServiceWorkerState::Installing)', file /builds/worker/workspace/build/src/dom/workers/ScriptLoader.cpp, line 1717
[task 2019-12-13T12:39:08.431Z] 12:39:08 INFO - PID 1721 | 2019-12-13 12:39:08.412 firefox[1721:12217] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightlydebug.savedState/window_1.data: No such file or directory (2)
[task 2019-12-13T12:39:35.058Z] 12:39:35 INFO -
[task 2019-12-13T12:39:35.058Z] 12:39:35 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | Same-origin service worker allowed by script-src 'self'. - Test timed out
[task 2019-12-13T12:39:35.058Z] 12:39:35 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | expected OK
[task 2019-12-13T12:39:35.058Z] 12:39:35 INFO - TEST-INFO took 31077ms
[task 2019-12-13T12:39:35.114Z] 12:39:35 INFO - PID 1721 | 1576240775059 Marionette INFO Stopped listening on port 49987
[task 2019-12-13T12:39:35.290Z] 12:39:35 INFO - PID 1721 | [Child 1723, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2019-12-13T12:39:35.292Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.292Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.326Z] 12:39:35 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2019-12-13T12:39:35.326Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.492Z] 12:39:35 INFO - PID 1721 | [Child 1749, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-12-13T12:39:35.493Z] 12:39:35 INFO - PID 1721 | [Child 1723, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-12-13T12:39:35.559Z] 12:39:35 INFO - PID 1721 | nsStringStats
[task 2019-12-13T12:39:35.559Z] 12:39:35 INFO - PID 1721 | => mAllocCount: 16378
[task 2019-12-13T12:39:35.568Z] 12:39:35 INFO - PID 1721 | => mReallocCount: 0
[task 2019-12-13T12:39:35.568Z] 12:39:35 INFO - PID 1721 | => mFreeCount: 16378
[task 2019-12-13T12:39:35.568Z] 12:39:35 INFO - PID 1721 | => mShareCount: 11505
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mAdoptCount: 440
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mAdoptFreeCount: 484
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => Process ID: 1723, Thread ID: 4517107136
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | nsStringStats
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mAllocCount: 8881
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mReallocCount: 0
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mFreeCount: 8881
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mShareCount: 10398
[task 2019-12-13T12:39:35.569Z] 12:39:35 INFO - PID 1721 | => mAdoptCount: 541
[task 2019-12-13T12:39:35.570Z] 12:39:35 INFO - PID 1721 | => mAdoptFreeCount: 549
[task 2019-12-13T12:39:35.570Z] 12:39:35 INFO - PID 1721 | => Process ID: 1749, Thread ID: 4520850880
[task 2019-12-13T12:39:35.773Z] 12:39:35 INFO - PID 1721 | [Child 1724, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2019-12-13T12:39:35.773Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.773Z] 12:39:35 INFO - PID 1721 | [Child 1750, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2019-12-13T12:39:35.773Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.773Z] 12:39:35 INFO - PID 1721 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-12-13T12:39:35.775Z] 12:39:35 INFO - PID 1721 | nsStringStats
[task 2019-12-13T12:39:35.775Z] 12:39:35 INFO - PID 1721 | => mAllocCount: 4937
[task 2019-12-13T12:39:35.775Z] 12:39:35 INFO - PID 1721 | => mReallocCount: 0
[task 2019-12-13T12:39:35.776Z] 12:39:35 INFO - PID 1721 | => mFreeCount: 4937
[task 2019-12-13T12:39:35.778Z] 12:39:35 INFO - PID 1721 | => mShareCount: 4245
[task 2019-12-13T12:39:35.778Z] 12:39:35 INFO - PID 1721 | => mAdoptCount: 72
[task 2019-12-13T12:39:35.778Z] 12:39:35 INFO - PID 1721 | => mAdoptFreeCount: 72
[task 2019-12-13T12:39:35.778Z] 12:39:35 INFO - PID 1721 | => Process ID: 1750, Thread ID: 4549604800
[task 2019-12-13T12:39:35.958Z] 12:39:35 INFO - PID 1721 | [Child 1724, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-12-13T12:39:36.011Z] 12:39:36 INFO - PID 1721 | nsStringStats
[task 2019-12-13T12:39:36.011Z] 12:39:36 INFO - PID 1721 | => mAllocCount: 14297
[task 2019-12-13T12:39:36.011Z] 12:39:36 INFO - PID 1721 | => mReallocCount: 0
[task 2019-12-13T12:39:36.014Z] 12:39:36 INFO - PID 1721 | => mFreeCount: 14297
[task 2019-12-13T12:39:36.014Z] 12:39:36 INFO - PID 1721 | => mShareCount: 25885
[task 2019-12-13T12:39:36.015Z] 12:39:36 INFO - PID 1721 | => mAdoptCount: 774
[task 2019-12-13T12:39:36.015Z] 12:39:36 INFO - PID 1721 | => mAdoptFreeCount: 798
[task 2019-12-13T12:39:36.015Z] 12:39:36 INFO - PID 1721 | => Process ID: 1724, Thread ID: 4582643136

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=288419081&repo=mozilla-release&lineNumber=2120

[task 2020-02-11T22:16:10.056Z] 22:16:10 INFO - TEST-START | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html
[task 2020-02-11T22:16:10.084Z] 22:16:10 INFO - Closing window 57
[task 2020-02-11T22:16:10.084Z] 22:16:10 INFO - PID 5852 | Gtk-Message: 22:16:10.080: Failed to load module "canberra-gtk-module"
[task 2020-02-11T22:16:10.086Z] 22:16:10 INFO - PID 5852 | Gtk-Message: 22:16:10.082: Failed to load module "canberra-gtk-module"
[task 2020-02-11T22:16:10.499Z] 22:16:10 INFO - PID 5852 | Gtk-Message: 22:16:10.487: Failed to load module "canberra-gtk-module"
[task 2020-02-11T22:16:10.500Z] 22:16:10 INFO - PID 5852 | Gtk-Message: 22:16:10.489: Failed to load module "canberra-gtk-module"
[task 2020-02-11T22:16:20.712Z] 22:16:20 INFO -
[task 2020-02-11T22:16:20.712Z] 22:16:20 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | Same-origin service worker allowed by script-src 'self'. - Test timed out
[task 2020-02-11T22:16:20.713Z] 22:16:20 INFO - TEST-UNEXPECTED-TIMEOUT | /content-security-policy/worker-src/service-worker-src-script-fallback.https.sub.html | expected OK
[task 2020-02-11T22:16:20.713Z] 22:16:20 INFO - TEST-INFO took 10654ms

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.