Closed
Bug 1624876
Opened 5 years ago
Closed 5 years ago
Intermittent GECKO(1234) | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"RemoteSettingsClient - finish IDB access.","state":[{"method":"list","identifier":"main/public-suffix-list"}],"filename":"<...>Database.jsm","line
Categories
(Firefox :: Remote Settings Client, defect, P5)
Firefox
Remote Settings Client
Tracking
()
RESOLVED
DUPLICATE
of bug 1624983
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=294683433&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WecCpafBR2mEklBnwHVrFA/runs/0/artifacts/public/logs/live_backing.log
[task 2020-03-25T14:17:51.296Z] 14:17:51 INFO - TEST-START | dom/cache/test/mochitest/test_chrome_constructor.html
[task 2020-03-25T14:17:51.445Z] 14:17:51 INFO - GECKO(1234) | MEMORY STAT | vsize 20974532MB | residentFast 945MB
[task 2020-03-25T14:17:51.461Z] 14:17:51 INFO - TEST-OK | dom/cache/test/mochitest/test_chrome_constructor.html | took 160ms
[task 2020-03-25T14:17:51.506Z] 14:17:51 INFO - TEST-START | Shutdown
[task 2020-03-25T14:17:51.507Z] 14:17:51 INFO - Passed: 3333
[task 2020-03-25T14:17:51.509Z] 14:17:51 INFO - Failed: 0
[task 2020-03-25T14:17:51.510Z] 14:17:51 INFO - Todo: 0
[task 2020-03-25T14:17:51.511Z] 14:17:51 INFO - Mode: e10s
[task 2020-03-25T14:17:51.512Z] 14:17:51 INFO - Slowest: 8222ms - /tests/dom/cache/test/mochitest/test_cache_tons_of_fd.html
[task 2020-03-25T14:17:51.513Z] 14:17:51 INFO - SimpleTest FINISHED
[task 2020-03-25T14:17:51.515Z] 14:17:51 INFO - TEST-INFO | Ran 1 Loops
[task 2020-03-25T14:17:51.516Z] 14:17:51 INFO - SimpleTest FINISHED
[task 2020-03-25T14:17:52.586Z] 14:17:52 INFO - GECKO(1234) | JavaScript error: resource://gre/modules/IndexedDB.jsm, line 370: InvalidStateError: IDBDatabase.transaction: Can't start a transaction on a closed database
[task 2020-03-25T14:17:52.587Z] 14:17:52 INFO - GECKO(1234) | JavaScript error: resource://gre/modules/IndexedDB.jsm, line 370: InvalidStateError: IDBDatabase.transaction: Can't start a transaction on a closed database
[task 2020-03-25T14:17:53.469Z] 14:17:53 INFO - GECKO(1234) | -----------------------------------------------------
[task 2020-03-25T14:17:53.469Z] 14:17:53 INFO - GECKO(1234) | Suppressions used:
[task 2020-03-25T14:17:53.470Z] 14:17:53 INFO - GECKO(1234) | count bytes template
[task 2020-03-25T14:17:53.471Z] 14:17:53 INFO - GECKO(1234) | 27 832 nsComponentManagerImpl
[task 2020-03-25T14:17:53.472Z] 14:17:53 INFO - GECKO(1234) | 633 18287 libfontconfig.so
[task 2020-03-25T14:17:53.473Z] 14:17:53 INFO - GECKO(1234) | 1 11 libglib-2.0.so
[task 2020-03-25T14:17:53.473Z] 14:17:53 INFO - GECKO(1234) | -----------------------------------------------------
[task 2020-03-25T14:17:53.748Z] 14:17:53 INFO - GECKO(1234) | -----------------------------------------------------
[task 2020-03-25T14:17:53.750Z] 14:17:53 INFO - GECKO(1234) | Suppressions used:
[task 2020-03-25T14:17:53.750Z] 14:17:53 INFO - GECKO(1234) | count bytes template
[task 2020-03-25T14:17:53.751Z] 14:17:53 INFO - GECKO(1234) | 27 832 nsComponentManagerImpl
[task 2020-03-25T14:17:53.752Z] 14:17:53 INFO - GECKO(1234) | 633 18287 libfontconfig.so
[task 2020-03-25T14:17:53.753Z] 14:17:53 INFO - GECKO(1234) | 1 11 libglib-2.0.so
[task 2020-03-25T14:17:53.753Z] 14:17:53 INFO - GECKO(1234) | -----------------------------------------------------
[task 2020-03-25T14:18:02.804Z] 14:18:02 INFO - GECKO(1234) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"RemoteSettingsClient - finish IDB access.","state":[{"method":"list","identifier":"main/public-suffix-list"}],"filename":"resource://services-settings/Database.jsm","lineNumber":509,"stack":["resource://services-settings/Database.jsm:ensureShutdownBlocker:509","resource://services-settings/Database.jsm:Database:442","resource://services-settings/RemoteSettingsClient.jsm:RemoteSettingsClient/<:239","resource://gre/modules/XPCOMUtils.jsm:get:129","resource://services-settings/Utils.jsm:hasLocalData:98","resource://services-settings/RemoteSettingsClient.jsm:get:309","resource://gre/modules/Blocklist.jsm:_updateEntries:1078","resource://gre/modules/Blocklist.jsm:_ensureEntries:1069","resource://gre/modules/Blocklist.jsm:getEntry:1229","resource://gre/modules/Blocklist.jsm:getAddonBlocklistEntry:1460","resource://gre/modules/addons/XPIDatabase.jsm:findBlocklistEntry:566","resource://gre/modules/addons/XPIDatabase.jsm:updateBlocklistState:574","resource://gre/modules/addons/XPIInstall.jsm:loadManifest:709"]}] Barrier: profile-before-change
[task 2020-03-25T14:18:07.583Z] 14:18:07 INFO - GECKO(1234) | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 433))
[task 2020-03-25T14:20:53.801Z] 14:20:53 INFO - GECKO(1234) | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"RemoteSettingsClient - finish IDB access.","state":[{"method":"list","identifier":"main/public-suffix-list"}],"filename":"resource://services-settings/Database.jsm","lineNumber":509,"stack":["resource://services-settings/Database.jsm:ensureShutdownBlocker:509","resource://services-settings/Database.jsm:Database:442","resource://services-settings/RemoteSettingsClient.jsm:RemoteSettingsClient/<:239","resource://gre/modules/XPCOMUtils.jsm:get:129","resource://services-settings/Utils.jsm:hasLocalData:98","resource://services-settings/RemoteSettingsClient.jsm:get:309","resource://gre/modules/Blocklist.jsm:_updateEntries:1078","resource://gre/modules/Blocklist.jsm:_ensureEntries:1069","resource://gre/modules/Blocklist.jsm:getEntry:1229","resource://gre/modules/Blocklist.jsm:getAddonBlocklistEntry:1460","resource://gre/modules/addons/XPIDatabase.jsm:findBlocklistEntry:566","resource://gre/modules/addons/XPIDatabase.jsm:updateBlocklistState:574","resource://gre/modules/addons/XPIInstall.jsm:loadManifest:709"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2020-03-25T14:20:53.802Z] 14:20:53 INFO - GECKO(1234) | WARNING: No crash reporter available
[task 2020-03-25T14:20:53.803Z] 14:20:53 INFO - GECKO(1234) | [Parent 1234, Main Thread] ###!!! ABORT: file resource://services-settings/Database.jsm, line 509
[task 2020-03-25T14:20:53.803Z] 14:20:53 INFO - GECKO(1234) | [Parent 1234, Main Thread] ###!!! ABORT: file resource://services-settings/Database.jsm, line 509
[task 2020-03-25T14:20:53.804Z] 14:20:53 INFO - GECKO(1234) | AddressSanitizer:DEADLYSIGNAL
[task 2020-03-25T14:20:53.805Z] 14:20:53 INFO - GECKO(1234) | =================================================================
[task 2020-03-25T14:20:53.806Z] 14:20:53 ERROR - GECKO(1234) | ==1234==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000001 (pc 0x55b989d1c1cc bp 0x7ffc141a4b70 sp 0x7ffc141a4b60 T0)
[task 2020-03-25T14:20:53.806Z] 14:20:53 INFO - GECKO(1234) | ==1234==The signal is caused by a WRITE memory access.
[task 2020-03-25T14:20:53.807Z] 14:20:53 INFO - GECKO(1234) | ==1234==Hint: address points to the zero page.
[task 2020-03-25T14:20:53.851Z] 14:20:53 INFO - GECKO(1234) | #0 0x55b989d1c1cb in mozalloc_abort /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc_abort.cpp:33:3
[task 2020-03-25T14:20:54.170Z] 14:20:54 INFO - GECKO(1234) | #1 0x7f8ca29578de in NS_DebugBreak /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp
[task 2020-03-25T14:20:54.170Z] 14:20:54 INFO - GECKO(1234) | #2 0x7f8ca29579b7 in nsDebugImpl::Abort(char const*, int) /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:134:3
[task 2020-03-25T14:20:54.171Z] 14:20:54 INFO - GECKO(1234) | #3 0x7f8ca2bbba91 in NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:106
[task 2020-03-25T14:20:54.177Z] 14:20:54 INFO - GECKO(1234) | #4 0x7f8ca455497b in Invoke /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1634:10
[task 2020-03-25T14:20:54.178Z] 14:20:54 INFO - GECKO(1234) | #5 0x7f8ca455497b in Call /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1175:19
[task 2020-03-25T14:20:54.178Z] 14:20:54 INFO - GECKO(1234) | #6 0x7f8ca455497b in XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1141:23
[task 2020-03-25T14:20:54.178Z] 14:20:54 INFO - GECKO(1234) | #7 0x7f8ca4559982 in XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:947:10
[task 2020-03-25T14:20:54.194Z] 14:20:54 INFO - GECKO(1234) | #8 0x7f8cad5901c7 in CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:476:13
[task 2020-03-25T14:20:54.194Z] 14:20:54 INFO - GECKO(1234) | #9 0x7f8cad5901c7 in js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:568:12
[task 2020-03-25T14:20:54.194Z] 14:20:54 INFO - GECKO(1234) | #10 0x7f8cad591e7a in InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:631:10
[task 2020-03-25T14:20:54.195Z] 14:20:54 INFO - GECKO(1234) | #11 0x7f8cad57882f in CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:635:10
[task 2020-03-25T14:20:54.198Z] 14:20:54 INFO - GECKO(1234) | #12 0x7f8cad57882f in Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3026:16
[task 2020-03-25T14:20:54.198Z] 14:20:54 INFO - GECKO(1234) | #13 0x7f8cad55e7d3 in js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:448:10
[task 2020-03-25T14:20:54.198Z] 14:20:54 INFO - GECKO(1234) | #14 0x7f8cad5902aa in js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:603:13
[task 2020-03-25T14:20:54.199Z] 14:20:54 INFO - GECKO(1234) | #15 0x7f8cad591e7a in InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:631:10
[task 2020-03-25T14:20:54.199Z] 14:20:54 INFO - GECKO(1234) | #16 0x7f8cad5920f9 in js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:8
[task 2020-03-25T14:20:54.215Z] 14:20:54 INFO - GECKO(1234) | #17 0x7f8cad93a4e2 in Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.h:103:10
[task 2020-03-25T14:20:54.215Z] 14:20:54 INFO - GECKO(1234) | #18 0x7f8cad93a4e2 in PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:1880:10
[task 2020-03-25T14:20:54.215Z] 14:20:54 INFO - GECKO(1234) | #19 0x7f8cad5901c7 in CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:476:13
[task 2020-03-25T14:20:54.216Z] 14:20:54 INFO - GECKO(1234) | #20 0x7f8cad5901c7 in js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:568:12
[task 2020-03-25T14:20:54.217Z] 14:20:54 INFO - GECKO(1234) | #21 0x7f8cad591e7a in InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:631:10
[task 2020-03-25T14:20:54.217Z] 14:20:54 INFO - GECKO(1234) | #22 0x7f8cad5920f9 in js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:648:8
[task 2020-03-25T14:20:54.233Z] 14:20:54 INFO - GECKO(1234) | #23 0x7f8cad71a6c2 in JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2798:10
[task 2020-03-25T14:20:54.269Z] 14:20:54 INFO - GECKO(1234) | #24 0x7f8ca66dfc5b in mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:28:8
[task 2020-03-25T14:20:54.270Z] 14:20:54 INFO - GECKO(1234) | #25 0x7f8ca298ff9d in Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:91:12
[task 2020-03-25T14:20:54.270Z] 14:20:54 INFO - GECKO(1234) | #26 0x7f8ca298ff9d in Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:104:12
[task 2020-03-25T14:20:54.270Z] 14:20:54 INFO - GECKO(1234) | #27 0x7f8ca298ff9d in mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:214:18
[task 2020-03-25T14:20:54.270Z] 14:20:54 INFO - GECKO(1234) | #28 0x7f8ca29705b1 in mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:645:17
[task 2020-03-25T14:20:54.270Z] 14:20:54 INFO - GECKO(1234) | #29 0x7f8ca2971a6f in mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:464:3
[task 2020-03-25T14:20:54.285Z] 14:20:54 INFO - GECKO(1234) | #30 0x7f8ca44db05d in XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1331:28
[task 2020-03-25T14:20:54.293Z] 14:20:54 INFO - GECKO(1234) | #31 0x7f8ca2b79557 in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1245:24
[task 2020-03-25T14:20:54.294Z] 14:20:54 INFO - GECKO(1234) | #32 0x7f8ca2bbba91 in NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:106
[task 2020-03-25T14:20:54.294Z] 14:20:54 INFO - GECKO(1234) | #33 0x7f8ca455497b in Invoke /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1634:10
[task 2020-03-25T14:20:54.295Z] 14:20:54 INFO - GECKO(1234) | #34 0x7f8ca455497b in Call /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1175:19
[task 2020-03-25T14:20:54.296Z] 14:20:54 INFO - GECKO(1234) | #35 0x7f8ca455497b in XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1141:23
[task 2020-03-25T14:20:54.297Z] 14:20:54 INFO - GECKO(1234) | #36 0x7f8ca4559982 in XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:947:10
[task 2020-03-25T14:20:54.313Z] 14:20:54 INFO - GECKO(1234) | #37 0x26035b30074f (<unknown module>)
[task 2020-03-25T14:20:54.313Z] 14:20:54 INFO - GECKO(1234) | AddressSanitizer can not provide additional info.
[task 2020-03-25T14:20:54.313Z] 14:20:54 INFO - GECKO(1234) | SUMMARY: AddressSanitizer: SEGV /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc_abort.cpp:33:3 in mozalloc_abort
[task 2020-03-25T14:20:54.313Z] 14:20:54 INFO - GECKO(1234) | ==1234==ABORTING
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•5 years ago
|
||
Mathieu, could you please take a look over these failures?
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=295344891&repo=try
Thank you.
Flags: needinfo?(mpillard)
|
||
Comment 3•5 years ago
|
||
Did you mean to ni another Mathieu ? I'm guessing this is more something for :leplatrem.
Flags: needinfo?(mpillard)
|
|
||
Updated•5 years ago
|
Flags: needinfo?(mathieu)
|
||
Comment 4•5 years ago
|
||
It will be fixed by https://phabricator.services.mozilla.com/D68465
Flags: needinfo?(mathieu)
|
|
||
Updated•5 years ago
|
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → DUPLICATE
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•