Closed Bug 1459602 Opened 6 years ago Closed 5 years ago

Intermittent SUMMARY: AddressSanitizer: SEGV /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2555:13 in mozilla::ipc::MessageChannel::OnChannelErrorFromLink()

Categories

(Core :: IPC, defect)

defect
Not set
normal

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell needswork:owner][retriggered])

Attachments

(1 file)

Filed by: archaeopteryx [at] coole-files.de

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

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

[task 2018-05-04T17:40:04.323Z] 17:40:04     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py TestTimeouts.test_reset_timeout
[task 2018-05-04T17:40:04.324Z] 17:40:04     INFO -  1525455604315	Marionette	TRACE	523 <- [1,10,null,{}]
[task 2018-05-04T17:40:04.326Z] 17:40:04     INFO -  1525455604321	Marionette	DEBUG	Closed connection 523
[task 2018-05-04T17:40:04.327Z] 17:40:04     INFO -  1525455604324	Marionette	DEBUG	Accepted connection 524 from 127.0.0.1:36502
[task 2018-05-04T17:40:04.331Z] 17:40:04     INFO -  1525455604329	Marionette	TRACE	524 -> [0,1,"WebDriver:NewSession",{}]
[task 2018-05-04T17:40:04.347Z] 17:40:04     INFO -  1525455604337	Marionette	DEBUG	Register listener.js for window 2147483691
[task 2018-05-04T17:40:04.349Z] 17:40:04     INFO -  1525455604338	Marionette	DEBUG	Register listener.js for window 8589935139
[task 2018-05-04T17:40:04.357Z] 17:40:04     INFO -  1525455604347	Marionette	TRACE	524 <- [1,1,null,{"sessionId":"25488eec-0d3a-48ac-8187-54dd8f2676ba","capabilities":{"browserName":"firefox","browserVersion":"61.0a ... ssID":1154,"moz:profile":"/tmp/tmpjVSAuE.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-05-04T17:40:04.357Z] 17:40:04     INFO -  1525455604352	Marionette	TRACE	524 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2018-05-04T17:40:04.359Z] 17:40:04     INFO -  1525455604353	Marionette	TRACE	524 <- [1,2,null,{}]
[task 2018-05-04T17:40:04.360Z] 17:40:04     INFO -  1525455604355	Marionette	TRACE	524 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2018-05-04T17:40:04.361Z] 17:40:04     INFO -  1525455604356	Marionette	TRACE	524 <- [1,3,null,{}]
[task 2018-05-04T17:40:04.363Z] 17:40:04     INFO -  1525455604361	Marionette	TRACE	524 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2018-05-04T17:40:04.367Z] 17:40:04     INFO -  1525455604364	Marionette	TRACE	524 <- [1,4,null,{}]
[task 2018-05-04T17:40:04.368Z] 17:40:04     INFO -  1525455604366	Marionette	TRACE	524 -> [0,5,"WebDriver:GetTimeouts",{}]
[task 2018-05-04T17:40:04.372Z] 17:40:04     INFO -  1525455604368	Marionette	TRACE	524 <- [1,5,null,{"implicit":0,"pageLoad":300000,"script":30000}]
[task 2018-05-04T17:40:04.373Z] 17:40:04     INFO -  1525455604371	Marionette	TRACE	524 -> [0,6,"WebDriver:GetTimeouts",{}]
[task 2018-05-04T17:40:04.374Z] 17:40:04     INFO -  1525455604371	Marionette	TRACE	524 <- [1,6,null,{"implicit":0,"pageLoad":300000,"script":30000}]
[task 2018-05-04T17:40:04.378Z] 17:40:04     INFO -  1525455604375	Marionette	TRACE	524 -> [0,7,"WebDriver:GetTimeouts",{}]
[task 2018-05-04T17:40:04.378Z] 17:40:04     INFO -  1525455604376	Marionette	TRACE	524 <- [1,7,null,{"implicit":0,"pageLoad":300000,"script":30000}]
[task 2018-05-04T17:40:04.384Z] 17:40:04     INFO -  1525455604380	Marionette	TRACE	524 -> [0,8,"Marionette:GetContext",{}]
[task 2018-05-04T17:40:04.385Z] 17:40:04     INFO -  1525455604381	Marionette	TRACE	524 <- [1,8,null,{"value":"content"}]
[task 2018-05-04T17:40:04.389Z] 17:40:04     INFO -  1525455604383	Marionette	TRACE	524 -> [0,9,"WebDriver:DeleteSession",{}]
[task 2018-05-04T17:40:04.421Z] 17:40:04     INFO -  1525455604389	Marionette	TRACE	524 <- [1,9,null,{}]
[task 2018-05-04T17:40:04.565Z] 17:40:04     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpjVSAuE.mozrunner
[task 2018-05-04T17:40:05.681Z] 17:40:05     INFO -  1525455605668	Marionette	DEBUG	Received observer notification profile-after-change
[task 2018-05-04T17:40:05.758Z] 17:40:05     INFO -  1525455605749	Marionette	DEBUG	Received observer notification command-line-startup
[task 2018-05-04T17:40:05.759Z] 17:40:05     INFO -  1525455605750	Marionette	DEBUG	Received observer notification nsPref:changed
[task 2018-05-04T17:40:07.497Z] 17:40:07     INFO -  1525455607492	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
[task 2018-05-04T17:40:09.345Z] 17:40:09     INFO -  1525455609334	Marionette	DEBUG	New connections are accepted
[task 2018-05-04T17:40:09.345Z] 17:40:09     INFO -  1525455609335	Marionette	INFO	Listening on port 2828
[task 2018-05-04T17:40:09.422Z] 17:40:09     INFO -  1525455609418	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:36612
[task 2018-05-04T17:40:09.430Z] 17:40:09     INFO -  1525455609426	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:36614
[task 2018-05-04T17:40:09.432Z] 17:40:09     INFO -  1525455609428	Marionette	DEBUG	Closed connection 0
[task 2018-05-04T17:40:09.452Z] 17:40:09     INFO -  1525455609437	Marionette	TRACE	1 -> [0,1,"WebDriver:NewSession",{}]
[task 2018-05-04T17:40:09.711Z] 17:40:09     INFO -  1525455609700	Marionette	DEBUG	Register listener.js for window 2147483649
[task 2018-05-04T17:40:09.748Z] 17:40:09     INFO -  1525455609735	Marionette	TRACE	1 <- [1,1,null,{"sessionId":"9cf0dbcf-09a8-4c3a-9e21-ceebdc6d240e","capabilities":{"browserName":"firefox","browserVersion":"61.0a ... ssID":1812,"moz:profile":"/tmp/tmpjVSAuE.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-05-04T17:40:09.765Z] 17:40:09     INFO -  1525455609754	Marionette	TRACE	1 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2018-05-04T17:40:09.765Z] 17:40:09     INFO -  1525455609756	Marionette	TRACE	1 <- [1,2,null,{}]
[task 2018-05-04T17:40:09.787Z] 17:40:09     INFO -  1525455609781	Marionette	TRACE	1 -> [0,3,"WebDriver:DeleteSession",{}]
[task 2018-05-04T17:40:09.789Z] 17:40:09     INFO -  1525455609785	Marionette	TRACE	1 <- [1,3,null,{}]
[task 2018-05-04T17:40:09.952Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.954Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.955Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.957Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.958Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.960Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709
[task 2018-05-04T17:40:09.962Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc, line 22
[task 2018-05-04T17:40:09.963Z] 17:40:09     INFO -  [Child 1911, Chrome_ChildThread] WARNING: FileDescriptorSet destroyed with unconsumed descriptors: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/file_descriptor_set_posix.cc, line 22
[task 2018-05-04T17:40:09.964Z] 17:40:09     INFO -  AddressSanitizer:DEADLYSIGNAL
[task 2018-05-04T17:40:09.966Z] 17:40:09     INFO -  =================================================================
[task 2018-05-04T17:40:09.966Z] 17:40:09    ERROR -  ==1911==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000000 (pc 0x7f70244eea33 bp 0x7f7020638300 sp 0x7f70206382e0 T2)
[task 2018-05-04T17:40:09.971Z] 17:40:09     INFO -  ==1911==The signal is caused by a WRITE memory access.
[task 2018-05-04T17:40:09.972Z] 17:40:09     INFO -  ==1911==Hint: address points to the zero page.
[task 2018-05-04T17:40:10.998Z] 17:40:10     INFO -      #0 0x7f70244eea32 in mozilla::ipc::MessageChannel::OnChannelErrorFromLink() /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2555:13
[task 2018-05-04T17:40:10.999Z] 17:40:10     INFO -      #1 0x7f70244f3947 in OnChannelError /builds/worker/workspace/build/src/ipc/glue/MessageLink.cpp:393:12
[task 2018-05-04T17:40:11.001Z] 17:40:11     INFO -      #2 0x7f70244f3947 in non-virtual thunk to mozilla::ipc::ProcessLink::OnChannelError() /builds/worker/workspace/build/src/ipc/glue/MessageLink.cpp
[task 2018-05-04T17:40:11.001Z] 17:40:11     INFO -      #3 0x7f7024496fe7 in event_persist_closure /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1580:9
[task 2018-05-04T17:40:11.003Z] 17:40:11     INFO -      #4 0x7f7024496fe7 in event_process_active_single_queue /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1639
[task 2018-05-04T17:40:11.004Z] 17:40:11     INFO -      #5 0x7f702448eee5 in event_process_active /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c
[task 2018-05-04T17:40:11.004Z] 17:40:11     INFO -      #6 0x7f702448eee5 in event_base_loop /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1961
[task 2018-05-04T17:40:11.014Z] 17:40:11     INFO -      #7 0x7f702444e443 in base::MessagePumpLibevent::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/chromium/src/base/message_pump_libevent.cc:373:7
[task 2018-05-04T17:40:11.014Z] 17:40:11     INFO -      #8 0x7f70244487e9 in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:326:10
[task 2018-05-04T17:40:11.015Z] 17:40:11     INFO -      #9 0x7f70244487e9 in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:319
[task 2018-05-04T17:40:11.015Z] 17:40:11     INFO -      #10 0x7f70244487e9 in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:299
[task 2018-05-04T17:40:11.023Z] 17:40:11     INFO -      #11 0x7f702446667f in base::Thread::ThreadMain() /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:181:16
[task 2018-05-04T17:40:11.023Z] 17:40:11     INFO -      #12 0x7f702445935c in ThreadFunc(void*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:38:13
[task 2018-05-04T17:40:11.025Z] 17:40:11     INFO -      #13 0x7f70441566b9 in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76b9)
[task 2018-05-04T17:40:11.089Z] 17:40:11     INFO -      #14 0x7f70431df41c in clone /build/glibc-Cl5G7W/glibc-2.23/misc/../sysdeps/unix/sysv/linux/x86_64/clone.S:109
[task 2018-05-04T17:40:11.089Z] 17:40:11     INFO -  AddressSanitizer can not provide additional info.
[task 2018-05-04T17:40:11.091Z] 17:40:11     INFO -  SUMMARY: AddressSanitizer: SEGV /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2555:13 in mozilla::ipc::MessageChannel::OnChannelErrorFromLink()
[task 2018-05-04T17:40:11.091Z] 17:40:11     INFO -  Thread T2 (Chrome_~dThread) created by T0 (Web Content) here:
[task 2018-05-04T17:40:11.117Z] 17:40:11     INFO -      #0 0x4aafed in __interceptor_pthread_create /builds/worker/workspace/moz-toolchain/src/llvm/projects/compiler-rt/lib/asan/asan_interceptors.cc:204:3
[task 2018-05-04T17:40:11.119Z] 17:40:11     INFO -      #1 0x7f7024456d8f in CreateThread /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:135:14
[task 2018-05-04T17:40:11.119Z] 17:40:11     INFO -      #2 0x7f7024456d8f in PlatformThread::Create(unsigned long, PlatformThread::Delegate*, unsigned long*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:146
[task 2018-05-04T17:40:11.121Z] 17:40:11     INFO -      #3 0x7f702446601f in base::Thread::StartWithOptions(base::Thread::Options const&) /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:99:8
[task 2018-05-04T17:40:11.121Z] 17:40:11     INFO -      #4 0x7f7024467dae in Run /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/child_thread.cc:27:12
[task 2018-05-04T17:40:11.123Z] 17:40:11     INFO -      #5 0x7f7024467dae in ChildProcess::ChildProcess(ChildThread*) /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/child_process.cc:20
[task 2018-05-04T17:40:11.123Z] 17:40:11     INFO -      #6 0x7f70244f5493 in mozilla::ipc::ProcessChild::ProcessChild(int) /builds/worker/workspace/build/src/ipc/glue/ProcessChild.cpp:24:5
[task 2018-05-04T17:40:11.125Z] 17:40:11     INFO -      #7 0x7f702f16044e in ContentProcess /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/dom/ContentProcess.h:31:7
[task 2018-05-04T17:40:11.126Z] 17:40:11     INFO -      #8 0x7f702f16044e in XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/workspace/build/src/toolkit/xre/nsEmbedFunctions.cpp:667
[task 2018-05-04T17:40:11.128Z] 17:40:11     INFO -      #9 0x4f1875 in content_process_main /builds/worker/workspace/build/src/browser/app/../../ipc/contentproc/plugin-container.cpp:50:30
[task 2018-05-04T17:40:11.129Z] 17:40:11     INFO -      #10 0x4f1875 in main /builds/worker/workspace/build/src/browser/app/nsBrowserApp.cpp:280
[task 2018-05-04T17:40:11.129Z] 17:40:11     INFO -      #11 0x7f70430f882f in __libc_start_main /build/glibc-Cl5G7W/glibc-2.23/csu/../csu/libc-start.c:291
[task 2018-05-04T17:40:11.131Z] 17:40:11     INFO -  ==1911==ABORTING
Component: Marionette → IPC
Product: Testing → Core
Version: Version 3 → unspecified
I think I'm seeing the same thing;  both my main and work machines (Fedora 28 on both) are failing a couple of times a day, the backtrace is:

(gdb) where
#0  0x00007f429b7870d8 in mozilla::ipc::MessageChannel::OnChannelErrorFromLink() (this=0x7f42aa2c6128) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/glue/MessageChannel.cpp:2534
2534	            MOZ_CRASH("Aborting on channel error.");
#1  0x00007f429bcc4eb3 in mozilla::ipc::ProcessLink::OnChannelError() (this=0x7f428db4fd30) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/glue/MessageLink.cpp:393
#2  0x00007f429bcae77a in event_persist_closure (ev=<optimized out>, base=0x7f42aa2c1400) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/third_party/libevent/event.c:1580
#3  0x00007f429bcae77a in event_process_active_single_queue (base=base@entry=0x7f42aa2c1400, max_to_process=max_to_process@entry=2147483647, endtime=endtime@entry=0x0, activeq=<optimized out>)
    at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/third_party/libevent/event.c:1639
#4  0x00007f429bcaed8f in event_process_active (base=0x7f42aa2c1400) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/third_party/libevent/event.c:1738
#5  0x00007f429bcaed8f in event_base_loop (base=0x7f42aa2c1400, flags=flags@entry=1) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/third_party/libevent/event.c:1961
#6  0x00007f429bc943ce in base::MessagePumpLibevent::Run(base::MessagePump::Delegate*) (this=0x7f42aa251a40, delegate=0x7f429811cd00) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/message_pump_libevent.cc:381
#7  0x00007f429bc96849 in MessageLoop::RunInternal() (this=0x7f429811cd00) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/message_loop.cc:326
#8  0x00007f429bc96849 in MessageLoop::RunHandler() (this=0x7f429811cd00) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/message_loop.cc:319
#9  0x00007f429bc96849 in MessageLoop::Run() (this=this@entry=0x7f429811cd00) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/message_loop.cc:299
#10 0x00007f429bca4785 in base::Thread::ThreadMain() (this=0x7f42aa2263e8) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/thread.cc:181
#11 0x00007f429bc93eee in ThreadFunc(void*) (closure=<optimized out>) at /usr/src/debug/firefox-59.0.2-1.fc28.x86_64/ipc/chromium/src/base/platform_thread_posix.cc:38
#12 0x00007f42ab572564 in start_thread () at /lib64/libpthread.so.0
#13 0x00007f42aa76431f in clone () at /lib64/libc.so.6

Note the 'mChannelState is mozilla::ipc::ChannelConnected.

I've had it fail at quite a few different activities; scrolling through videos, clicking on the 'tick' in google photos; and a bunch of others - can't nail down a single cause.  My work machine has no extra codecs, my home machine has a load of ffmpeg - so there's nothing common between them.  I don't think I'd seen it before fedora28 though - it's currently Firefox 59.0.2.

I'd filed it as Fedora bug:
https://bugzilla.redhat.com/show_bug.cgi?id=1568097
Attached file Marionette test
I can replicate that failure all the time on MacOS when running the above Marionette test in headless mode:

`mach marionette-test --headless -vv --gecko-log - %path_to_py_file%`

It always results in a:

> Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2555
> [GFX1-]: Receive IPC close with reason=AbnormalShutdown

I will see if I can narrow down the regression range (if there is one) a bit further.
It looks like I cannot really do a regression test with artifact builds which I'm using locally. So if a developer could have a look at this and run the test it might be faster.
Could this bug be related to https://bugzilla.mozilla.org/show_bug.cgi?id=1461041 ?
FF 60 ESR was crashing on my AArch64 box with the exact same results. But removing the "-fno-delete-null-pointer-checks" compiler flag fixes this for me.
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Recent log failure:
https://treeherder.mozilla.org/logviewer.html#?job_id=206850667&repo=try&lineNumber=53570


[task 2018-10-21T11:50:07.915Z] 11:50:07     INFO -  1540122607903	Marionette	DEBUG	[30] Frame script loaded
[task 2018-10-21T11:50:07.920Z] 11:50:07     INFO -  1540122607906	Marionette	DEBUG	[30] Frame script registered
[task 2018-10-21T11:50:07.952Z] 11:50:07     INFO -  console.log: "[bug 1345098] Lazy browser prematurely inserted via 'messageManager' property access:\ngetter@chrome://browser/content/tabbrowser.js:2020:37\nflush@resource:///modules/sessionstore/TabStateFlusher.jsm:84:9\nflush@resource:///modules/sessionstore/TabStateFlusher.jsm:23:12\ntestSSPostReset/<@tests/browser/components/migration/tests/marionette/test_refresh_firefox.py:473:52\ntestSSPostReset@tests/browser/components/migration/tests/marionette/test_refresh_firefox.py:473:25\nssi_sendWindowStateEvent@resource:///modules/sessionstore/SessionStore.jsm:4762:5\nssi_setWindowStateReady@resource:///modules/sessionstore/SessionStore.jsm:4736:7\nssi_restoreWindow@resource:///modules/sessionstore/SessionStore.jsm:3579:5\n_restoreWindowsFeaturesAndTabs@resource:///modules/sessionstore/SessionStore.jsm:3651:7\n_restoreWindowsInReversedZOrder@resource:///modules/sessionstore/SessionStore.jsm:3668:5\nssi_restoreWindows/<@resource:///modules/sessionstore/SessionStore.jsm:3723:7\n"
[task 2018-10-21T11:50:08.174Z] 11:50:08     INFO -  1540122608167	Marionette	DEBUG	[8589934593] Frame script loaded
[task 2018-10-21T11:50:08.210Z] 11:50:08     INFO -  1540122608198	Marionette	ERROR	[8589934593] No reply from Marionette:Register
[task 2018-10-21T11:50:08.212Z] 11:50:08     INFO -  JavaScript error: chrome://marionette/content/listener.js, line 456: TypeError: reply[0] is undefined; can't access its "outerWindowID" property
[task 2018-10-21T11:50:08.351Z] 11:50:08     INFO -  1540122608344	Marionette	DEBUG	[8589934595] Frame script loaded
[task 2018-10-21T11:50:08.387Z] 11:50:08     INFO -  1540122608375	Marionette	DEBUG	[8589934595] Frame script registered
[task 2018-10-21T11:50:08.488Z] 11:50:08     INFO -  1540122608479	Marionette	TRACE	1 <- [1,20,null,{"value":["about:robots","about:mozilla"]}]
[task 2018-10-21T11:50:08.553Z] 11:50:08     INFO -  1540122608551	Marionette	TRACE	1 -> [0,21,"Marionette:GetContext",{}]
[task 2018-10-21T11:50:08.557Z] 11:50:08     INFO -  1540122608554	Marionette	TRACE	1 <- [1,21,null,{"value":"chrome"}]
[task 2018-10-21T11:50:08.607Z] 11:50:08     INFO -  1540122608601	Marionette	TRACE	1 -> [0,22,"WebDriver:DeleteSession",{}]
[task 2018-10-21T11:50:08.623Z] 11:50:08     INFO -  1540122608609	Marionette	TRACE	1 <- [1,22,null,{"value":null}]
[task 2018-10-21T11:50:08.807Z] 11:50:08     INFO -  [Child 10868, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 736
[task 2018-10-21T11:50:08.809Z] 11:50:08     INFO -  [Child 10868, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 736
[task 2018-10-21T11:50:08.809Z] 11:50:08     INFO -  AddressSanitizer:DEADLYSIGNAL
[task 2018-10-21T11:50:08.809Z] 11:50:08     INFO -  =================================================================
[task 2018-10-21T11:50:08.810Z] 11:50:08    ERROR -  ==10868==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000001 (pc 0x7f281b055e8f bp 0x7f28133922c0 sp 0x7f28133922a0 T1)
[task 2018-10-21T11:50:08.811Z] 11:50:08     INFO -  ==10868==The signal is caused by a WRITE memory access.
[task 2018-10-21T11:50:08.811Z] 11:50:08     INFO -  ==10868==Hint: address points to the zero page.
[task 2018-10-21T11:50:09.630Z] 11:50:09     INFO -      #0 0x7f281b055e8e in mozilla::ipc::MessageChannel::OnChannelErrorFromLink() /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2669:13
[task 2018-10-21T11:50:09.632Z] 11:50:09     INFO -      #1 0x7f281b05a247 in non-virtual thunk to mozilla::ipc::ProcessLink::OnChannelError() /builds/worker/workspace/build/src/ipc/glue/MessageLink.cpp:397:12
[task 2018-10-21T11:50:09.634Z] 11:50:09     INFO -      #2 0x7f281affc7e7 in event_persist_closure /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1580:9
[task 2018-10-21T11:50:09.636Z] 11:50:09     INFO -      #3 0x7f281affc7e7 in event_process_active_single_queue /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1639
[task 2018-10-21T11:50:09.637Z] 11:50:09     INFO -      #4 0x7f281aff4685 in event_process_active /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c
[task 2018-10-21T11:50:09.640Z] 11:50:09     INFO -      #5 0x7f281aff4685 in event_base_loop /builds/worker/workspace/build/src/ipc/chromium/src/third_party/libevent/event.c:1961
[task 2018-10-21T11:50:09.641Z] 11:50:09     INFO -      #6 0x7f281afb3dd0 in base::MessagePumpLibevent::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/chromium/src/base/message_pump_libevent.cc:373:7
[task 2018-10-21T11:50:09.641Z] 11:50:09     INFO -      #7 0x7f281afae41c in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:325:10
[task 2018-10-21T11:50:09.643Z] 11:50:09     INFO -      #8 0x7f281afae41c in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:318
[task 2018-10-21T11:50:09.643Z] 11:50:09     INFO -      #9 0x7f281afae41c in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:298
[task 2018-10-21T11:50:09.643Z] 11:50:09     INFO -      #10 0x7f281afcb376 in base::Thread::ThreadMain() /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:198:16
[task 2018-10-21T11:50:09.643Z] 11:50:09     INFO -      #11 0x7f281afbf86c in ThreadFunc(void*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:40:13
[task 2018-10-21T11:50:09.644Z] 11:50:09     INFO -      #12 0x7f283977c6b9 in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76b9)
[task 2018-10-21T11:50:09.696Z] 11:50:09     INFO -      #13 0x7f283880541c in clone /build/glibc-Cl5G7W/glibc-2.23/misc/../sysdeps/unix/sysv/linux/x86_64/clone.S:109
[task 2018-10-21T11:50:09.697Z] 11:50:09     INFO -  AddressSanitizer can not provide additional info.
[task 2018-10-21T11:50:09.698Z] 11:50:09     INFO -  SUMMARY: AddressSanitizer: SEGV /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2669:13 in mozilla::ipc::MessageChannel::OnChannelErrorFromLink()
[task 2018-10-21T11:50:09.699Z] 11:50:09     INFO -  Thread T1 (Chrome_~dThread) created by T0 here:
[task 2018-10-21T11:50:09.700Z] 11:50:09     INFO -      #0 0x55942aa572ed in __interceptor_pthread_create /builds/worker/workspace/moz-toolchain/src/llvm/projects/compiler-rt/lib/asan/asan_interceptors.cc:204:3
[task 2018-10-21T11:50:09.701Z] 11:50:09     INFO -      #1 0x7f281afbd2ec in CreateThread /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:127:14
[task 2018-10-21T11:50:09.702Z] 11:50:09     INFO -      #2 0x7f281afbd2ec in PlatformThread::Create(unsigned long, PlatformThread::Delegate*, unsigned long*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:138
[task 2018-10-21T11:50:09.708Z] 11:50:09     INFO -      #3 0x7f281afcaa93 in base::Thread::StartWithOptions(base::Thread::Options const&) /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:102:8
[task 2018-10-21T11:50:09.708Z] 11:50:09     INFO -      #4 0x7f281afcccde in Run /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/child_thread.cc:27:12
[task 2018-10-21T11:50:09.715Z] 11:50:09     INFO -      #5 0x7f281afcccde in ChildProcess::ChildProcess(ChildThread*) /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/child_process.cc:20
[task 2018-10-21T11:50:09.715Z] 11:50:09     INFO -      #6 0x7f281b05be97 in mozilla::ipc::ProcessChild::ProcessChild(int) /builds/worker/workspace/build/src/ipc/glue/ProcessChild.cpp:24:5
[task 2018-10-21T11:50:09.716Z] 11:50:09     INFO -      #7 0x7f2825d161d9 in ContentProcess /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/dom/ContentProcess.h:31:7
[task 2018-10-21T11:50:09.718Z] 11:50:09     INFO -      #8 0x7f2825d161d9 in XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/workspace/build/src/toolkit/xre/nsEmbedFunctions.cpp:707
[task 2018-10-21T11:50:09.718Z] 11:50:09     INFO -      #9 0x55942aa9e6f4 in content_process_main /builds/worker/workspace/build/src/browser/app/../../ipc/contentproc/plugin-container.cpp:50:30
[task 2018-10-21T11:50:09.719Z] 11:50:09     INFO -      #10 0x55942aa9e6f4 in main /builds/worker/workspace/build/src/browser/app/nsBrowserApp.cpp:287
[task 2018-10-21T11:50:09.720Z] 11:50:09     INFO -      #11 0x7f283871e82f in __libc_start_main /build/glibc-Cl5G7W/glibc-2.23/csu/../csu/libc-start.c:291
[task 2018-10-21T11:50:09.720Z] 11:50:09     INFO -  ==10868==ABORTING
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Since this was landed, this bug started to perma fail. It might me from bug 1519862 https://bugzilla.mozilla.org/show_bug.cgi?id=1519862

James, could you please take a look?

Flags: needinfo?(james)
Whiteboard: [stockwell needswork:owner] → [stockwell needswork:owner][retriggered]
Flags: needinfo?(aryx.bugmail)
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Flags: needinfo?(aryx.bugmail)
Resolution: --- → INCOMPLETE
Flags: needinfo?(james)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: