Closed Bug 1573730 Opened 6 years ago Closed 6 years ago

Intermittent TVg FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"Places Connection shutdown","state":{"progress":{}},"filename":"/builds/worker/workspace/build/src/toolkit/components/places/Database.cpp","lineNumber"

Categories

(Core :: Storage: IndexedDB, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=261471613&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/be-vZ8DgRDywD490C_gl0A/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://queue.taskcluster.net/v1/task/be-vZ8DgRDywD490C_gl0A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2019-08-14T00:48:32.927Z] 00:48:32     INFO - JavaScript error: resource://services-settings/RemoteSettingsClient.jsm, line 149: Error: Unknown callback
[task 2019-08-14T00:48:44.296Z] 00:48:44     INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Places Connection shutdown","state":{"progress":{}},"filename":"/builds/worker/workspace/build/src/toolkit/components/places/Database.cpp","lineNumber":496,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162","resource://gre/modules/PlacesUtils.jsm:null:1906","resource://gre/modules/XPCOMUtils.jsm:get:129","resource://gre/modules/PlacesUtils.jsm:getFolderContents:1292","resource:///modules/BrowserGlue.jsm:getToolbarFolderCount:2661","resource:///modules/BrowserGlue.jsm:_maybeToggleBookmarkToolbarVisibility:2671","resource:///modules/BrowserGlue.jsm:BG__migrateUI:2712","resource:///modules/BrowserGlue.jsm:BG__beforeUIStartup:1223","resource:///modules/BrowserGlue.jsm:BG_observe:877"]}] Barrier: profile-before-change
[task 2019-08-14T00:49:46.318Z] 00:49:46     INFO - FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"Places Connection shutdown","state":{"progress":{}},"filename":"/builds/worker/workspace/build/src/toolkit/components/places/Database.cpp","lineNumber":496,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162","resource://gre/modules/PlacesUtils.jsm:null:1906","resource://gre/modules/XPCOMUtils.jsm:get:129","resource://gre/modules/PlacesUtils.jsm:getFolderContents:1292","resource:///modules/BrowserGlue.jsm:getToolbarFolderCount:2661","resource:///modules/BrowserGlue.jsm:_maybeToggleBookmarkToolbarVisibility:2671","resource:///modules/BrowserGlue.jsm:BG__migrateUI:2712","resource:///modules/BrowserGlue.jsm:BG__beforeUIStartup:1223","resource:///modules/BrowserGlue.jsm:BG_observe:877"]}] 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 2019-08-14T00:49:46.319Z] 00:49:46     INFO - [Parent 2440, Main Thread] ###!!! ABORT: file /builds/worker/workspace/build/src/toolkit/components/places/Database.cpp, line 496
[task 2019-08-14T00:49:46.319Z] 00:49:46     INFO - [Parent 2440, Main Thread] ###!!! ABORT: file /builds/worker/workspace/build/src/toolkit/components/places/Database.cpp, line 496
[task 2019-08-14T00:49:46.319Z] 00:49:46     INFO - ExceptionHandler::GenerateDump cloned child 2681
[task 2019-08-14T00:49:46.320Z] 00:49:46     INFO - ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2019-08-14T00:49:46.320Z] 00:49:46     INFO - ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2019-08-14T00:49:48.187Z] 00:49:48    ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 11
[task 2019-08-14T00:49:48.187Z] 00:49:48     INFO - REFTEST INFO | Downloading symbols from: https://queue.taskcluster.net/v1/task/GtcMZySmTsyds7JCLRSbhA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-08-14T00:52:22.627Z] 00:52:22     INFO - REFTEST INFO | Copy/paste: /builds/worker/workspace/build/linux64-minidump_stackwalk /tmp/tmpfM2VJR.mozrunner/minidumps/673b28aa-2dff-d528-a4b3-6f04a1c22a6e.dmp /tmp/tmplaVL6o
[task 2019-08-14T00:52:32.361Z] 00:52:32     INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/673b28aa-2dff-d528-a4b3-6f04a1c22a6e.dmp
[task 2019-08-14T00:52:32.362Z] 00:52:32     INFO - REFTEST INFO | Saved app info as /builds/worker/workspace/build/blobber_upload_dir/673b28aa-2dff-d528-a4b3-6f04a1c22a6e.extra
[task 2019-08-14T00:52:32.448Z] 00:52:32     INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ mozilla::(anonymous namespace)::RunWatchdog(void*)]
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - Crash dump filename: /tmp/tmpfM2VJR.mozrunner/minidumps/673b28aa-2dff-d528-a4b3-6f04a1c22a6e.dmp
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - Operating system: Linux
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO -                   0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - CPU: amd64
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO -      family 6 model 85 stepping 4
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO -      1 CPU
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - 
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - GPU: UNKNOWN
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - 
[task 2019-08-14T00:52:32.449Z] 00:52:32     INFO - Crash reason:  SIGSEGV /SEGV_MAPERR
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO - Crash address: 0x0
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO - Process uptime: not available
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO - 
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO - Thread 49 (crashed)
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -  0  libxul.so!mozilla::(anonymous namespace)::RunWatchdog(void*) [nsTerminator.cpp:eff8c62bdeb71f78dbf0e2324b31d094355cf3dc : 200 + 0x0]
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     rax = 0x00007fa6f6f73d0a   rdx = 0x0000000000000000
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     rcx = 0x00005579d0492c40   rbx = 0x000000000000003f
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     rsi = 0x0000000000000000   rdi = 0x0000000000000000
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     rbp = 0x00007fa6dc6d4ec0   rsp = 0x00007fa6dc6d4ea0
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -      r8 = 0x0000000000000000    r9 = 0x0000000020000000
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     r10 = 0x000000000000060c   r11 = 0x0000000000000000
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     r12 = 0x00007fa702a639d0   r13 = 0x00007fa6d5e24a60
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     r14 = 0x00007fa6d71b2548   r15 = 0x0000000000000a75
[task 2019-08-14T00:52:32.450Z] 00:52:32     INFO -     rip = 0x00007fa6f45fe6bf
[task 2019-08-14T00:52:32.451Z] 00:52:32     INFO -     Found by: given as instruction pointer in context
[task 2019-08-14T00:52:32.451Z] 00:52:32     INFO -  1  libnspr4.so!_pt_root [ptthread.c:eff8c62bdeb71f78dbf0e2324b31d094355cf3dc : 198 + 0x8]
[task 2019-08-14T00:52:32.451Z] 00:52:32     INFO -     rbx = 0x0000000000000007   rbp = 0x00007fa6dc6d4f10
[task 2019-08-14T00:52:32.451Z] 00:52:32     INFO -     rsp = 0x00007fa6dc6d4ed0   r12 = 0x00007fa702a639d0
[task 2019-08-14T00:52:32.457Z] 00:52:32     INFO -     r13 = 0x00007fa6d5e24a60   r14 = 0x00007fa6dc6d5700
[task 2019-08-14T00:52:32.457Z] 00:52:32     INFO -     r15 = 0x0000000000000a75   rip = 0x00007fa7041197ee
[task 2019-08-14T00:52:32.457Z] 00:52:32     INFO -     Found by: call frame info
[task 2019-08-14T00:52:32.457Z] 00:52:32     INFO -  2  libpthread-2.23.so!start_thread [pthread_create.c : 333 + 0x11]
[task 2019-08-14T00:52:32.457Z] 00:52:32     INFO -     rbx = 0x0000000000000000   rbp = 0x0000000000000000
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     rsp = 0x00007fa6dc6d4f20   r12 = 0x0000000000000000
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     r13 = 0x00007ffe06ced0ef   r14 = 0x0000000000800000
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     r15 = 0x00007fa702a6bf60   rip = 0x00007fa703d656ba
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     Found by: call frame info
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -  3  libc-2.23.so!__clone + 0x6d
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     rsp = 0x00007fa6dc6d4fc0   rip = 0x00007fa702dee41d
[task 2019-08-14T00:52:32.458Z] 00:52:32     INFO -     Found by: stack scanning
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.