1730733 - Intermittent [tier 2] Main app process exited normally | application crashed [@ nsFrameLoader::Show(nsSubDocumentFrame*)]

Status: RESOLVED FIXED

(Core :: DOM: Navigation, defect)

Description

[Treeherder Bug Filer]

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=351514290&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Wxq9kNSTRPmO60yLodSwPA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-09-14T16:58:40.919Z] 16:58:40     INFO - TEST-START | docshell/test/browser/browser_viewsource_multipart.js
[task 2021-09-14T16:58:41.421Z] 16:58:41     INFO - GECKO(2329) | IPDL protocol error: Handler returned error code!
[task 2021-09-14T16:58:41.422Z] 16:58:41     INFO - GECKO(2329) | ###!!! [Parent][DispatchAsyncMessage] Error: PClientManager::Msg_ExpectFutureClientSource Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2021-09-14T16:58:41.434Z] 16:58:41     INFO - GECKO(2329) | IPDL protocol error: Handler returned error code!
[task 2021-09-14T16:58:41.435Z] 16:58:41     INFO - GECKO(2329) | ###!!! [Parent][DispatchAsyncMessage] Error: PClientManager::Msg_ForgetFutureClientSource Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2021-09-14T16:58:41.469Z] 16:58:41     INFO - GECKO(2329) | MEMORY STAT | vsize 8611MB | residentFast 785MB | heapAllocated 192MB
[task 2021-09-14T16:58:41.469Z] 16:58:41     INFO - TEST-OK | docshell/test/browser/browser_viewsource_multipart.js | took 550ms
[task 2021-09-14T16:58:41.479Z] 16:58:41     INFO - checking window state
[task 2021-09-14T16:58:41.490Z] 16:58:41     INFO - GECKO(2329) | Completed ShutdownLeaks collections in process 2329
[task 2021-09-14T16:58:41.490Z] 16:58:41     INFO - TEST-START | Shutdown
[task 2021-09-14T16:58:41.491Z] 16:58:41     INFO - Browser Chrome Test Summary
[task 2021-09-14T16:58:41.492Z] 16:58:41     INFO - Passed:  3956
[task 2021-09-14T16:58:41.492Z] 16:58:41     INFO - Failed:  0
[task 2021-09-14T16:58:41.492Z] 16:58:41     INFO - Todo:    2
[task 2021-09-14T16:58:41.493Z] 16:58:41     INFO - Mode:    e10s
[task 2021-09-14T16:58:41.493Z] 16:58:41     INFO - *** End BrowserChrome Test Results ***
[task 2021-09-14T16:58:41.534Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.535Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.535Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.536Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.536Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.536Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.537Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.537Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.538Z] 16:58:41     INFO - GECKO(2329) | Exiting due to channel error.
[task 2021-09-14T16:58:41.693Z] 16:58:41     INFO - TEST-INFO | Main app process: exit 0
[task 2021-09-14T16:58:41.694Z] 16:58:41     INFO - runtests.py | Application ran for: 0:02:37.114116
[task 2021-09-14T16:58:41.694Z] 16:58:41     INFO - zombiecheck | Reading PID log: /var/folders/4w/4sjw1np10xscw9gxswptj15h000014/T/tmpomtdsreqpidlog
[task 2021-09-14T16:58:41.695Z] 16:58:41     INFO - ==> process 2329 launched child process 2330
[task 2021-09-14T16:58:41.695Z] 16:58:41     INFO - ==> process 2329 launched child process 2331
<...>
[task 2021-09-14T16:58:41.828Z] 16:58:41     INFO - zombiecheck | Checking for orphan process with PID: 2558
[task 2021-09-14T16:58:41.829Z] 16:58:41     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YSX_bHM0RBewiwCgnKSh0Q/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-09-14T16:58:43.826Z] 16:58:43     INFO - mozcrash Copy/paste: /opt/worker/tasks/task_163163782998300/fetches/minidump_stackwalk/minidump_stackwalk /var/folders/4w/4sjw1np10xscw9gxswptj15h000014/T/tmppo023wz7.mozrunner/minidumps/EA3877BF-8CFB-48CB-ABA4-BFC57CEEB8C0.dmp /var/folders/4w/4sjw1np10xscw9gxswptj15h000014/T/tmpo3ivif2w
[task 2021-09-14T16:58:47.499Z] 16:58:47     INFO - mozcrash Saved minidump as /opt/worker/tasks/task_163163782998300/build/blobber_upload_dir/EA3877BF-8CFB-48CB-ABA4-BFC57CEEB8C0.dmp
[task 2021-09-14T16:58:47.499Z] 16:58:47     INFO - mozcrash Saved app info as /opt/worker/tasks/task_163163782998300/build/blobber_upload_dir/EA3877BF-8CFB-48CB-ABA4-BFC57CEEB8C0.extra
[task 2021-09-14T16:58:47.712Z] 16:58:47     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ nsFrameLoader::Show(nsSubDocumentFrame*)]
[task 2021-09-14T16:58:47.712Z] 16:58:47     INFO - Crash dump filename: /var/folders/4w/4sjw1np10xscw9gxswptj15h000014/T/tmppo023wz7.mozrunner/minidumps/EA3877BF-8CFB-48CB-ABA4-BFC57CEEB8C0.dmp
[task 2021-09-14T16:58:47.712Z] 16:58:47     INFO - Operating system: Mac OS X
[task 2021-09-14T16:58:47.712Z] 16:58:47     INFO -                   10.15.7 19H524
[task 2021-09-14T16:58:47.712Z] 16:58:47     INFO - CPU: amd64
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO -      family 6 model 158 stepping 10
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO -      12 CPUs
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - 
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - GPU: UNKNOWN
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - 
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - Crash reason:  EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - Crash address: 0x0
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - Process uptime: 3 seconds
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - 
[task 2021-09-14T16:58:47.713Z] 16:58:47     INFO - Thread 0 tid 172299 (crashed) - GeckoMain 0  XUL!nsFrameLoader::ShowRemoteFrame(mozilla::gfx::IntSizeTyped<mozilla::ScreenPixel> const&, nsSubDocumentFrame*) [nsFrameLoader.cpp:45206a6d152efbb2d59cd8fbd9ae91251375d464 : 1135 + 0xc]

Comment 1

[Chris Peterson [:cpeterson]]

Nika, the first instance of this test crash was on 2021-09-14 (revision 45206a6d152efbb2d59cd8fbd9ae91251375d464), just a few revisions after your fix for bug 1729458 landed:

https://hg.mozilla.org/integration/autoland/shortlog/45206a6d152efbb2d59cd8fbd9ae91251375d464

Most of these crashes (13 of 15 so far) are on macOS (macmini-r8).

Comment 2

[Intermittent Failures Robot]

17 failures in 3375 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64-qr: 8
  • opt: 8
• macosx1015-64-shippable-qr: 7
  • opt: 7
• windows10-64-2004-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1730733&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 3

[Olli Pettay [:smaug][[e-mail]]]

This was a regression from an earlier version of the patch for bug 1727514