Closed Bug 1650075 Opened 4 years ago Closed 2 years ago

Intermittent services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC()]

Categories

(Core :: XPConnect, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE
Tracking Status
firefox-esr68 --- unaffected
firefox-esr78 --- unaffected
firefox78 --- unaffected
firefox79 --- unaffected
firefox80 --- affected

People

(Reporter: intermittent-bug-filer, Assigned: allstars.chh)

References

(Regression)

Details

(5 keywords)

Crash Data

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


[task 2020-07-02T12:21:50.768Z] 12:21:50 INFO - TEST-PASS | services/sync/tests/unit/test_addons_store.js | test_incoming_reconciled_but_not_cached - [test_incoming_reconciled_but_not_cached : 720] {} != null
[task 2020-07-02T12:21:50.769Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510212 addons.xpi-utils DEBUG Make addon app-profile:addon1@tests.mozilla.org visible"
[task 2020-07-02T12:21:50.769Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510212 addons.xpi DEBUG XPIStates adding add-on addon1@tests.mozilla.org in {"addons":{},"staged":{},"path":"c:\\users\\task_1593691516\\appdata\\local\\temp\\xpc-profile-xveabk\\extensions"}: c:\users\task_1593691516\appdata\local\temp\xpc-profile-xveabk\extensions\addon1@tests.mozilla.org.xpi"
[task 2020-07-02T12:21:50.769Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510212 addons.xpi DEBUG Updating XPIState for {"id":"addon1@tests.mozilla.org","syncGUID":"1eyN52UbCpoT","version":"1.0","type":"extension","loader":null,"updateURL":"http://example.com/data/test_install.json","optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"embedderDisabled":false,"installDate":1593692510204,"updateDate":1593692510204,"applyBackgroundUpdates":1,"path":"c:\\users\\task_1593691516\\appdata\\local\\temp\\xpc-profile-xveabk\\extensions\\addon1@tests.mozilla.org.xpi","skinnable":false,"sourceURI":"http://localhost:8888/addon1.xpi","releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":4,"signedDate":null,"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"optionalPermissions":{"permissions":[],"origins":[]},"icons":{},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":{"source":"sync"},"recommendationState":null,"rootURI":"jar:file:///c:/users/task_1593691516/appdata/local/temp/xpc-profile-xveabk/extensions/addon1@tests.mozilla.org.xpi!/","location":"app-profile"}"
[task 2020-07-02T12:21:50.770Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510213 addons.xpi DEBUG Install of http://localhost:8888/addon1.xpi completed."
[task 2020-07-02T12:21:50.770Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510213 addons.xpi DEBUG Loading bootstrap scope from jar:file:///c:/users/task_1593691516/appdata/local/temp/xpc-profile-xveabk/extensions/addon1@tests.mozilla.org.xpi!/"
[task 2020-07-02T12:21:50.771Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510213 addons.xpi DEBUG Calling bootstrap method install on addon1@tests.mozilla.org version 1.0"
[task 2020-07-02T12:21:50.771Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510213 addons.xpi DEBUG Calling bootstrap method startup on addon1@tests.mozilla.org version 1.0"
[task 2020-07-02T12:21:50.771Z] 12:21:50 INFO - (xpcshell/head.js) | test run_next_test 20 pending (2)
[task 2020-07-02T12:21:50.772Z] 12:21:50 INFO - (xpcshell/head.js) | test test_incoming_reconciled_but_not_cached finished (2)
[task 2020-07-02T12:21:50.772Z] 12:21:50 INFO - services/sync/tests/unit/test_addons_store.js | Starting cleanup
[task 2020-07-02T12:21:50.772Z] 12:21:50 INFO - (xpcshell/head.js) | test cleanup pending (2)
[task 2020-07-02T12:21:50.773Z] 12:21:50 INFO - PID 9264 | 1593692510216 Sync.AddonsReconciler DEBUG Stopping listening and removing AddonManager listener.
[task 2020-07-02T12:21:50.773Z] 12:21:50 INFO - (xpcshell/head.js) | test run_next_test 20 finished (2)
[task 2020-07-02T12:21:50.773Z] 12:21:50 INFO - (xpcshell/head.js) | test run_next_test 21 pending (2)
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - (xpcshell/head.js) | test cleanup finished (2)
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - (xpcshell/head.js) | test run_next_test 21 finished (1)
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - exiting test
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - Error: Found unexpected files in temporary directory: tmp-3pv.xpi at resource://testing-common/AddonTestUtils.jsm:450
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - init/<@resource://testing-common/AddonTestUtils.jsm:450:15
[task 2020-07-02T12:21:50.774Z] 12:21:50 INFO - _execute_test/<@Z:\task_1593691516\build\tests\xpcshell\head.js:641:28
[task 2020-07-02T12:21:50.775Z] 12:21:50 INFO - _execute_test@Z:\task_1593691516\build\tests\xpcshell\head.js:653:16
[task 2020-07-02T12:21:50.775Z] 12:21:50 INFO - @-e:1:1
[task 2020-07-02T12:21:50.775Z] 12:21:50 INFO - PID 9264 | 1593692510222 addons.xpi DEBUG removeTemporaryFile: http://localhost:8888/addon1.xpi removing temp file c:\users\task_1593691516\appdata\local\temp\xpc-profile-xveabk\temp\tmp-3pv.xpi
[task 2020-07-02T12:21:50.776Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) 1593692510222 addons.xpi DEBUG removeTemporaryFile: http://localhost:8888/addon1.xpi removing temp file c:\users\task_1593691516\appdata\local\temp\xpc-profile-xveabk\temp\tmp-3pv.xpi"
[task 2020-07-02T12:21:50.776Z] 12:21:50 INFO - PID 9264 | 1593692510223 Sync.SyncScheduler TRACE Handling network:offline-status-changed
[task 2020-07-02T12:21:50.776Z] 12:21:50 INFO - PID 9264 | 1593692510223 Sync.SyncScheduler TRACE Network offline status change: offline
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510223 Sync.SyncScheduler TRACE _checkSync returned "Sync is not configured".
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510223 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510225 Sync.Telemetry TRACE observed profile-before-change null
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510231 Toolkit.Telemetry TRACE TelemetryEnvironment::constructor
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510241 Toolkit.Telemetry TRACE TelemetryEnvironment::_getGFXData - Only one display adapter detected.
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510243 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - ignoring early call
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510243 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510244 Sync.Telemetry TRACE Not sending telemetry ping for self-hosted Sync user
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510248 Sync.Engine.Prefs.Tracker TRACE stop().
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510249 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: auto-update-config-change, aData: true
[task 2020-07-02T12:21:50.777Z] 12:21:50 INFO - PID 9264 | 1593692510250 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons: addons differ
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - PID 9264 | 1593692510251 Toolkit.ProfileAge DEBUG Iterating over profile c:\users\task_1593691516\appdata\local\temp\xpc-profile-xveabk
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (info) UpdateUtils.getAppUpdateAutoEnabled - Unable to read app update configuration file. Exception: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 62" data: no]"
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "UpdateUtils.getAppUpdateAutoEnabled - Migration failed. Exception: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 62" data: no]" {file: "resource://gre/modules/UpdateUtils.jsm" line: 237}]
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - getAppUpdateAutoEnabled/readPromise<@resource://gre/modules/UpdateUtils.jsm:237:18
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - observe@resource://gre/modules/AsyncShutdown.jsm:554:16
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - _execute_test@Z:\task_1593691516\build\tests\xpcshell\head.js:673:19
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - @-e:1:1
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - "
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - PID 9264 | 1593692510332 Toolkit.Telemetry ERROR TelemetryEnvironment::EnvironmentCache - error while initializing: Error: Unable to fetch oldest profile entry: Error: OS.File has been shut down. Rejecting request to DirectoryIterator_prototype_next(resource://gre/modules/ProfileAge.jsm:86:11) JS Stack trace: getOldestProfileTimestamp@ProfileAge.jsm:86:11
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - PID 9264 | observe@AsyncShutdown.jsm:554:16
[task 2020-07-02T12:21:50.778Z] 12:21:50 INFO - PID 9264 | 1593692510332 Toolkit.Telemetry TRACE TelemetryEnvironment::startWatchingPrefs - [object Map]
[task 2020-07-02T12:21:50.779Z] 12:21:50 INFO - PID 9264 | JavaScript error: resource://gre/modules/osfile/osfile_async_front.jsm, line 426: Error: OS.File has been shut down. Rejecting post to DirectoryIterator_prototype_close
[task 2020-07-02T12:21:50.779Z] 12:21:50 INFO - <<<<<<<
[task 2020-07-02T12:21:50.779Z] 12:21:50 INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GCVVih2-TbCGHwoQcAQz4g/artifacts/public/build/target.crashreporter-symbols.zip
[task 2020-07-02T12:21:56.875Z] 12:21:56 INFO - mozcrash Copy/paste: Z:/task_1593691516/fetches\minidump_stackwalk\minidump_stackwalk.exe c:\users\task_1593691516\appdata\local\temp\xpc-other-1nwzcs\0b4f5be7-f74b-4925-a66d-469e8491971c.dmp c:\users\task_1593691516\appdata\local\temp\tmpm7iyd

[task 2020-07-02T12:22:09.194Z] 12:22:09 INFO - mozcrash Saved minidump as Z:\task_1593691516\build\blobber_upload_dir\0b4f5be7-f74b-4925-a66d-469e8491971c.dmp
[task 2020-07-02T12:22:09.197Z] 12:22:09 INFO - mozcrash Saved app info as Z:\task_1593691516\build\blobber_upload_dir\0b4f5be7-f74b-4925-a66d-469e8491971c.extra
[task 2020-07-02T12:22:09.198Z] 12:22:09 WARNING - PROCESS-CRASH | services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC()]
[task 2020-07-02T12:22:09.199Z] 12:22:09 INFO - Crash dump filename: c:\users\task_1593691516\appdata\local\temp\xpc-other-1nwzcs\0b4f5be7-f74b-4925-a66d-469e8491971c.dmp
[task 2020-07-02T12:22:09.199Z] 12:22:09 INFO - Operating system: Windows NT
[task 2020-07-02T12:22:09.199Z] 12:22:09 INFO - 6.1.7601 Service Pack 1
[task 2020-07-02T12:22:09.200Z] 12:22:09 INFO - CPU: x86
[task 2020-07-02T12:22:09.200Z] 12:22:09 INFO - GenuineIntel family 6 model 63 stepping 2
[task 2020-07-02T12:22:09.201Z] 12:22:09 INFO - 8 CPUs
[task 2020-07-02T12:22:09.201Z] 12:22:09 INFO - GPU: UNKNOWN
[task 2020-07-02T12:22:09.201Z] 12:22:09 INFO - Crash reason: EXCEPTION_ACCESS_VIOLATION_READ
[task 2020-07-02T12:22:09.202Z] 12:22:09 INFO - Crash address: 0xe5e5e605
[task 2020-07-02T12:22:09.202Z] 12:22:09 INFO - Process uptime: 7 seconds
[task 2020-07-02T12:22:09.203Z] 12:22:09 INFO - Thread 0 (crashed)
[task 2020-07-02T12:22:09.203Z] 12:22:09 INFO - 0 xul.dll!JSObject2WrappedJSMap::UpdateWeakPointersAfterGC() [XPCMaps.cpp:bf1694d363d929118ae66a03341a444323aea63d : 52 + 0x0]
[task 2020-07-02T12:22:09.203Z] 12:22:09 INFO - eip = 0x593f7367 esp = 0x002aee5c ebp = 0x002aee8c ebx = 0x00000000
[task 2020-07-02T12:22:09.204Z] 12:22:09 INFO - esi = 0x002aee5c edi = 0xe5e5e5e5 eax = 0xe5e5e5e4 ecx = 0x00897200
[task 2020-07-02T12:22:09.204Z] 12:22:09 INFO - edx = 0x008970d0 efl = 0x00010282
[task 2020-07-02T12:22:09.204Z] 12:22:09 INFO - Found by: given as instruction pointer in context
[task 2020-07-02T12:22:09.205Z] 12:22:09 INFO - 1 xul.dll!xpc::CompartmentPrivate::UpdateWeakPointersAfterGC() [XPCJSRuntime.cpp:bf1694d363d929118ae66a03341a444323aea63d : 925 + 0x5]
[task 2020-07-02T12:22:09.206Z] 12:22:09 INFO - eip = 0x593f7653 esp = 0x002aee94 ebp = 0x002aeebc ebx = 0x002aee94
[task 2020-07-02T12:22:09.206Z] 12:22:09 INFO - esi = 0x0b9d70c0 edi = 0x0b9d70d8
[task 2020-07-02T12:22:09.206Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.207Z] 12:22:09 INFO - 2 xul.dll!js::gc::GCRuntime::beginSweepingSweepGroup(JSFreeOp*, js::SliceBudget&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 5297 + 0x36]
[task 2020-07-02T12:22:09.207Z] 12:22:09 INFO - eip = 0x5bd364fa esp = 0x002aeec4 ebp = 0x002af0e0 ebx = 0x0b828524
[task 2020-07-02T12:22:09.207Z] 12:22:09 INFO - esi = 0x0b82852c edi = 0x0b810800
[task 2020-07-02T12:22:09.208Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.208Z] 12:22:09 INFO - 3 xul.dll!sweepaction::SweepActionCall::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 5933 + 0xb]
[task 2020-07-02T12:22:09.209Z] 12:22:09 INFO - eip = 0x5bd43433 esp = 0x002af0e8 ebp = 0x002af0f0 ebx = 0x002af15c
[task 2020-07-02T12:22:09.209Z] 12:22:09 INFO - esi = 0x0b826180 edi = 0x00000001
[task 2020-07-02T12:22:09.209Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.210Z] 12:22:09 INFO - 4 xul.dll!sweepaction::SweepActionSequence::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6003 + 0x6]
[task 2020-07-02T12:22:09.211Z] 12:22:09 INFO - eip = 0x5bd45708 esp = 0x002af0f8 ebp = 0x002af108
[task 2020-07-02T12:22:09.211Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.211Z] 12:22:09 INFO - 5 xul.dll!sweepaction::SweepActionForEach<js::gc::SweepGroupsIter,JSRuntime >::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6038 + 0x6]
[task 2020-07-02T12:22:09.212Z] 12:22:09 INFO - eip = 0x5bd42ff6 esp = 0x002af110 ebp = 0x002af148 ebx = 0x002af728
[task 2020-07-02T12:22:09.212Z] 12:22:09 INFO - esi = 0x0b826300 edi = 0x002af15c
[task 2020-07-02T12:22:09.213Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.213Z] 12:22:09 INFO - 6 xul.dll!js::gc::GCRuntime::performSweepActions(js::SliceBudget&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6170 + 0x8]
[task 2020-07-02T12:22:09.214Z] 12:22:09 INFO - eip = 0x5bd38a4b esp = 0x002af150 ebp = 0x002af198 ebx = 0x002af728
[task 2020-07-02T12:22:09.214Z] 12:22:09 INFO - esi = 0x002af168 edi = 0x0b8272c8
[task 2020-07-02T12:22:09.215Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.215Z] 12:22:09 INFO - 7 xul.dll!js::gc::GCRuntime::incrementalSlice(js::SliceBudget&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason, js::gc::AutoGCSession&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6699 + 0x5]
[task 2020-07-02T12:22:09.215Z] 12:22:09 INFO - eip = 0x5bd3acbd esp = 0x002af1a0 ebp = 0x002af6b4 ebx = 0x0b8272f8
[task 2020-07-02T12:22:09.216Z] 12:22:09 INFO - esi = 0x002af270 edi = 0x0b8281c0
[task 2020-07-02T12:22:09.216Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.217Z] 12:22:09 INFO - 8 xul.dll!js::gc::GCRuntime::gcCycle(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7109 + 0x17]
[task 2020-07-02T12:22:09.217Z] 12:22:09 INFO - eip = 0x5bd3c0f1 esp = 0x002af6bc ebp = 0x002af778 ebx = 0x0b8285a0
[task 2020-07-02T12:22:09.218Z] 12:22:09 INFO - esi = 0x0b8272c8 edi = 0x0b8272f8
[task 2020-07-02T12:22:09.218Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.219Z] 12:22:09 INFO - 9 xul.dll!js::gc::GCRuntime::collect(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7343 + 0x67]
[task 2020-07-02T12:22:09.219Z] 12:22:09 INFO - eip = 0x5bd3c9ec esp = 0x002af780 ebp = 0x002af828 ebx = 0x00000002
[task 2020-07-02T12:22:09.219Z] 12:22:09 INFO - esi = 0x0b8272c8 edi = 0x00000001
[task 2020-07-02T12:22:09.220Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.220Z] 12:22:09 INFO - 10 xul.dll!js::gc::GCRuntime::gc(JSGCInvocationKind, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7420 + 0x59]
[task 2020-07-02T12:22:09.221Z] 12:22:09 INFO - eip = 0x5bd256e4 esp = 0x002af830 ebp = 0x002af8d0 ebx = 0x0b810800
[task 2020-07-02T12:22:09.221Z] 12:22:09 INFO - esi = 0x919bdf37 edi = 0x0b8272c8
[task 2020-07-02T12:22:09.222Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.222Z] 12:22:09 INFO - 11 xul.dll!JSRuntime::destroyRuntime() [Runtime.cpp:bf1694d363d929118ae66a03341a444323aea63d : 287 + 0xb]
[task 2020-07-02T12:22:09.223Z] 12:22:09 INFO - eip = 0x5bc4c191 esp = 0x002af8d8 ebp = 0x002af8fc esi = 0x0b827000
[task 2020-07-02T12:22:09.223Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.223Z] 12:22:09 INFO - 12 xul.dll!js::DestroyContext(JSContext
) [JSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 213 + 0x7]
[task 2020-07-02T12:22:09.224Z] 12:22:09 INFO - eip = 0x5bc10516 esp = 0x002af904 ebp = 0x002af91c ebx = 0x00000000
[task 2020-07-02T12:22:09.224Z] 12:22:09 INFO - esi = 0x0b810800 edi = 0x0b827000
[task 2020-07-02T12:22:09.225Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.225Z] 12:22:09 INFO - 13 xul.dll!mozilla::CycleCollectedJSContext::~CycleCollectedJSContext() [CycleCollectedJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 104 + 0xb]
[task 2020-07-02T12:22:09.226Z] 12:22:09 INFO - eip = 0x58cc07fc esp = 0x002af924 ebp = 0x002af93c esi = 0x0b9cb000
[task 2020-07-02T12:22:09.226Z] 12:22:09 INFO - edi = 0x0b9cad40
[task 2020-07-02T12:22:09.227Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.227Z] 12:22:09 INFO - 14 xul.dll!XPCJSContext::~XPCJSContext() [XPCJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 1063 + 0x7]
[task 2020-07-02T12:22:09.228Z] 12:22:09 INFO - eip = 0x585158cd esp = 0x002af944 ebp = 0x002af95c ebx = 0x00000000
[task 2020-07-02T12:22:09.228Z] 12:22:09 INFO - esi = 0x0b9cb000 edi = 0x0b9cad40
[task 2020-07-02T12:22:09.229Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.229Z] 12:22:09 INFO - 15 xul.dll!XPCJSContext::~XPCJSContext() [XPCJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 1027 + 0x5]
[task 2020-07-02T12:22:09.230Z] 12:22:09 INFO - eip = 0x594018fb esp = 0x002af964 ebp = 0x002af968 esi = 0x0b9cb000
[task 2020-07-02T12:22:09.230Z] 12:22:09 INFO - edi = 0x00879420
[task 2020-07-02T12:22:09.230Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.231Z] 12:22:09 INFO - 16 xul.dll!nsXPConnect::~nsXPConnect() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 126 + 0xd]
[task 2020-07-02T12:22:09.231Z] 12:22:09 INFO - eip = 0x5941a88a esp = 0x002af970 ebp = 0x002af978 esi = 0x008b03a0
[task 2020-07-02T12:22:09.232Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.232Z] 12:22:09 INFO - 17 xul.dll!nsXPConnect::~nsXPConnect() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 97 + 0x5]
[task 2020-07-02T12:22:09.232Z] 12:22:09 INFO - eip = 0x5941cb6b esp = 0x002af980 ebp = 0x002af984 esi = 0x008b03a0
[task 2020-07-02T12:22:09.233Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.233Z] 12:22:09 INFO - 18 xul.dll!static nsXPConnect::ReleaseXPConnectSingleton() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 161 + 0x13]
[task 2020-07-02T12:22:09.234Z] 12:22:09 INFO - eip = 0x5941a8c0 esp = 0x002af98c ebp = 0x002af990 esi = 0x008088e0
[task 2020-07-02T12:22:09.234Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.235Z] 12:22:09 INFO - 19 xul.dll!nsComponentManagerImpl::Shutdown() [nsComponentManager.cpp:bf1694d363d929118ae66a03341a444323aea63d : 931 + 0x5]
[task 2020-07-02T12:22:09.235Z] 12:22:09 INFO - eip = 0x58d331dc esp = 0x002af998 ebp = 0x002af9a0
[task 2020-07-02T12:22:09.236Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.236Z] 12:22:09 INFO - 20 xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:bf1694d363d929118ae66a03341a444323aea63d : 736 + 0x5]
[task 2020-07-02T12:22:09.236Z] 12:22:09 INFO - eip = 0x58d6110a esp = 0x002af9a8 ebp = 0x002af9bc esi = 0x00000000
[task 2020-07-02T12:22:09.237Z] 12:22:09 INFO - edi = 0x00879420
[task 2020-07-02T12:22:09.237Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.237Z] 12:22:09 INFO - 21 xul.dll!XRE_XPCShellMain(int, char**, char**, XREShellData const*) [XPCShellImpl.cpp:bf1694d363d929118ae66a03341a444323aea63d : 1377 + 0x7]
[task 2020-07-02T12:22:09.238Z] 12:22:09 INFO - eip = 0x59409b0a esp = 0x002af9c4 ebp = 0x002afc58 esi = 0x00000001
[task 2020-07-02T12:22:09.238Z] 12:22:09 INFO - edi = 0x00000000
[task 2020-07-02T12:22:09.239Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.239Z] 12:22:09 INFO - 22 xul.dll!mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) [Bootstrap.cpp:bf1694d363d929118ae66a03341a444323aea63d : 54 + 0x11]
[task 2020-07-02T12:22:09.240Z] 12:22:09 INFO - eip = 0x5ba9e064 esp = 0x002afc60 ebp = 0x002afc70 ebx = 0x00803b00
[task 2020-07-02T12:22:09.240Z] 12:22:09 INFO - esi = 0x003badf0 edi = 0x00803f80
[task 2020-07-02T12:22:09.241Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.241Z] 12:22:09 INFO - 23 xpcshell.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:bf1694d363d929118ae66a03341a444323aea63d : 131 + 0x5a]
[task 2020-07-02T12:22:09.242Z] 12:22:09 INFO - eip = 0x00ce11dd esp = 0x002afc78 ebp = 0x002afcac
[task 2020-07-02T12:22:09.242Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.243Z] 12:22:09 INFO - 24 xpcshell.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x1c]
[task 2020-07-02T12:22:09.243Z] 12:22:09 INFO - eip = 0x00d0db5c esp = 0x002afcb4 ebp = 0x002afcf4 ebx = 0x7ffde000
[task 2020-07-02T12:22:09.244Z] 12:22:09 INFO - esi = 0x6be0f0b0 edi = 0x003b6728
[task 2020-07-02T12:22:09.244Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.244Z] 12:22:09 INFO - 25 kernel32.dll!_libm_sse2_pow_precise + 0x20c
[task 2020-07-02T12:22:09.245Z] 12:22:09 INFO - eip = 0x7786ef3c esp = 0x002afcfc ebp = 0x002afd00
[task 2020-07-02T12:22:09.245Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.246Z] 12:22:09 INFO - 26 ntdll.dll!__libm_sse2_asin + 0x3e8
[task 2020-07-02T12:22:09.246Z] 12:22:09 INFO - eip = 0x77c13618 esp = 0x002afd08 ebp = 0x002afd40
[task 2020-07-02T12:22:09.246Z] 12:22:09 INFO - Found by: previous frame's frame pointer
[task 2020-07-02T12:22:09.247Z] 12:22:09 INFO - 27 kernel32.dll!control87 + 0x49
[task 2020-07-02T12:22:09.247Z] 12:22:09 INFO - eip = 0x778808b9 esp = 0x002afd24 ebp = 0x002afd40
[task 2020-07-02T12:22:09.248Z] 12:22:09 INFO - Found by: call frame info with scanning
[task 2020-07-02T12:22:09.248Z] 12:22:09 INFO - 28 ntdll.dll!__libm_sse2_asin + 0x3bb
[task 2020-07-02T12:22:09.248Z] 12:22:09 INFO - eip = 0x77c135eb esp = 0x002afd48 ebp = 0x002afd58
[task 2020-07-02T12:22:09.249Z] 12:22:09 INFO - Found by: call frame info
[task 2020-07-02T12:22:09.249Z] 12:22:09 INFO - 29 xpcshell.exe + 0x2dbf0
[task 2020-07-02T12:22:09.250Z] 12:22:09 INFO - eip = 0x00d0dbf0 esp = 0x002afd64 ebp = 0x002afd58
[task 2020-07-02T12:22:09.250Z] 12:22:09 INFO - Found by: call frame info with scanning

Looks like a UAF during shutdown. It's possible that bug 1649702 has caused this since that concerns xpconnect and shutdown, but I don't see why.

Crash reason:  EXCEPTION_ACCESS_VIOLATION_READ
Crash address: 0xe5e5e605
Process uptime: 7 seconds
Thread 0 (crashed)
 0  xul.dll!JSObject2WrappedJSMap::UpdateWeakPointersAfterGC() [XPCMaps.cpp:bf1694d363d929118ae66a03341a444323aea63d : 52 + 0x0]
 1  xul.dll!xpc::CompartmentPrivate::UpdateWeakPointersAfterGC() [XPCJSRuntime.cpp:bf1694d363d929118ae66a03341a444323aea63d : 925 + 0x5]
 2  xul.dll!js::gc::GCRuntime::beginSweepingSweepGroup(JSFreeOp*, js::SliceBudget&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 5297 + 0x36]
 3  xul.dll!sweepaction::SweepActionCall::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 5933 + 0xb]
 4  xul.dll!sweepaction::SweepActionSequence::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6003 + 0x6]
 5  xul.dll!sweepaction::SweepActionForEach<js::gc::SweepGroupsIter,JSRuntime *>::run(js::gc::SweepAction::Args&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6038 + 0x6]
 6  xul.dll!js::gc::GCRuntime::performSweepActions(js::SliceBudget&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6170 + 0x8]
 7  xul.dll!js::gc::GCRuntime::incrementalSlice(js::SliceBudget&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason, js::gc::AutoGCSession&) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 6699 + 0x5]
 8  xul.dll!js::gc::GCRuntime::gcCycle(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7109 + 0x17]
 9  xul.dll!js::gc::GCRuntime::collect(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7343 + 0x67]
10  xul.dll!js::gc::GCRuntime::gc(JSGCInvocationKind, JS::GCReason) [GC.cpp:bf1694d363d929118ae66a03341a444323aea63d : 7420 + 0x59]
11  xul.dll!JSRuntime::destroyRuntime() [Runtime.cpp:bf1694d363d929118ae66a03341a444323aea63d : 287 + 0xb]
12  xul.dll!js::DestroyContext(JSContext*) [JSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 213 + 0x7]
13  xul.dll!mozilla::CycleCollectedJSContext::~CycleCollectedJSContext() [CycleCollectedJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 104 + 0xb]
14  xul.dll!XPCJSContext::~XPCJSContext() [XPCJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 1063 + 0x7]
15  xul.dll!XPCJSContext::~XPCJSContext() [XPCJSContext.cpp:bf1694d363d929118ae66a03341a444323aea63d : 1027 + 0x5]
16  xul.dll!nsXPConnect::~nsXPConnect() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 126 + 0xd]
17  xul.dll!nsXPConnect::~nsXPConnect() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 97 + 0x5]
18  xul.dll!static nsXPConnect::ReleaseXPConnectSingleton() [nsXPConnect.cpp:bf1694d363d929118ae66a03341a444323aea63d : 161 + 0x13]
19  xul.dll!nsComponentManagerImpl::Shutdown() [nsComponentManager.cpp:bf1694d363d929118ae66a03341a444323aea63d : 931 + 0x5]

Poison values in registers. This might be more appropriately put in XPConnect. I'm not sure.

Group: javascript-core-security

We're doing a GC in shutdown. Maybe the CompartmentPrivate got destroyed already?

Group: javascript-core-security → dom-core-security
Component: JavaScript: GC → XPConnect

Ah, I see, Jon already commented. I missed that in the midst of all of the log spam from comment 0.

Yeah, bug 1649702 does seem like a likely regressor. Maybe it causes ShouldCleanUpEverything() to return a different value?

Regressed by: 1649702
Has Regression Range: --- → yes
Keywords: regression
Assignee: nobody → allstars.chh

Set release status flags based on info from the regressing bug 1649702

Severity: normal → S2
Flags: needinfo?(allstars.chh)
Priority: -- → P3

This is in my list but the orange factor is low (seems no try failure) and no crash-report so far.

Flags: needinfo?(allstars.chh)
See Also: → 1672010

Can you maybe take another look at this? It happened at least once more in automation, and it is happening a fair amount in the wild, including some crashes on poison values, though those may be a different issue... Thanks.

Flags: needinfo?(allstars.chh)
Comment 8 is private: false

Here's an example of a crash on a poison value: bp-af98c2f9-67b0-427f-a655-aa9f90201211

Originally in bug 1649702 I just tried not to submit a SourceCompressionTask during shutdown.
I'll file another bug to remove the XPCONNECT_SHUTDOWN from IsShutdownReason and add another reason check before calling StartHandlingCompressionOnGC
https://searchfox.org/mozilla-central/rev/8883276967d39918e2ce64e873afdd432fb406ca/js/src/gc/GC.cpp#4213

If the problem goes away then we know the problem is from adding XPCONNECT_SHUTDOWN in IsShutdownReason.

Flags: needinfo?(allstars.chh)

Not all of these crashes are during shutdown (like the one in comment 9) so I don't think it will help everything but it would be good to be able to narrow down the cause a bit.

No recent crashes AFAICT so I'll mark this as incomplete.

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Group: dom-core-security
You need to log in before you can comment on or make changes to this bug.