1472437 - Intermittent devtools/server/tests/browser/browser_perf-01.js | application terminated with exit code 1

Status: RESOLVED INCOMPLETE

(DevTools :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=185732283&repo=mozilla-central

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

04:02:49     INFO - TEST-START | devtools/server/tests/browser/browser_perf-01.js
04:02:49     INFO - GECKO(2360) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
04:02:49     INFO - GECKO(2360) | ** Unknown exception behavior: -2147483647
04:02:49     INFO - GECKO(2360) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
04:02:49     INFO - GECKO(2360) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
04:02:49     INFO - GECKO(2360) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
04:02:49     INFO - GECKO(2360) | ** Unknown exception behavior: -2147483647
04:02:49     INFO - GECKO(2360) | ** Unknown exception behavior: -2147483647
04:02:49     INFO - GECKO(2360) | ** Unknown exception behavior: -2147483647
04:02:49     INFO - GECKO(2360) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
04:02:49     INFO - GECKO(2360) | ** Unknown exception behavior: -2147483647
04:02:51     INFO - TEST-INFO | Main app process: exit 1
04:02:51    ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
04:02:51     INFO - Buffered messages logged at 04:02:49
04:02:51     INFO - Entering test bound 
04:02:51     INFO - TEST-PASS | devtools/server/tests/browser/browser_perf-01.js | This test only runs on supported platforms. - 
04:02:51     INFO - TEST-PASS | devtools/server/tests/browser/browser_perf-01.js | The browser is not in private browsing mode. - 
04:02:51     INFO - TEST-PASS | devtools/server/tests/browser/browser_perf-01.js | The profiler is not active yet. - 
04:02:51     INFO - Waiting for event: 'profiler-started' on [Front for perf/server1.conn0.perfActor8].
04:02:51     INFO - Got event: 'profiler-started' on [Front for perf/server1.conn0.perfActor8].
04:02:51     INFO - TEST-PASS | devtools/server/tests/browser/browser_perf-01.js | The profiler was started. - 
04:02:51     INFO - Waiting for event: 'profiler-stopped' on [Front for perf/server1.conn0.perfActor8].
04:02:51     INFO - Buffered messages finished
04:02:51    ERROR - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_perf-01.js | application terminated with exit code 1
04:02:51     INFO - runtests.py | Application ran for: 0:01:02.359631
04:02:51     INFO - zombiecheck | Reading PID log: /var/folders/jk/zt0f3sgn0455bc5xyzz_kg5r00000w/T/tmphdv_uIpidlog
04:02:51     INFO - ==> process 2360 launched child process 2361
04:02:51     INFO - ==> process 2360 launched child process 2362
04:02:51     INFO - ==> process 2360 launched child process 2363
04:02:51     INFO - ==> process 2360 launched child process 2364
04:02:51     INFO - ==> process 2360 launched child process 2365
04:02:51     INFO - zombiecheck | Checking for orphan process with PID: 2361
04:02:51     INFO - zombiecheck | Checking for orphan process with PID: 2362
04:02:51     INFO - zombiecheck | Checking for orphan process with PID: 2363
04:02:51     INFO - zombiecheck | Checking for orphan process with PID: 2364
04:02:51     INFO - zombiecheck | Checking for orphan process with PID: 2365
04:02:51     INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1530326688/build/macosx64-minidump_stackwalk /var/folders/jk/zt0f3sgn0455bc5xyzz_kg5r00000w/T/tmpbOv7r5.mozrunner/minidumps/60511955-0FD3-4349-ACB2-7BC20C8DD51B.dmp /Users/cltbld/tasks/task_1530326688/build/symbols
04:03:08     INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1530326688/build/blobber_upload_dir/60511955-0FD3-4349-ACB2-7BC20C8DD51B.dmp
04:03:08     INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1530326688/build/blobber_upload_dir/60511955-0FD3-4349-ACB2-7BC20C8DD51B.extra
04:03:08     INFO - PROCESS-CRASH | devtools/server/tests/browser/browser_perf-01.js | application crashed [@ nsProfiler::StartGathering(double)::$_6::operator()(mozilla::ipc::Shmem const&) const]
04:03:08     INFO - Crash dump filename: /var/folders/jk/zt0f3sgn0455bc5xyzz_kg5r00000w/T/tmpbOv7r5.mozrunner/minidumps/60511955-0FD3-4349-ACB2-7BC20C8DD51B.dmp
04:03:08     INFO - Operating system: Mac OS X
04:03:08     INFO -                   10.10.5 14F27
04:03:08     INFO - CPU: amd64
04:03:08     INFO -      family 6 model 69 stepping 1
04:03:08     INFO -      4 CPUs
04:03:08     INFO - 
04:03:08     INFO - GPU: UNKNOWN
04:03:08     INFO - 
04:03:08     INFO - Crash reason:  EXC_BAD_ACCESS / KERN_PROTECTION_FAILURE
04:03:08     INFO - Crash address: 0x25c9d000
04:03:08     INFO - Process uptime: 60 seconds
04:03:08     INFO - 
04:03:08     INFO - Thread 0 (crashed)
04:03:08     INFO -  0  XUL!nsProfiler::StartGathering(double)::$_6::operator()(mozilla::ipc::Shmem const&) const [nsTString.h:c0cd065ee5c8832f6962d09805541ecad1d5398e : 507 + 0x6]
04:03:08     INFO -     rax = 0x0000000000019000   rdx = 0x0000000000019000
04:03:08     INFO -     rcx = 0x0000000125c84000   rbx = 0x0000000118054720
04:03:08     INFO -     rsi = 0x0000000125c84000   rdi = 0x00007fff5d741da8
04:03:08     INFO -     rbp = 0x00007fff5d741dd0   rsp = 0x00007fff5d741da0
04:03:08     INFO -      r8 = 0x0000000000000002    r9 = 0x0000000000000032
04:03:08     INFO -     r10 = 0x0000000000000011   r11 = 0xffff8001ad31f0e9
04:03:08     INFO -     r12 = 0x000000010270f668   r13 = 0x000000010270f640
04:03:08     INFO -     r14 = 0x0000000123704318   r15 = 0x0000000125c84000
04:03:08     INFO -     rip = 0x0000000107572b0a
04:03:08     INFO -     Found by: given as instruction pointer in context
04:03:08     INFO -  1  XUL!mozilla::MozPromise<mozilla::ipc::Shmem, mozilla::ipc::ResponseRejectReason, true>::ThenValue<nsProfiler::StartGathering(double)::$_6, nsProfiler::StartGathering(double)::$_7>::DoResolveOrRejectInternal(mozilla::MozPromise<mozilla::ipc::Shmem, mozilla::ipc::ResponseRejectReason, true>::ResolveOrRejectValue&) [MozPromise.h:c0cd065ee5c8832f6962d09805541ecad1d5398e : 529 + 0x8]
04:03:08     INFO -     rbp = 0x00007fff5d741e00   rsp = 0x00007fff5d741de0
04:03:08     INFO -     rip = 0x0000000107572736
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  2  XUL!mozilla::MozPromise<mozilla::ipc::Shmem, mozilla::ipc::ResponseRejectReason, true>::ThenValueBase::ResolveOrRejectRunnable::Run() [MozPromise.h:c0cd065ee5c8832f6962d09805541ecad1d5398e : 411 + 0x5]
04:03:08     INFO -     rbp = 0x00007fff5d741e30   rsp = 0x00007fff5d741e10
04:03:08     INFO -     rip = 0x0000000103471a3c
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  3  XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:c0cd065ee5c8832f6962d09805541ecad1d5398e : 1051 + 0x6]
04:03:08     INFO -     rbp = 0x00007fff5d7423a0   rsp = 0x00007fff5d741e40
04:03:08     INFO -     rip = 0x0000000102bc60e6
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  4  XUL!NS_ProcessPendingEvents(nsIThread*, unsigned int) [nsThreadUtils.cpp:c0cd065ee5c8832f6962d09805541ecad1d5398e : 461 + 0xf]
04:03:08     INFO -     rbp = 0x00007fff5d7423e0   rsp = 0x00007fff5d7423b0
04:03:08     INFO -     rip = 0x0000000102bc3787
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  5  XUL!nsBaseAppShell::NativeEventCallback() [nsBaseAppShell.cpp:c0cd065ee5c8832f6962d09805541ecad1d5398e : 99 + 0xa]
04:03:08     INFO -     rbp = 0x00007fff5d742410   rsp = 0x00007fff5d7423f0
04:03:08     INFO -     rip = 0x0000000105ffa6f1
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  6  XUL!nsAppShell::ProcessGeckoEvents(void*) [nsAppShell.mm:c0cd065ee5c8832f6962d09805541ecad1d5398e : 456 + 0x8]
04:03:08     INFO -     rbp = 0x00007fff5d742470   rsp = 0x00007fff5d742420
04:03:08     INFO -     rip = 0x00000001060703be
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  7  CoreFoundation + 0x80a01
04:03:08     INFO -     rbp = 0x00007fff5d742480   rsp = 0x00007fff5d742480
04:03:08     INFO -     rip = 0x00007fff9b2aba01
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  8  CoreFoundation + 0x72b8d
04:03:08     INFO -     rbp = 0x00007fff5d7424e0   rsp = 0x00007fff5d742490
04:03:08     INFO -     rip = 0x00007fff9b29db8d
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO -  9  CoreFoundation + 0x721bf
04:03:08     INFO -     rbp = 0x00007fff5d7431c0   rsp = 0x00007fff5d7424f0
04:03:08     INFO -     rip = 0x00007fff9b29d1bf
04:03:08     INFO -     Found by: previous frame's frame pointer
04:03:08     INFO - 10  CoreFoundation + 0x71bd8
04:03:08     INFO -     rbp = 0x00007fff5d743220   rsp = 0x00007fff5d7431d0
04:03:08     INFO -     rip = 0x00007fff9b29cbd8
04:03:08     INFO -     Found by: previous frame's frame pointer

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1472437&startday=2018-06-25&endday=2018-07-01&tree=trunk

Comment 2

[Julien Wajsberg [:julienw]]

The failing condition is https://searchfox.org/mozilla-central/rev/ed2763bea882619ccb48b0aecc54e523d2bdd2ae/ipc/glue/MessageChannel.cpp#2546

    if (ChannelClosing != mChannelState) {

This crashes in these lines in the test: https://searchfox.org/mozilla-central/rev/ed2763bea882619ccb48b0aecc54e523d2bdd2ae/devtools/server/tests/browser/browser_perf-01.js#28-30

    const profile = await front.getProfileAndStopProfiler();
    await profilerStopped1;
    is(await front.isActive(), false, "The profiler was stopped.");

Comment 3

[Intermittent Failures Robot]

2 failures in 622 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-beta: 1
* autoland: 1

Platform breakdown:
* windows7-32: 1
* osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1472437&startday=2018-07-02&endday=2018-07-08&tree=trunk

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 1

Platform breakdown:
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1472437&startday=2018-07-09&endday=2018-07-15&tree=trunk

Comment 5

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 6

[Arthur Iakab [arthur_iakab]]

Recent failure https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&selectedJob=214769223&revision=b26e28ce8cfebb91f1c5764606585b8b670938ff

Failure log:https://treeherder.mozilla.org/logviewer.html#?job_id=214769223&repo=mozilla-inbound

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 1

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