Closed Bug 1505408 Opened 7 years ago Closed 7 years ago

Intermittent GECKO(805) | FATAL ERROR: AsyncShutdown timeout in profile-change-teardown Conditions: [{"name":"Extension shutdown: webcompat@mozilla.org","state":{"state":"Startup: Run manifest"},"filename":"resource://gre/modules/addons/XPIProvider.jsm"

Categories

(WebExtensions :: Untriaged, defect, P5)

Product:
WebExtensions
Component:
Untriaged
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: ebalazs [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=210308317&repo=autoland https://queue.taskcluster.net/v1/task/fQYsJt4RTt-1whP28dlXEw/runs/0/artifacts/public/logs/live_backing.log TEST-START | Shutdown 04:28:00 INFO - Browser Chrome Test Summary 04:28:00 INFO - Passed: 33 04:28:00 INFO - Failed: 0 04:28:00 INFO - Todo: 0 04:28:00 INFO - Mode: e10s 04:28:00 INFO - *** End BrowserChrome Test Results *** 04:28:00 INFO - GECKO(805) | Completed ShutdownLeaks collections in process 825 04:28:10 INFO - GECKO(805) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Extension shutdown: webcompat@mozilla.org","state":{"state":"Startup: Run manifest"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: webcompat-reporter@mozilla.org","state":{"state":"Startup: Run manifest: asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: screenshots@mozilla.org","state":{"state":"Startup: Run manifest: asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: formautofill@mozilla.org","state":{"state":"Startup: Run manifest"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]}] Barrier: profile-change-teardown 04:28:14 INFO - GECKO(805) | JavaScript error: jar:file:///Users/cltbld/tasks/task_1541593527/build/application/Firefox%20Nightly.app/Contents/Resources/browser/omni.ja!/components/nsBrowserGlue.js, line 1389: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIAppShellService.hiddenDOMWindow] 04:28:23 INFO - GECKO(805) | JavaScript error: chrome://browser/content/browser-ctrlTab.js, line 557: TypeError: this.tabList[i].linkedBrowser.currentURI is undefined; can't access its "spec" property 04:29:01 INFO - GECKO(805) | FATAL ERROR: AsyncShutdown timeout in profile-change-teardown Conditions: [{"name":"Extension shutdown: webcompat@mozilla.org","state":{"state":"Startup: Run manifest"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: webcompat-reporter@mozilla.org","state":{"state":"Startup: Run manifest: asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: screenshots@mozilla.org","state":{"state":"Startup: Run manifest: asyncEmitManifestEntry(\"background\")"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]},{"name":"Extension shutdown: formautofill@mozilla.org","state":{"state":"Startup: Run manifest"},"filename":"resource://gre/modules/addons/XPIProvider.jsm","lineNumber":2211,"stack":["resource://gre/modules/addons/XPIProvider.jsm:startup/<:2211"]}] 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. 04:29:01 INFO - GECKO(805) | [Parent 805, Main Thread] ###!!! ABORT: file resource://gre/modules/addons/XPIProvider.jsm, line 2211 04:29:01 INFO - GECKO(805) | [Parent 805, Main Thread] ###!!! ABORT: file resource://gre/modules/addons/XPIProvider.jsm, line 2211 04:29:01 INFO - GECKO(805) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown 04:29:01 INFO - GECKO(805) | [Child 813, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 736 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:01 INFO - GECKO(805) | ** Unknown exception behavior: -2147483647 04:29:04 INFO - TEST-INFO | Main app process: exit 1 04:29:04 INFO - Buffered messages finished 04:29:04 ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 1 04:29:04 INFO - runtests.py | Application ran for: 0:01:11.886066 04:29:04 INFO - zombiecheck | Reading PID log: /var/folders/8f/0k9pb_kd6vl0grv981w9965w00000w/T/tmp7YdFcbpidlog 04:29:04 INFO - ==> process 805 launched child process 809 04:29:04 INFO - ==> process 805 launched child process 810 04:29:04 INFO - ==> process 805 launched child process 812 04:29:04 INFO - ==> process 805 launched child process 813 04:29:04 INFO - ==> process 805 launched child process 817 04:29:04 INFO - ==> process 805 launched child process 821 04:29:04 INFO - ==> process 805 launched child process 825 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 809 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 810 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 812 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 813 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 817 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 821 04:29:04 INFO - zombiecheck | Checking for orphan process with PID: 825 04:29:04 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/TgQYrPVZTfu8xs8nTWePzw/artifacts/public/build/target.crashreporter-symbols.zip 04:29:13 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1541593527/build/macosx64-minidump_stackwalk /var/folders/8f/0k9pb_kd6vl0grv981w9965w00000w/T/tmpYaCxaz.mozrunner/minidumps/7911EE63-339A-4B22-9EA5-7375E46C7A3D.dmp /var/folders/8f/0k9pb_kd6vl0grv981w9965w00000w/T/tmpWCWXwq 04:29:29 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1541593527/build/blobber_upload_dir/7911EE63-339A-4B22-9EA5-7375E46C7A3D.dmp 04:29:29 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1541593527/build/blobber_upload_dir/7911EE63-339A-4B22-9EA5-7375E46C7A3D.extra 04:29:29 INFO - PROCESS-CRASH | Last test finished | application crashed [@ libsystem_kernel.dylib + 0x114de] 04:29:29 INFO - Crash dump filename: /var/folders/8f/0k9pb_kd6vl0grv981w9965w00000w/T/tmpYaCxaz.mozrunner/minidumps/7911EE63-339A-4B22-9EA5-7375E46C7A3D.dmp 04:29:29 INFO - Operating system: Mac OS X 04:29:29 INFO - 10.10.5 14F27 04:29:29 INFO - CPU: amd64 04:29:29 INFO - family 6 model 69 stepping 1 04:29:29 INFO - 4 CPUs 04:29:29 INFO - 04:29:29 INFO - GPU: UNKNOWN 04:29:29 INFO - 04:29:29 INFO - Crash reason: EXC_SOFTWARE / SIGABRT 04:29:29 INFO - Crash address: 0x7fff924f64de 04:29:29 INFO - Process uptime: 2 seconds 04:29:29 INFO - 04:29:29 INFO - Thread 0 (crashed) 04:29:29 INFO - 0 libsystem_kernel.dylib + 0x114de 04:29:29 INFO - rax = 0x000000000100001f rdx = 0x0000000000000000 04:29:29 INFO - rcx = 0x00007fff52f92f08 rbx = 0x0000000000001388 04:29:29 INFO - rsi = 0x0000000000000102 rdi = 0x00007fff52f92f90 04:29:29 INFO - rbp = 0x00007fff52f92f50 rsp = 0x00007fff52f92f08 04:29:29 INFO - r8 = 0x0000000000004407 r9 = 0x0000000000001388 04:29:29 INFO - r10 = 0x000000000000041c r11 = 0x0000000000000202 04:29:29 INFO - r12 = 0x000000000000041c r13 = 0x0000000000000000 04:29:29 INFO - r14 = 0x00007fff52f92f90 r15 = 0x0000000000004407 04:29:29 INFO - rip = 0x00007fff924f64de 04:29:29 INFO - Found by: given as instruction pointer in context 04:29:29 INFO - 1 XUL!google_breakpad::CrashGenerationClient::RequestDumpForException(int, int, int, unsigned int) [MachIPC.mm:10a65f18e697a58685832661d670242408b2a11a : 249 + 0x25] 04:29:29 INFO - rbp = 0x00007fff52f93800 rsp = 0x00007fff52f92f60 04:29:29 INFO - rip = 0x0000000111eab23f 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 2 XUL!google_breakpad::ExceptionHandler::WriteMinidumpWithException(int, int, int, __darwin_ucontext*, unsigned int, bool, bool) [exception_handler.cc:10a65f18e697a58685832661d670242408b2a11a : 382 + 0x11] 04:29:29 INFO - rbp = 0x00007fff52f938f0 rsp = 0x00007fff52f93810 04:29:29 INFO - rip = 0x0000000111eace83 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 3 XUL!google_breakpad::ExceptionHandler::SignalHandler(int, __siginfo*, void*) [exception_handler.cc:10a65f18e697a58685832661d670242408b2a11a : 628 + 0x21] 04:29:29 INFO - rbp = 0x00007fff52f93920 rsp = 0x00007fff52f93900 04:29:29 INFO - rip = 0x0000000111ead4a7 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 4 libsystem_platform.dylib + 0x4f1a 04:29:29 INFO - rbp = 0x00007fff52f93930 rsp = 0x00007fff52f93930 04:29:29 INFO - rip = 0x00007fff96a26f1a 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 5 libsystem_asl.dylib + 0x15b42 04:29:29 INFO - rbp = 0x00007fff52f93ea0 rsp = 0x00007fff52f93940 04:29:29 INFO - rip = 0x00007fff8f145b42 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 6 libsystem_c.dylib + 0x5d9b3 04:29:29 INFO - rbp = 0x00007fff52f93ed0 rsp = 0x00007fff52f93eb0 04:29:29 INFO - rip = 0x00007fff965659b3 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 7 CarbonCore + 0xd368a 04:29:29 INFO - rbp = 0x00007fff52f93fb0 rsp = 0x00007fff52f93ee0 04:29:29 INFO - rip = 0x00007fff97de368a 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 8 CarbonCore + 0x4462 04:29:29 INFO - rbp = 0x00007fff52f93fe0 rsp = 0x00007fff52f93fc0 04:29:29 INFO - rip = 0x00007fff97d14462 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 9 CarbonCore + 0x2cb2 04:29:29 INFO - rbp = 0x00007fff52f94040 rsp = 0x00007fff52f93ff0 04:29:29 INFO - rip = 0x00007fff97d12cb2 04:29:29 INFO - Found by: previous frame's frame pointer 04:29:29 INFO - 10 CarbonCore + 0x2b67 04:29:29 INFO - rbp = 0x00007fff52f94070 rsp = 0x00007fff52f94050 04:29:29 INFO - rip = 0x00007fff97d12b67 04:29:29 INFO - Found by: previous frame's frame pointer
Component: Add-ons Manager → General
Product: Toolkit → Web Compatibility Tools
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=224434391&repo=mozilla-inbound

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

I'm gonna move this to WebExtensions::Untriaged speculatively.... all of the system addons are timing out here, the webcompat one just happens to be the first in the list.

Component: General → Untriaged
Product: Web Compatibility Tools → WebExtensions

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

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