1503226 - Intermittent application crashed [@ <name omitted>] after application terminated with exit code 11 - crash in CycleCollector

Status: RESOLVED FIXED

(Core :: XPConnect, defect, P2)

Description

[Treeherder Bug Filer]

Filed by: ebalazs [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=208580995&repo=autoland

https://queue.taskcluster.net/v1/task/fVvkXfQdQm2MpWsSQ4OrZg/runs/0/artifacts/public/logs/live_backing.log

TEST-START | toolkit/components/antitracking/test/browser/browser_userInteraction.js
[task 2018-10-30T09:08:43.041Z] 09:08:43     INFO - GECKO(7019) | MEMORY STAT | vsize 808MB | residentFast 425MB | heapAllocated 140MB
[task 2018-10-30T09:08:43.043Z] 09:08:43     INFO - TEST-OK | toolkit/components/antitracking/test/browser/browser_userInteraction.js | took 5317ms
[task 2018-10-30T09:08:43.060Z] 09:08:43     INFO - checking window state
[task 2018-10-30T09:08:44.392Z] 09:08:44     INFO - GECKO(7019) | ExceptionHandler::GenerateDump cloned child 7493
[task 2018-10-30T09:08:44.392Z] 09:08:44     INFO - GECKO(7019) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2018-10-30T09:08:44.393Z] 09:08:44     INFO - GECKO(7019) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2018-10-30T09:08:44.655Z] 09:08:44     INFO - GECKO(7019) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2018-10-30T09:08:44.657Z] 09:08:44     INFO - GECKO(7019) | [Child 7320, Chrome_ChildThread] WARNING: pipe error (3): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 363
[task 2018-10-30T09:08:44.658Z] 09:08:44     INFO - GECKO(7019) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2018-10-30T09:08:44.660Z] 09:08:44     INFO - GECKO(7019) | [Child 7295, Chrome_ChildThread] WARNING: pipe error (3): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 363
[task 2018-10-30T09:08:44.661Z] 09:08:44     INFO - GECKO(7019) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2018-10-30T09:08:44.663Z] 09:08:44     INFO - GECKO(7019) | [Child 7176, Chrome_ChildThread] WARNING: pipe error (3): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 363
[task 2018-10-30T09:08:44.720Z] 09:08:44     INFO - TEST-INFO | Main app process: exit 11
[task 2018-10-30T09:08:44.720Z] 09:08:44     INFO - Buffered messages finished
[task 2018-10-30T09:08:44.722Z] 09:08:44    ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 11
[task 2018-10-30T09:08:44.722Z] 09:08:44     INFO - runtests.py | Application ran for: 0:05:51.647722
[task 2018-10-30T09:08:44.723Z] 09:08:44     INFO - zombiecheck | Reading PID log: /tmp/tmpg3tJK_pidlog
[task 2018-10-30T09:08:44.724Z] 09:08:44     INFO - ==> process 7019 launched child process 7042
[task 2018-10-30T09:08:44.724Z] 09:08:44     INFO - ==> process 7019 launched child process 7110
[task 2018-10-30T09:08:44.725Z] 09:08:44     INFO - ==> process 7019 launched child process 7131
[task 2018-10-30T09:08:44.726Z] 09:08:44     INFO - ==> process 7019 launched child process 7176
[task 2018-10-30T09:08:44.726Z] 09:08:44     INFO - ==> process 7019 launched child process 7210
[task 2018-10-30T09:08:44.728Z] 09:08:44     INFO - ==> process 7019 launched child process 7241
[task 2018-10-30T09:08:44.728Z] 09:08:44     INFO - ==> process 7019 launched child process 7245
[task 2018-10-30T09:08:44.729Z] 09:08:44     INFO - ==> process 7019 launched child process 7270
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - ==> process 7019 launched child process 7295
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - ==> process 7019 launched child process 7320
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - ==> process 7019 launched child process 7345
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7042
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7270
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7110
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7176
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7241
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7210
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7245
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7345
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7320
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7131
[task 2018-10-30T09:08:44.734Z] 09:08:44     INFO - zombiecheck | Checking for orphan process with PID: 7295
[task 2018-10-30T09:08:44.736Z] 09:08:44     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/GduFpYAGTKiRRAMbLYj7Yg/artifacts/public/build/target.crashreporter-symbols.zip
[task 2018-10-30T09:08:51.651Z] 09:08:51     INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpV1pBZu.mozrunner/minidumps/27f4961a-ad46-4c12-7335-4a220847223c.dmp /tmp/tmpYsmEcJ
[task 2018-10-30T09:09:02.193Z] 09:09:02     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/27f4961a-ad46-4c12-7335-4a220847223c.dmp
[task 2018-10-30T09:09:02.194Z] 09:09:02     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/27f4961a-ad46-4c12-7335-4a220847223c.extra
[task 2018-10-30T09:09:02.336Z] 09:09:02     INFO - PROCESS-CRASH | Last test finished | application crashed [@ <name omitted>]
[task 2018-10-30T09:09:02.336Z] 09:09:02     INFO - Crash dump filename: /tmp/tmpV1pBZu.mozrunner/minidumps/27f4961a-ad46-4c12-7335-4a220847223c.dmp
[task 2018-10-30T09:09:02.337Z] 09:09:02     INFO - Operating system: Linux
[task 2018-10-30T09:09:02.337Z] 09:09:02     INFO -                   0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2018-10-30T09:09:02.338Z] 09:09:02     INFO - CPU: x86
[task 2018-10-30T09:09:02.338Z] 09:09:02     INFO -      GenuineIntel family 6 model 62 stepping 4
[task 2018-10-30T09:09:02.339Z] 09:09:02     INFO -      2 CPUs
[task 2018-10-30T09:09:02.339Z] 09:09:02     INFO - 
[task 2018-10-30T09:09:02.340Z] 09:09:02     INFO - GPU: UNKNOWN
[task 2018-10-30T09:09:02.340Z] 09:09:02     INFO - 
[task 2018-10-30T09:09:02.341Z] 09:09:02     INFO - Crash reason:  SIGSEGV
[task 2018-10-30T09:09:02.341Z] 09:09:02     INFO - Crash address: 0xcf6ffff8
[task 2018-10-30T09:09:02.341Z] 09:09:02     INFO - Process uptime: not available
[task 2018-10-30T09:09:02.342Z] 09:09:02     INFO - 
[task 2018-10-30T09:09:02.342Z] 09:09:02     INFO - Thread 0 (crashed)
[task 2018-10-30T09:09:02.343Z] 09:09:02     INFO -  0  libxul.so!<name omitted> [Cell.h:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 286 + 0x0]
[task 2018-10-30T09:09:02.343Z] 09:09:02     INFO -     eip = 0xf280ab17   esp = 0xff87ec90   ebp = 0xff87ecb8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.344Z] 09:09:02     INFO -     esi = 0xd1e01ea8   edi = 0xd1e01e8c   eax = 0xcf600000   ecx = 0x00000000
[task 2018-10-30T09:09:02.344Z] 09:09:02     INFO -     edx = 0xf72febc8   efl = 0x00210286
[task 2018-10-30T09:09:02.345Z] 09:09:02     INFO -     Found by: given as instruction pointer in context
[task 2018-10-30T09:09:02.345Z] 09:09:02     INFO -  1  libxul.so!nsXPCWrappedJS::~nsXPCWrappedJS() [RootingAPI.h:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 688 + 0x14]
[task 2018-10-30T09:09:02.345Z] 09:09:02     INFO -     eip = 0xefbd6a0a   esp = 0xff87ecc0   ebp = 0xff87ecd8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.346Z] 09:09:02     INFO -     esi = 0xd1e01e80   edi = 0xd1e01e8c
[task 2018-10-30T09:09:02.347Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.347Z] 09:09:02     INFO -  2  libxul.so!nsXPCWrappedJS::~nsXPCWrappedJS() [XPCWrappedJS.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 460 + 0x8]
[task 2018-10-30T09:09:02.348Z] 09:09:02     INFO -     eip = 0xefbd6a7f   esp = 0xff87ece0   ebp = 0xff87ecf8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.348Z] 09:09:02     INFO -     esi = 0xd1e01e80   edi = 0x000000cc
[task 2018-10-30T09:09:02.349Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.350Z] 09:09:02     INFO -  3  libxul.so!nsXPCWrappedJS::cycleCollection::DeleteCycleCollectable(void*) [XPCWrappedJS.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 307 + 0xa]
[task 2018-10-30T09:09:02.350Z] 09:09:02     INFO -     eip = 0xefbe7336   esp = 0xff87ed00   ebp = 0xff87ed08   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.351Z] 09:09:02     INFO -     esi = 0xd0ce0000   edi = 0x000000cc
[task 2018-10-30T09:09:02.351Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.352Z] 09:09:02     INFO -  4  libxul.so!SnowWhiteKiller::~SnowWhiteKiller() [nsCycleCollector.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 2754 + 0xe]
[task 2018-10-30T09:09:02.353Z] 09:09:02     INFO -     eip = 0xef39a606   esp = 0xff87ed10   ebp = 0xff87ed58   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.353Z] 09:09:02     INFO -     esi = 0xd0ce0000   edi = 0x000000cc
[task 2018-10-30T09:09:02.354Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.354Z] 09:09:02     INFO -  5  libxul.so!nsCycleCollector::BeginCollection(ccType, nsICycleCollectorListener*) [nsCycleCollector.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 2963 + 0x10]
[task 2018-10-30T09:09:02.355Z] 09:09:02     INFO -     eip = 0xef39cc26   esp = 0xff87ed60   ebp = 0xff87edb8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.355Z] 09:09:02     INFO -     esi = 0xf53e2001   edi = 0xd0c71000
[task 2018-10-30T09:09:02.356Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.357Z] 09:09:02     INFO -  6  libxul.so!nsCycleCollector::Collect(ccType, js::SliceBudget&, nsICycleCollectorListener*, bool) [nsCycleCollector.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 3817 + 0x18]
[task 2018-10-30T09:09:02.358Z] 09:09:02     INFO -     eip = 0xef39c77f   esp = 0xff87edc0   ebp = 0xff87ee28   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.359Z] 09:09:02     INFO -     esi = 0xf704b660   edi = 0x5787be01
[task 2018-10-30T09:09:02.360Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.360Z] 09:09:02     INFO -  7  libxul.so!nsCycleCollector_collect(nsICycleCollectorListener*) [nsCycleCollector.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 4408 + 0x13]
[task 2018-10-30T09:09:02.361Z] 09:09:02     INFO -     eip = 0xef39dce6   esp = 0xff87ee30   ebp = 0xff87ee88   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.362Z] 09:09:02     INFO -     esi = 0xff87ee58   edi = 0x00000000
[task 2018-10-30T09:09:02.363Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.363Z] 09:09:02     INFO -  8  libxul.so!nsJSContext::CycleCollectNow(nsICycleCollectorListener*) [nsJSEnvironment.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 1526 + 0xb]
[task 2018-10-30T09:09:02.364Z] 09:09:02     INFO -     eip = 0xf0311e05   esp = 0xff87ee90   ebp = 0xff87eeb8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.365Z] 09:09:02     INFO -     esi = 0xf540bf08   edi = 0xf7006040
[task 2018-10-30T09:09:02.366Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.367Z] 09:09:02     INFO -  9  libxul.so!nsXPCComponents_Utils::ForceCC(nsICycleCollectorListener*) [XPCComponents.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 2397 + 0x8]
[task 2018-10-30T09:09:02.368Z] 09:09:02     INFO -     eip = 0xefbb308c   esp = 0xff87eec0   ebp = 0xff87eec8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.368Z] 09:09:02     INFO -     esi = 0x00000001   edi = 0xff87ef50
[task 2018-10-30T09:09:02.369Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.370Z] 09:09:02     INFO - 10  libxul.so!NS_InvokeByIndex + 0x30
[task 2018-10-30T09:09:02.370Z] 09:09:02     INFO -     eip = 0xef41ee70   esp = 0xff87eed0   ebp = 0xff87eee8   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.371Z] 09:09:02     INFO -     esi = 0x00000001   edi = 0xff87ef50
[task 2018-10-30T09:09:02.371Z] 09:09:02     INFO -     Found by: call frame info
[task 2018-10-30T09:09:02.372Z] 09:09:02     INFO - 11  libxul.so!XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [XPCWrappedNative.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 1723 + 0xf]
[task 2018-10-30T09:09:02.373Z] 09:09:02     INFO -     eip = 0xefbddefe   esp = 0xff87eef0   ebp = 0xff87f0a8
[task 2018-10-30T09:09:02.373Z] 09:09:02     INFO -     Found by: previous frame's frame pointer
[task 2018-10-30T09:09:02.374Z] 09:09:02     INFO - 12  libxul.so!XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [XPCWrappedNativeJSOps.cpp:63fc6e8dea677888fdf36dcba91b4f179d19f3c2 : 1020 + 0x9]
[task 2018-10-30T09:09:02.375Z] 09:09:02     INFO -     eip = 0xefbdf384   esp = 0xff87f0b0   ebp = 0xff87f168   ebx = 0xf53e2000
[task 2018-10-30T09:09:02.375Z] 09:09:02     INFO -     esi = 0xeaa04400   edi = 0xff87f0c0
[task 2018-10-30T09:09:02.376Z] 09:09:02     INFO -     Found by: call frame info

Comment 2

[Daniel Veditz [:dveditz]]

Clearing "name omitted" crash signature because that's not all that useful.

Comment 3

[Intermittent Failures Robot]

3 failures in 2978 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 1
* autoland: 2

Platform breakdown:
* windows10-64: 1
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-10-29&endday=2018-11-04&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 3007 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-11-05&endday=2018-11-11&tree=all

Comment 5

[Intermittent Failures Robot]

6 failures in 2917 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 2
* mozilla-central: 3
* try: 1

Platform breakdown:
* linux32: 3
* linux64-qr: 1
* linux64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-11-12&endday=2018-11-18&tree=all

Comment 11

[Intermittent Failures Robot]

31 failures in 2387 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #47 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. ** 

Repository breakdown:
* mozilla-inbound: 11
* autoland: 16
* mozilla-central: 4

Platform breakdown:
* osx-10-10: 1
* linux32: 16
* linux64: 14

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-11-19&endday=2018-11-25&tree=all

Comment 12

[Dorel Luca [:dluca]]

This bug failed 34 times in the last 7 days. Occurs on Linux32 and Linux64 on opt and debug build type.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=214983641&repo=mozilla-inbound&lineNumber=5879

neha: Can you please take a look at this bug?

Comment 13

[Intermittent Failures Robot]

34 failures in 2808 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. ** 

Repository breakdown:
* mozilla-inbound: 8
* autoland: 18
* mozilla-central: 6
* try: 2

Platform breakdown:
* linux32: 19
* linux64: 14
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-11-26&endday=2018-12-02&tree=all

Comment 14

[Andrew McCreight [:mccr8]]

jonco is looking at this issue over in bug 1508102.

Comment 15

[Intermittent Failures Robot]

3 failures in 1862 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 1
* mozilla-central: 1
* autoland: 1

Platform breakdown:
* linux64: 1
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-12-03&endday=2018-12-09&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 1
* autoland: 7
* mozilla-inbound: 6
* mozilla-central: 3

Platform breakdown:
* linux32: 6
* linux64: 11

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-12-10&endday=2018-12-16&tree=all

Comment 17

[Dorel Luca [:dluca]]

This bug failed 44 times in the last 7 days. Occurs on linux platforms and a few times on opt and debug build types.

Log:
https://treeherder.mozilla.org/logviewer.html#?job_id=217181616&repo=mozilla-inbound

Comment 18

[Intermittent Failures Robot]

34 failures in 2648 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. ** 

Repository breakdown:
* mozilla-central: 4
* mozilla-inbound: 11
* autoland: 19

Platform breakdown:
* linux64: 13
* osx-10-10: 2
* linux32: 18
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2018-12-17&endday=2018-12-23&tree=all

Comment 19

[Andrew McCreight [:mccr8]]

Fixed by bug 1480121.

Comment 20

[Intermittent Failures Robot]

1 failures in 4292 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 21

[Intermittent Failures Robot]

1 failures in 5423 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1503226&startday=2020-04-27&endday=2020-05-03&tree=all