Closed Bug 1456013 Opened 7 years ago Closed 6 years ago

Intermittent GECKO(5663) | Assertion failure: m_refCount == 1, at /builds/worker/workspace/build/src/js/src/jit/ExecutableAllocator.cpp:51

Categories

(DevTools :: Debugger, defect, P3)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1531493

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled])

Attachments

(1 file)

Filed by: ncsoregi [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=175054673&repo=mozilla-central https://queue.taskcluster.net/v1/task/dc0zgqWGT-mf_2gaXB1aTA/runs/0/artifacts/public/logs/live_backing.log [task 2018-04-22T23:50:36.531Z] 23:50:36 INFO - GECKO(5663) | ERROR: GC found live SharedScriptData 0x7fe13b61aea0 with ref count 2 at shutdown [task 2018-04-22T23:50:36.532Z] 23:50:36 INFO - GECKO(5663) | ERROR: GC found live SharedScriptData 0x7fe13b688800 with ref count 2 at shutdown [task 2018-04-22T23:50:36.532Z] 23:50:36 INFO - GECKO(5663) | Assertion failure: m_refCount == 1, at /builds/worker/workspace/build/src/js/src/jit/ExecutableAllocator.cpp:51 [task 2018-04-22T23:50:36.533Z] 23:50:36 INFO - GECKO(5663) | ExceptionHandler::GenerateDump cloned child 5754 [task 2018-04-22T23:50:36.533Z] 23:50:36 INFO - GECKO(5663) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child [task 2018-04-22T23:50:36.535Z] 23:50:36 INFO - GECKO(5663) | ExceptionHandler::WaitForContinueSignal waiting for continue signal... [task 2018-04-22T23:50:36.536Z] 23:50:36 INFO - TEST-INFO | Main app process: exit 11 [task 2018-04-22T23:50:36.536Z] 23:50:36 INFO - Buffered messages finished [task 2018-04-22T23:50:36.537Z] 23:50:36 ERROR - TEST-UNEXPECTED-FAIL | tools/profiler/tests/chrome/test_profile_worker_bug_1428076.html | application terminated with exit code 11 [task 2018-04-22T23:50:36.537Z] 23:50:36 INFO - runtests.py | Application ran for: 0:00:25.749003 [task 2018-04-22T23:50:36.538Z] 23:50:36 INFO - zombiecheck | Reading PID log: /tmp/tmpzJpSpIpidlog [task 2018-04-22T23:50:36.539Z] 23:50:36 INFO - ==> process 5663 launched child process 5690 [task 2018-04-22T23:50:36.539Z] 23:50:36 INFO - zombiecheck | Checking for orphan process with PID: 5690 [task 2018-04-22T23:50:36.540Z] 23:50:36 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/Zil22F64T8CWlX6RagQjyg/artifacts/public/build/target.crashreporter-symbols.zip [task 2018-04-22T23:50:43.284Z] 23:50:43 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpJic7VP.mozrunner/minidumps/581ca9b5-cfe0-c6e5-8afd-5e395035e201.dmp /tmp/tmpIRQjWN [task 2018-04-22T23:50:50.587Z] 23:50:50 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/581ca9b5-cfe0-c6e5-8afd-5e395035e201.dmp [task 2018-04-22T23:50:50.587Z] 23:50:50 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/581ca9b5-cfe0-c6e5-8afd-5e395035e201.extra [task 2018-04-22T23:50:50.697Z] 23:50:50 INFO - PROCESS-CRASH | tools/profiler/tests/chrome/test_profile_worker_bug_1428076.html | application crashed [@ js::jit::ExecutablePool::release]
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

(In reply to Cristina Coroiu [:ccoroiu] from comment #4)

There are 31 failures in the last 7 days.

Brian, could be from https://bugzilla.mozilla.org/show_bug.cgi?id=1533202?
https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&revision=0ad12e8fb98c2c20a249cdd9bed5e032a71c500f&selectedJob=241168949

Yeah, this could be from bug 1533202. Except for a pretty minor change to dom/workers/RegisterBindings.cpp, that bug didn't touch C++ at all, and the new JS logic and test aren't doing anything particularly strange. The new test may have exposed an existing bug in the interface between the DOM and worker runtimes or in the JS engine, though. I believe this assertion failure means that the worker runtime is being destroyed while there are still references to some of its contents.

Flags: needinfo?(bhackett1024)

Current failures seem to happen in debugger-related tests => changing component :)

Component: Gecko Profiler → Debugger
Product: Core → DevTools

Jason can you please assign someone to take a look at this?

There are 161 total failures in the last 7 days on

  • debug: linux64, osx-10-10

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=242827398&repo=autoland&lineNumber=20361

[task 2019-04-26T10:21:21.593Z] 10:21:21 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js
[task 2019-04-26T10:21:21.634Z] 10:21:21 INFO - GECKO(1065) | ++DOCSHELL 0x7f6a01b18000 == 1 [pid = 1254] [id = {717e6667-b3d2-4721-a1b7-c55afefabc23}]
[task 2019-04-26T10:21:21.635Z] 10:21:21 INFO - GECKO(1065) | ++DOMWINDOW == 1 (0x7f6a05cb9020) [pid = 1254] [serial = 245] [outer = (nil)]
[task 2019-04-26T10:21:21.712Z] 10:21:21 INFO - GECKO(1065) | ++DOMWINDOW == 2 (0x7f6a0354dc00) [pid = 1254] [serial = 246] [outer = 0x7f6a05cb9020]
[task 2019-04-26T10:21:21.951Z] 10:21:21 INFO - GECKO(1065) | ++DOMWINDOW == 3 (0x7f6a035aa400) [pid = 1254] [serial = 247] [outer = 0x7f6a05cb9020]
[task 2019-04-26T10:21:22.273Z] 10:21:22 INFO - GECKO(1065) | --DOMWINDOW == 19 (0x7fe765f7a3e0) [pid = 1065] [serial = 614] [outer = (nil)] [url = about:blank]

[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | ERROR: GC found live SharedScriptData 0x7f6a065c3080 with ref count 2 at shutdown
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | ERROR: GC found live SharedScriptData 0x7f6a03a4e7a0 with ref count 2 at shutdown
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | Assertion failure: m_refCount == 1, at /builds/worker/workspace/build/src/js/src/jit/ExecutableAllocator.cpp:49
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | [ACTION] ADD_TAB - {"type":"ADD_TAB","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js","isOriginal":false,"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js"}
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | [ACTION] SET_SELECTED_LOCATION - {"type":"SET_SELECTED_LOCATION","cx":{"navigateCounter":0,"thread":"server1.conn94.child1/workerTarget23/context1","isPaused":true,"pauseCounter":1},"source":{"id":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js","isPrettyPrinted":false,"sourceMapURL":null,"introductionUrl":null,"isBlackBoxed":false,"isWasm":false,"isExtension":false},"location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js","line":11,"column":2}}
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x35001F,name=PContent::Msg_PreferenceUpdate) Channel error: cannot send/recv
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | [ACTION] SET_FOCUSED_SOURCE_ITEM - {}
[task 2019-04-26T10:21:32.295Z] 10:21:32 INFO - GECKO(1065) | [ACTION] SET_FOCUSED_SOURCE_ITEM - {}
[task 2019-04-26T10:21:32.415Z] 10:21:32 INFO - GECKO(1065) | [Parent 1065, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file /builds/worker/workspace/build/src/dom/events/EventListenerManager.cpp, line 351
[task 2019-04-26T10:21:32.416Z] 10:21:32 INFO - GECKO(1065) | [Parent 1065, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(d)', file /builds/worker/workspace/build/src/dom/events/EventListenerManager.cpp, line 351
[task 2019-04-26T10:21:32.432Z] 10:21:32 INFO - GECKO(1065) | [ACTION] SET_VIEWPORT - {"type":"SET_VIEWPORT","viewport":{"start":{"line":0,"column":0},"end":{"line":1,"column":92}}}
[task 2019-04-26T10:21:32.714Z] 10:21:32 INFO - GECKO(1065) | [ACTION] SET_VIEWPORT - {"type":"SET_VIEWPORT","viewport":{"start":{"line":3,"column":0},"end":{"line":14,"column":91}}}
[task 2019-04-26T10:21:32.782Z] 10:21:32 INFO - GECKO(1065) | [Parent 1065, Main Thread] ###!!! ASSERTION: Unexpected UpdateTransformLayer hint: '!(aChange & nsChangeHint_UpdateTransformLayer) || nsLayoutUtils::GetPrimaryFrameFromStyleFrame(aFrame) ->IsTransformed() || aFrame->StyleDisplay()->HasTransformStyle()', file /builds/worker/workspace/build/src/layout/base/RestyleManager.cpp, line 1200
[task 2019-04-26T10:21:32.786Z] 10:21:32 INFO - GECKO(1065) | #01: mozilla::RestyleManager::DoProcessPendingRestyles(mozilla::ServoTraversalFlags) [xpcom/ds/nsTArray.h:347]
[task 2019-04-26T10:21:32.786Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.789Z] 10:21:32 INFO - GECKO(1065) | #02: mozilla::PresShell::DoFlushPendingNotifications(mozilla::ChangesToFlush) [layout/base/PresShell.cpp:4145]
[task 2019-04-26T10:21:32.790Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.791Z] 10:21:32 INFO - GECKO(1065) | #03: mozilla::dom::Document::FlushPendingNotifications(mozilla::ChangesToFlush) [dom/base/Document.cpp:7353]
[task 2019-04-26T10:21:32.792Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.793Z] 10:21:32 INFO - GECKO(1065) | #04: nsIContent::GetPrimaryFrame(mozilla::FlushType) [dom/base/Element.cpp:231]
[task 2019-04-26T10:21:32.793Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.794Z] 10:21:32 INFO - GECKO(1065) | #05: mozilla::dom::Element::GetBoundingClientRect() [dom/base/Element.cpp:1034]
[task 2019-04-26T10:21:32.795Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.797Z] 10:21:32 INFO - GECKO(1065) | #06: mozilla::dom::Element_Binding::getBoundingClientRect(JSContext*, JS::Handle<JSObject*>, mozilla::dom::Element*, JSJitMethodCallArgs const&) [s3:gecko-generated-sources:64e0714a7dfd23af47026e78df788aadcbb64ee2f9c2cb7fed8716118fade166de45f305a246d7da1b12963e237a2b0c2a8934de4d26a6d37653e8c1476d322b/dom/bindings/ElementBinding.cpp::3015]
[task 2019-04-26T10:21:32.798Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.799Z] 10:21:32 INFO - GECKO(1065) | #07: bool mozilla::dom::binding_detail::GenericMethod<mozilla::dom::binding_detail::NormalThisPolicy, mozilla::dom::binding_detail::ThrowExceptions>(JSContext*, unsigned int, JS::Value*) [dom/bindings/BindingUtils.cpp:3155]
[task 2019-04-26T10:21:32.800Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.801Z] 10:21:32 INFO - GECKO(1065) | #08: CallJSNative(JSContext*, bool ()(JSContext, unsigned int, JS::Value*), JS::CallArgs const&) [js/src/vm/Interpreter.cpp:443]
[task 2019-04-26T10:21:32.802Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.804Z] 10:21:32 INFO - GECKO(1065) | #09: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct) [js/src/vm/Interpreter.cpp:535]
[task 2019-04-26T10:21:32.805Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.806Z] 10:21:32 INFO - GECKO(1065) | #10: Interpret(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:3080]
[task 2019-04-26T10:21:32.808Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.809Z] 10:21:32 INFO - GECKO(1065) | #11: js::RunScript(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:423]
[task 2019-04-26T10:21:32.810Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.811Z] 10:21:32 INFO - GECKO(1065) | #12: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct) [js/src/vm/Interpreter.cpp:563]
[task 2019-04-26T10:21:32.811Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.812Z] 10:21:32 INFO - GECKO(1065) | #13: <name omitted> [js/src/vm/Interpreter.cpp:606]
[task 2019-04-26T10:21:32.813Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.814Z] 10:21:32 INFO - GECKO(1065) | #14: js::jit::InvokeFunction(JSContext*, JS::Handle<JSObject*>, bool, bool, unsigned int, JS::Value*, JS::MutableHandle<JS::Value>) [js/src/jit/VMFunctions.cpp:259]
[task 2019-04-26T10:21:32.815Z] 10:21:32 INFO -
[task 2019-04-26T10:21:32.815Z] 10:21:32 INFO - GECKO(1065) | #15: js::jit::InvokeFromInterpreterStub(JSContext*, js::jit::InterpreterStubExitFrameLayout*) [js/src/jit/VMF

Flags: needinfo?(jlaster)
Whiteboard: [stockwell disable-recommended] → [stockwell needswork]
Whiteboard: [stockwell disable-recommended] → [stockwell needswork]

Brian, could you look into this?

Flags: needinfo?(jlaster) → needinfo?(bhackett1024)
Assignee: nobody → bhackett1024
Priority: P5 → P1

I can look at this, but I can't reproduce this locally so it will probably take some time to track down. The test should be disabled in the interim I think.

Flags: needinfo?(bhackett1024)
Attachment #9061825 - Attachment description: Bug 1456013 - devtools/client/debugger/test/mochitest/browser.ini on all platforms for frequent failures → Bug 1456013. Disabled test browser_dbg-worker-scopes.js on Linux.
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by apavel@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/c0343b881420 Disabled test browser_dbg-worker-scopes.js on Linux. r=jmaher
See Also: → 1548163

Brian, have you had a chance to look into the dbg-worker-scopes intermittent?

I've tried to reproduce this, but can't do so locally. Debugging a leak like this on try would be extremely difficult I think, though having an rr recording should make it much easier to track down. I don't consider this a high priority because I don't think it's a devtools bug, though. It seems more likely to be an existing JS/Gecko issue that was exposed by this test, and since the test is still running on most platforms it still provides coverage.

Assignee: bhackett1024 → nobody

Makes sense.

Priority: P1 → P3

Maybe this is related / dependent on bug 1527846.

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

Attachment

General

Created:
Updated:
Size: