Closed Bug 1719324 Opened 5 years ago Closed 3 years ago

Intermittent browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | application crashed [@ mozalloc_handle_oom]

Categories

(Firefox :: Settings UI, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=344471591&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XYB6wH01RDOqGw1CyDhz5g/runs/0/artifacts/public/logs/live_backing.log


[task 2021-07-06T13:03:13.166Z] 13:03:13     INFO - TEST-START | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js
[task 2021-07-06T13:03:14.463Z] 13:03:14     INFO - GECKO(4364) | out of memory: 0x00000000000493E0 bytes requested
[task 2021-07-06T13:03:14.796Z] 13:03:14     INFO - TEST-INFO | Main app process: exit 1
[task 2021-07-06T13:03:14.796Z] 13:03:14     INFO - Buffered messages logged at 13:03:13
[task 2021-07-06T13:03:14.797Z] 13:03:14     INFO - Entering test bound testDialogAccept
[task 2021-07-06T13:03:14.798Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | Check the proper URL is loaded - 
[task 2021-07-06T13:03:14.799Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | Element should not be null, when checking visibility - 
[task 2021-07-06T13:03:14.800Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | Overlay is visible - 
[task 2021-07-06T13:03:14.800Z] 13:03:14     INFO - found chrome://global/skin/in-content/common.css
[task 2021-07-06T13:03:14.801Z] 13:03:14     INFO - found chrome://browser/skin/preferences/dialog.css
[task 2021-07-06T13:03:14.802Z] 13:03:14     INFO - found chrome://browser/skin/preferences/preferences.css
[task 2021-07-06T13:03:14.803Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | All expectedStyleSheetURLs should have been found - 
[task 2021-07-06T13:03:14.804Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | Choose what to sync window opened - [object Window] == true - 
[task 2021-07-06T13:03:14.805Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.806Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | true is expected value - true == true - 
[task 2021-07-06T13:03:14.807Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | true is expected value - true == true - 
[task 2021-07-06T13:03:14.808Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.809Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.809Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.810Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.811Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | false is expected value - false == false - 
[task 2021-07-06T13:03:14.812Z] 13:03:14     INFO - TEST-PASS | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | Accept callback was called - true == true - 
[task 2021-07-06T13:03:14.813Z] 13:03:14     INFO - Leaving test bound testDialogAccept
[task 2021-07-06T13:03:14.814Z] 13:03:14     INFO - Entering test bound testDialogCancel
[task 2021-07-06T13:03:14.814Z] 13:03:14     INFO - Buffered messages finished
[task 2021-07-06T13:03:14.815Z] 13:03:14    ERROR - TEST-UNEXPECTED-FAIL | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | application terminated with exit code 1
[task 2021-07-06T13:03:14.816Z] 13:03:14     INFO - runtests.py | Application ran for: 0:03:08.737740
[task 2021-07-06T13:03:14.817Z] 13:03:14     INFO - zombiecheck | Reading PID log: C:\Users\task_1625575006\AppData\Local\Temp\tmpx_3y40tzpidlog
[task 2021-07-06T13:03:14.818Z] 13:03:14     INFO - ==> process 4512 launched child process 1344 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.0.916893102\1370372181" -childID 1 -isForBrowser -prefsHandle 2232 -prefMapHandle 2228 -prefsLen 1 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 2276 21393648 tab)
[task 2021-07-06T13:03:14.820Z] 13:03:14     INFO - ==> process 4512 launched child process 1132 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.1.129364786\531727047" -childID 2 -isForBrowser -prefsHandle 2548 -prefMapHandle 2544 -prefsLen 183 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 2560 2335df78 tab)
[task 2021-07-06T13:03:14.821Z] 13:03:14     INFO - ==> process 4512 launched child process 5500 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.2.787709565\378058831" -childID 3 -isForBrowser -prefsHandle 2696 -prefMapHandle 2688 -prefsLen 223 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 2708 2406b798 tab)
[task 2021-07-06T13:03:14.823Z] 13:03:14     INFO - ==> process 4512 launched child process 2248 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.3.307923828\1835263933" -parentBuildID 20210706105242 -prefsHandle 2956 -prefMapHandle 2952 -prefsLen 1146 -prefMapSize 250242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 2968 1f446ba8 rdd)
[task 2021-07-06T13:03:14.824Z] 13:03:14     INFO - ==> process 4512 launched child process 4308 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.5.1511844030\452660077" -childID 4 -isForBrowser -prefsHandle 3712 -prefMapHandle 3716 -prefsLen 8887 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 3732 2236f258 tab)
[task 2021-07-06T13:03:14.826Z] 13:03:14     INFO - ==> process 4512 launched child process 6128 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.6.452979889\707511600" -childID 5 -isForBrowser -prefsHandle 3736 -prefMapHandle 3724 -prefsLen 8887 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 3232 2406b258 tab)
[task 2021-07-06T13:03:14.827Z] 13:03:14     INFO - ==> process 4512 launched child process 6092 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.7.63823286\542744324" -childID 6 -isForBrowser -prefsHandle 3992 -prefMapHandle 3988 -prefsLen 9130 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 3984 247aea38 tab)
[task 2021-07-06T13:03:14.828Z] 13:03:14     INFO - ==> process 4512 launched child process 5632 ("Z:\task_1625575006\build\application\firefox\firefox.exe" -contentproc --channel="4512.8.335668829\1747152573" -childID 7 -isForBrowser -prefsHandle 4436 -prefMapHandle 4392 -prefsLen 9371 -prefMapSize 250242 -jsInit 940 285716 -parentBuildID 20210706105242 -appdir "Z:\task_1625575006\build\application\firefox\browser" - 4512 "\\.\pipe\gecko-crash-server-pipe.4512" 4412 1f472f78 tab)
[task 2021-07-06T13:03:14.829Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 1344
[task 2021-07-06T13:03:14.830Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 5632
[task 2021-07-06T13:03:14.831Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 2248
[task 2021-07-06T13:03:14.831Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 1132
[task 2021-07-06T13:03:14.832Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 6092
[task 2021-07-06T13:03:14.833Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 6128
[task 2021-07-06T13:03:14.834Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 4308
[task 2021-07-06T13:03:14.834Z] 13:03:14     INFO - zombiecheck | Checking for orphan process with PID: 5500
[task 2021-07-06T13:03:14.835Z] 13:03:14     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RA5y_ULOTRiAeDl6BLZ1sA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-07-06T13:03:19.606Z] 13:03:19     INFO - mozcrash Copy/paste: Z:/task_1625575006/fetches\minidump_stackwalk\minidump_stackwalk.exe C:\Users\task_1625575006\AppData\Local\Temp\tmphsk06wv7.mozrunner\minidumps\23931c34-9774-4be0-bd9e-568434e219d3.dmp C:\Users\task_1625575006\AppData\Local\Temp\tmps9j2843t
[task 2021-07-06T13:03:29.190Z] 13:03:29     INFO - mozcrash Saved minidump as Z:\task_1625575006\build\blobber_upload_dir\23931c34-9774-4be0-bd9e-568434e219d3.dmp
[task 2021-07-06T13:03:29.195Z] 13:03:29     INFO - mozcrash Saved app info as Z:\task_1625575006\build\blobber_upload_dir\23931c34-9774-4be0-bd9e-568434e219d3.extra
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - PROCESS-CRASH | browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | application crashed [@ mozalloc_handle_oom(unsigned int)]
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - Mozilla crash reason: MOZ_CRASH()
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - Crash dump filename: C:\Users\task_1625575006\AppData\Local\Temp\tmphsk06wv7.mozrunner\minidumps\23931c34-9774-4be0-bd9e-568434e219d3.dmp
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - Operating system: Windows NT
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO -                   6.1.7601 Service Pack 1
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - CPU: x86
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO -      GenuineIntel family 6 model 63 stepping 2
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO -      8 CPUs
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - 
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - GPU: UNKNOWN
[task 2021-07-06T13:03:29.503Z] 13:03:29     INFO - 
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO - Crash reason:  EXCEPTION_BREAKPOINT
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO - Crash address: 0x6a765930
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO - Process uptime: 188 seconds
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO - 
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO - Thread 0 (crashed)
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -  0  mozglue.dll!mozalloc_abort(char const* const) [mozalloc_abort.cpp:b36db75f34bfca3a08f77558447949a00baaa58a : 33 + 0x0]
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     eip = 0x6a765930   esp = 0x0039c624   ebp = 0x0039c630   ebx = 0x6a51f9b0
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     esi = 0x0039c63c   edi = 0x00000002   eax = 0x0000000a   ecx = 0x6a527af0
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     edx = 0x00000000   efl = 0x00000216
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     Found by: given as instruction pointer in context
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -  1  mozglue.dll!mozalloc_handle_oom(unsigned int) [mozalloc_oom.cpp:b36db75f34bfca3a08f77558447949a00baaa58a : 51 + 0x9]
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     eip = 0x6a7659a2   esp = 0x0039c638   ebp = 0x0039c670   ebx = 0x000493e0
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     esi = 0x56a23930   edi = 0x00000155
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     Found by: call frame info
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -  2  xul.dll + 0x3dd844b
[task 2021-07-06T13:03:29.504Z] 13:03:29     INFO -     eip = 0x5b3e844b   esp = 0x0039c678   ebp = 0x0039c67c
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     Found by: call frame info
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -  3  xul.dll + 0x48e8b9
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     eip = 0x57a9e8b9   esp = 0x0039c684   ebp = 0x0039c6c8
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     Found by: previous frame's frame pointer
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -  4  xul.dll + 0x1182c93
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     eip = 0x58792c93   esp = 0x0039c6d0   ebp = 0x0039c774
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     Found by: previous frame's frame pointer
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -  5  xul.dll + 0xae879e
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     eip = 0x580f879e   esp = 0x0039c77c   ebp = 0x0039c7a4
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     Found by: previous frame's frame pointer
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -  6  xul.dll + 0xa85eee
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     eip = 0x58095eee   esp = 0x0039c7ac   ebp = 0x0039cbe0
[task 2021-07-06T13:03:29.505Z] 13:03:29     INFO -     Found by: previous frame's frame pointer
...

I'll leave this here for a bit so it shows up in triage again in a while, but the stack here is useless and it's an OOM crash, so at the moment it's not actionable. If it doesn't recur within 2 weeks I suggest we close as incomplete.

See Also: → 1717917

Continuing policy established in comment 1.

Hm, more useless stacks (e.g. https://treeherder.mozilla.org/logviewer?job_id=344864266&repo=try&lineNumber=7521 ). I wonder if gsvelto knows how to get useful crash info from these crashing tests? Are these really OOM crashes? And what's the stack?

Flags: needinfo?(gsvelto)

Yes, this is an OOM crash where the xul.dll library was unloaded (hence the useless stack). Specifically the allocation that failed was ~300KiB in size and it failed because we most likely ran out of address space; there was only ~172MiB left of it and it was probably heavily fragmented at that point. Given this is a 32-bit build it's likely that the process was pushing the boundaries of what we can do in the 2GiB of available address space.

Flags: needinfo?(gsvelto)
Priority: -- → P3
Crash Signature: [@ mozalloc_handle_oom(unsigned int)] → [@ mozalloc_handle_oom]
Summary: Intermittent browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | application crashed [@ mozalloc_handle_oom(unsigned int)] → Intermittent browser/components/preferences/tests/browser_sync_chooseWhatToSync.js | application crashed [@ mozalloc_handle_oom]
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.