Intermittent TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-embedder-policy/cache-storage-reporting-shared-worker.https.html | expected CRASH
Categories
(Core :: DOM: Workers, defect, P2)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Blocks 1 open bug, Regression)
Details
(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])
Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=295916918&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B0ckRNuRQY6Vf8H9URC7LQ/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/B0ckRNuRQY6Vf8H9URC7LQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
INFO - TEST-START | /html/cross-origin-embedder-policy/cache-storage-reporting-shared-worker.https.html
[task 2020-04-02T11:12:10.728Z] 11:12:10 INFO - Run 0/10
[task 2020-04-02T11:12:10.836Z] 11:12:10 INFO - PID 1062 | [Child 1654, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1692
[task 2020-04-02T11:12:10.836Z] 11:12:10 INFO - Setting pref browser.tabs.remote.useCrossOriginEmbedderPolicy (true)
[task 2020-04-02T11:12:10.837Z] 11:12:10 INFO - PID 1062 | [Child 1654, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 839
[task 2020-04-02T11:12:10.852Z] 11:12:10 INFO - PID 1062 | [2020-04-02T11:12:10Z WARN rkv::backend::impl_safe::environment] Ignoring `map_size=16777216`
[task 2020-04-02T11:12:11.030Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-04-02T11:12:11.030Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-02T11:12:11.030Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-02T11:12:11.287Z] 11:12:11 INFO - PID 1062 | [Child 1592, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 839
[task 2020-04-02T11:12:11.538Z] 11:12:11 INFO - PID 1062 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpzMnd2N/runtests_leaks_1002_tab_pid1728.log
[task 2020-04-02T11:12:11.881Z] 11:12:11 INFO - PID 1062 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-04-02T11:12:11.902Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-04-02T11:12:11.944Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-02T11:12:11.945Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-02T11:12:11.946Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-04-02T11:12:11.946Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-02T11:12:11.948Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-02T11:12:11.949Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-04-02T11:12:11.951Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-02T11:12:11.952Z] 11:12:11 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-02T11:12:12.081Z] 11:12:12 INFO - PID 1062 | [Child 1728, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1692
[task 2020-04-02T11:12:12.340Z] 11:12:12 INFO - PID 1062 | [Child 1654, Main Thread] WARNING: '!aWin', file /builds/worker/checkouts/gecko/dom/base/Document.cpp, line 1397
[task 2020-04-02T11:12:12.396Z] 11:12:12 INFO - PID 1062 | [Child 1654, Main Thread] WARNING: '!aWin', file /builds/worker/checkouts/gecko/dom/base/Document.cpp, line 1397
[task 2020-04-02T11:12:13.157Z] 11:12:13 INFO - PID 1062 | [Child 1592, Main Thread] WARNING: Workers don't support the 'mem.mem.' preference!: file /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp, line 538
[task 2020-04-02T11:15:12.321Z] 11:15:12 INFO - TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-embedder-policy/cache-storage-reporting-shared-worker.https.html | expected CRASH
[task 2020-04-02T11:15:12.322Z] 11:15:12 INFO - TEST-INFO expected CRASH | took 181594ms
[task 2020-04-02T11:15:12.358Z] 11:15:12 INFO - PID 1062 | 1585826112352 Marionette INFO Stopped listening on port 51120
[task 2020-04-02T11:15:12.641Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.641Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.641Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.641Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.642Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.642Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.642Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.642Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.642Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.643Z] 11:15:12 INFO - PID 1062 | [Parent 1062, URL Classifier] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 245
[task 2020-04-02T11:15:12.663Z] 11:15:12 INFO - PID 1062 | ###!!! [Child][MessageChannel] Error: (msgtype=0x5A000D,name=PHttpChannel::Msg_DeletingChannel) Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.664Z] 11:15:12 INFO - PID 1062 | [Child 1592, Main Thread] WARNING: MsgDropped in ContentChild: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 2253
[task 2020-04-02T11:15:12.679Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.680Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.680Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.680Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.680Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.684Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.685Z] 11:15:12 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:12.766Z] 11:15:12 INFO - PID 1062 | [Child 1479, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-02T11:15:12.782Z] 11:15:12 INFO - PID 1062 | [2020-04-02T11:15:12Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-04-02T11:15:12.783Z] 11:15:12 INFO - PID 1062 | [2020-04-02T11:15:12Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-04-02T11:15:12.792Z] 11:15:12 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:12.793Z] 11:15:12 INFO - PID 1062 | => mAllocCount: 11639
[task 2020-04-02T11:15:12.793Z] 11:15:12 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:12.793Z] 11:15:12 INFO - PID 1062 | => mFreeCount: 11639
[task 2020-04-02T11:15:12.794Z] 11:15:12 INFO - PID 1062 | => mShareCount: 9721
[task 2020-04-02T11:15:12.794Z] 11:15:12 INFO - PID 1062 | => mAdoptCount: 501
[task 2020-04-02T11:15:12.796Z] 11:15:12 INFO - PID 1062 | => mAdoptFreeCount: 509
[task 2020-04-02T11:15:12.796Z] 11:15:12 INFO - PID 1062 | => Process ID: 1479, Thread ID: 139811500193664
[task 2020-04-02T11:15:12.873Z] 11:15:12 INFO - PID 1062 | [Child 1218, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-02T11:15:12.888Z] 11:15:12 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:12.889Z] 11:15:12 INFO - PID 1062 | => mAllocCount: 24406
[task 2020-04-02T11:15:12.889Z] 11:15:12 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:12.889Z] 11:15:12 INFO - PID 1062 | => mFreeCount: 24406
[task 2020-04-02T11:15:12.889Z] 11:15:12 INFO - PID 1062 | => mShareCount: 11622
[task 2020-04-02T11:15:12.890Z] 11:15:12 INFO - PID 1062 | => mAdoptCount: 447
[task 2020-04-02T11:15:12.890Z] 11:15:12 INFO - PID 1062 | => mAdoptFreeCount: 479
[task 2020-04-02T11:15:12.890Z] 11:15:12 INFO - PID 1062 | => Process ID: 1218, Thread ID: 140553800095616
[task 2020-04-02T11:15:12.926Z] 11:15:12 INFO - PID 1062 | [Child 1592, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-02T11:15:12.946Z] 11:15:12 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:12.947Z] 11:15:12 INFO - PID 1062 | => mAllocCount: 2950627
[task 2020-04-02T11:15:12.948Z] 11:15:12 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:12.949Z] 11:15:12 INFO - PID 1062 | => mFreeCount: 2950627
[task 2020-04-02T11:15:12.949Z] 11:15:12 INFO - PID 1062 | => mShareCount: 6254259
[task 2020-04-02T11:15:12.949Z] 11:15:12 INFO - PID 1062 | => mAdoptCount: 14376
[task 2020-04-02T11:15:12.949Z] 11:15:12 INFO - PID 1062 | => mAdoptFreeCount: 14388
[task 2020-04-02T11:15:12.950Z] 11:15:12 INFO - PID 1062 | => Process ID: 1592, Thread ID: 139700403050368
[task 2020-04-02T11:15:13.107Z] 11:15:13 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:13.123Z] 11:15:13 INFO - PID 1062 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-04-02T11:15:13.179Z] 11:15:13 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:13.179Z] 11:15:13 INFO - PID 1062 | => mAllocCount: 6940
[task 2020-04-02T11:15:13.179Z] 11:15:13 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:13.179Z] 11:15:13 INFO - PID 1062 | => mFreeCount: 6940
[task 2020-04-02T11:15:13.180Z] 11:15:13 INFO - PID 1062 | => mShareCount: 3045
[task 2020-04-02T11:15:13.180Z] 11:15:13 INFO - PID 1062 | => mAdoptCount: 72
[task 2020-04-02T11:15:13.180Z] 11:15:13 INFO - PID 1062 | => mAdoptFreeCount: 72
[task 2020-04-02T11:15:13.180Z] 11:15:13 INFO - PID 1062 | => Process ID: 1728, Thread ID: 139987998754688
[task 2020-04-02T11:15:13.180Z] 11:15:13 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x80070057: file /builds/worker/checkouts/gecko/extensions/permissions/nsPermissionManager.cpp, line 1663
[task 2020-04-02T11:15:13.382Z] 11:15:13 INFO - PID 1062 | [Child 1654, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3352
[task 2020-04-02T11:15:13.423Z] 11:15:13 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:13.423Z] 11:15:13 INFO - PID 1062 | => mAllocCount: 12591
[task 2020-04-02T11:15:13.423Z] 11:15:13 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:13.424Z] 11:15:13 INFO - PID 1062 | => mFreeCount: 12591
[task 2020-04-02T11:15:13.424Z] 11:15:13 INFO - PID 1062 | => mShareCount: 14141
[task 2020-04-02T11:15:13.424Z] 11:15:13 INFO - PID 1062 | => mAdoptCount: 572
[task 2020-04-02T11:15:13.424Z] 11:15:13 INFO - PID 1062 | => mAdoptFreeCount: 576
[task 2020-04-02T11:15:13.424Z] 11:15:13 INFO - PID 1062 | => Process ID: 1654, Thread ID: 139641456260992
[task 2020-04-02T11:15:13.541Z] 11:15:13 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1279
[task 2020-04-02T11:15:13.597Z] 11:15:13 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 228
[task 2020-04-02T11:15:13.597Z] 11:15:13 INFO - PID 1062 | [Parent 1062, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 255
[task 2020-04-02T11:15:14.566Z] 11:15:14 INFO - PID 1062 | nsStringStats
[task 2020-04-02T11:15:14.566Z] 11:15:14 INFO - PID 1062 | => mAllocCount: 1077468
[task 2020-04-02T11:15:14.567Z] 11:15:14 INFO - PID 1062 | => mReallocCount: 0
[task 2020-04-02T11:15:14.567Z] 11:15:14 INFO - PID 1062 | => mFreeCount: 1077468
[task 2020-04-02T11:15:14.568Z] 11:15:14 INFO - PID 1062 | => mShareCount: 834804
[task 2020-04-02T11:15:14.568Z] 11:15:14 INFO - PID 1062 | => mAdoptCount: 16321
[task 2020-04-02T11:15:14.569Z] 11:15:14 INFO - PID 1062 | => mAdoptFreeCount: 16427
[task 2020-04-02T11:15:14.569Z] 11:15:14 INFO - PID 1062 | => Process ID: 1062, Thread ID: 139944544651136
[task 2020-04-02T11:15:14.637Z] 11:15:14 INFO - Browser exited with return code 0
[task 2020-04-02T11:15:14.638Z] 11:15:14 INFO - PROCESS LEAKS /tmp/tmpzMnd2N/runtests_leaks_1002.log
Comment 1•5 years ago
|
||
This looks to be a regression of Bug 1626224.
James: can you please take a look at this bug?
Comment hidden (Intermittent Failures Robot) |
Updated•5 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment 4•5 years ago
|
||
I believe the spike here is also regressed by bug 1626089. fix should be https://treeherder.mozilla.org/#/jobs?repo=try&revision=ada18c14ba3dd8a788486b5ef28ed935f8963fff&selectedJob=297154323
Comment 5•5 years ago
|
||
The retriggers pointed out what Andreea commented above:
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=linux%2C18.04%2Cx64%2Cdebug%2Cweb%2Cplatform%2Ctests%2Ctest-linux1804-64%2Fdebug-web-platform-tests-e10s-7%2Cw%28wpt7%29&tochange=acc1632e35c7afc826d16bea8e1dd812b4f5117c&fromchange=8d3f2162b557eee29b56dba6774fdf02492e43f8&selectedJob=297293131
This is still failing, even the fix landed:
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=Linux%2C18.04%2Cx64%2Cdebug%2CWeb%2Cplatform%2Ctests%2Ctest-linux1804-64%2Fdebug-web-platform-tests-e10s-7%2CW%28wpt7%29&tochange=07440613e13aae679dc169cbd7ab7a5527be3d96&fromchange=e4034d6f39e5d691bd74118f9fc0c1e215c45dd7&selectedJob=297276640
Updated•5 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Updated•5 years ago
|
Updated•5 years ago
|
Updated•5 years ago
|
Comment 8•4 years ago
|
||
No failures in >21 days.
Updated•4 years ago
|
Comment 10•4 years ago
|
||
(In reply to Valentin Gosu [:valentin] (he/him) from comment #8)
No failures in >21 days.
Description
•