Closed Bug 1611852 Opened 10 months ago Closed 7 months ago

Intermittent Tier 2 Fission /html/cross-origin-embedder-policy/blob.https.html | assertion count 2 is more than expected 0 assertions

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED WORKSFORME
Fission Milestone Future

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

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


[task 2020-01-27T20:53:26.512Z] 20:53:26 INFO - TEST-START | /html/cross-origin-embedder-policy/blob.https.html
[task 2020-01-27T20:53:26.594Z] 20:53:26 INFO - Setting pref browser.tabs.remote.useCrossOriginEmbedderPolicy (true)
[task 2020-01-27T20:53:26.675Z] 20:53:26 INFO - Setting pref browser.tabs.remote.useCORP (true)
[task 2020-01-27T20:53:26.790Z] 20:53:26 INFO - PID 16708 | [2020-01-27T20:53:26Z WARN rkv::backend::impl_safe::environment] Ignoring map_size=16777216
[task 2020-01-27T20:53:26.867Z] 20:53:26 INFO - PID 16708 | [Child 16915, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:26.869Z] 20:53:26 INFO - PID 16708 | [Child 16915, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:26.871Z] 20:53:26 INFO - PID 16708 | [Child 16915, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:26.910Z] 20:53:26 INFO - PID 16708 | JavaScript error: chrome://marionette/content/listener.js, line 378: SecurityError: Permission denied to access property "windowUtils" on cross-origin object
[task 2020-01-27T20:53:26.966Z] 20:53:26 INFO - PID 16708 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmprHSp6B.mozrunner/runtests_leaks_1061_tab_pid16948.log
[task 2020-01-27T20:53:27.083Z] 20:53:27 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-01-27T20:53:27.322Z] 20:53:27 INFO - PID 16708 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-01-27T20:53:27.655Z] 20:53:27 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1822
[task 2020-01-27T20:53:27.723Z] 20:53:27 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-01-27T20:53:27.740Z] 20:53:27 INFO - PID 16708 | [Child 16915, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-27T20:53:27.837Z] 20:53:27 INFO - PID 16708 | nsStringStats
[task 2020-01-27T20:53:27.839Z] 20:53:27 INFO - PID 16708 | => mAllocCount: 7923
[task 2020-01-27T20:53:27.839Z] 20:53:27 INFO - PID 16708 | => mReallocCount: 0
[task 2020-01-27T20:53:27.839Z] 20:53:27 INFO - PID 16708 | => mFreeCount: 7923
[task 2020-01-27T20:53:27.840Z] 20:53:27 INFO - PID 16708 | => mShareCount: 5913
[task 2020-01-27T20:53:27.841Z] 20:53:27 INFO - PID 16708 | => mAdoptCount: 379
[task 2020-01-27T20:53:27.842Z] 20:53:27 INFO - PID 16708 | => mAdoptFreeCount: 385
[task 2020-01-27T20:53:27.842Z] 20:53:27 INFO - PID 16708 | => Process ID: 16915, Thread ID: 140711763720064
[task 2020-01-27T20:53:29.058Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.554Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.595Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.615Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.639Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.716Z] 20:53:29 INFO - PID 16708 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmprHSp6B.mozrunner/runtests_leaks_1061_tab_pid16984.log
[task 2020-01-27T20:53:29.793Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.793Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.794Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:29.832Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.833Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:29.834Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:29.850Z] 20:53:29 INFO - PID 16708 | [Child 16984, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-01-27T20:53:29.894Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2020-01-27T20:53:29.895Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2020-01-27T20:53:29.993Z] 20:53:29 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.171Z] 20:53:30 INFO - PID 16708 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-01-27T20:53:30.293Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.294Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.294Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:30.301Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.343Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.346Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:30.362Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.364Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-27T20:53:30.366Z] 20:53:30 INFO - PID 16708 | [Child 16948, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 584
[task 2020-01-27T20:53:30.423Z] 20:53:30 INFO - PID 16708 | [Child 16984, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1822
[task 2020-01-27T20:53:30.501Z] 20:53:30 INFO - PID 16708 | [Child 16984, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-01-27T20:53:30.637Z] 20:53:30 INFO - PID 16708 | [Parent 16708, Main Thread] WARNING: Received load event on unbridged BrowserParent!: file /builds/worker/workspace/build/src/dom/ipc/BrowserParent.cpp, line 3931
[task 2020-01-27T20:53:30.637Z] 20:53:30 INFO - PID 16708 | [Parent 16708, Main Thread] WARNING: Received load event on unbridged BrowserParent!: file /builds/worker/workspace/build/src/dom/ipc/BrowserParent.cpp, line 3931
[task 2020-01-27T20:53:30.678Z] 20:53:30 INFO - PID 16708 | [Parent 16708, Main Thread] ###!!! ASSERTION: failed to establish the background channel: 'Error', file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelParent.cpp, line 999
[task 2020-01-27T20:53:30.680Z] 20:53:30 INFO - PID 16708 | [Parent 16708, Main Thread] ###!!! ASSERTION: failed to establish the background channel: 'Error', file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelParent.cpp, line 999
[task 2020-01-27T20:53:30.805Z] 20:53:30 INFO - PID 16708 | [Child 16984, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-27T20:53:30.841Z] 20:53:30 INFO - PID 16708 | nsStringStats
[task 2020-01-27T20:53:30.843Z] 20:53:30 INFO - PID 16708 | => mAllocCount: 6526
[task 2020-01-27T20:53:30.845Z] 20:53:30 INFO - PID 16708 | => mReallocCount: 0
[task 2020-01-27T20:53:30.846Z] 20:53:30 INFO - PID 16708 | => mFreeCount: 6526
[task 2020-01-27T20:53:30.849Z] 20:53:30 INFO - PID 16708 | => mShareCount: 3842
[task 2020-01-27T20:53:30.850Z] 20:53:30 INFO - PID 16708 | => mAdoptCount: 89
[task 2020-01-27T20:53:30.851Z] 20:53:30 INFO - PID 16708 | => mAdoptFreeCount: 93
[task 2020-01-27T20:53:30.851Z] 20:53:30 INFO - PID 16708 | => Process ID: 16984, Thread ID: 140611684722560
[task 2020-01-27T20:53:30.851Z] 20:53:30 INFO - Got chrome assert count 2
[task 2020-01-27T20:53:31.025Z] 20:53:31 INFO - TEST-UNEXPECTED-FAIL | /html/cross-origin-embedder-policy/blob.https.html | assertion count 2 is more than expected 0 assertions
[task 2020-01-27T20:53:31.025Z] 20:53:31 INFO - ......
[task 2020-01-27T20:53:31.025Z] 20:53:31 INFO - TEST-OK | /html/cross-origin-embedder-policy/blob.https.html | took 4511ms

Valentin, are these "failed to establish the background channel" assertion failures actually a problem? Or just a rare test race?

The assertion:

https://searchfox.org/mozilla-central/rev/5a10be606f2d76ef22f1f44565749490de991d35/netwerk/protocol/http/HttpChannelParent.cpp#1014

Nika says this bug does not need to block Fission.

Fission Milestone: --- → Future
Flags: needinfo?(valentin.gosu)

I don't think this is a big problem. It would actually be nice to have a test specifically for this failure case in HttpChannelParent.

Blocks: 1613061
Flags: needinfo?(valentin.gosu)
Blocks: 1595848
No longer blocks: 1613061
Status: NEW → RESOLVED
Closed: 7 months ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.